builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0115 starttime: 1462826218.81 results: success (0) buildid: 20160509113556 builduid: 4938c7541df24952bba95bc276b62f49 revision: 0530f8d36de834b921c7c68272e6c0831bedae63 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 13:36:58.809270) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 13:36:58.809824) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 13:36:58.810093) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 13:36:58.828919) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 13:36:58.829203) ========= 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.XFTLacSiB9/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.5HTNtYWhll/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-09 13:36:58-- 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% 12.0M=0.001s 2016-05-09 13:36:59 (12.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.177957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 13:36:59.024110) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 13:36:59.024416) ========= 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.XFTLacSiB9/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.5HTNtYWhll/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.028351 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 13:36:59.076234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 13:36:59.079603) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0530f8d36de834b921c7c68272e6c0831bedae63 --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 0530f8d36de834b921c7c68272e6c0831bedae63 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XFTLacSiB9/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.5HTNtYWhll/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-09 13:36:59,149 truncating revision to first 12 chars 2016-05-09 13:36:59,150 Setting DEBUG logging. 2016-05-09 13:36:59,150 attempt 1/10 2016-05-09 13:36:59,150 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0530f8d36de8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 13:37:00,825 unpacking tar archive at: fx-team-0530f8d36de8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.925408 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 13:37:01.022192) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 13:37:01.022510) ========= script_repo_revision: 0530f8d36de834b921c7c68272e6c0831bedae63 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 13:37:01.022894) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 13:37:01.023265) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 13:37:01.036859) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-09 13:37:01.037151) ========= /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.XFTLacSiB9/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.5HTNtYWhll/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:37:01 INFO - MultiFileLogger online at 20160509 13:37:01 in /builds/slave/test 13:37:01 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 13:37:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:37:01 INFO - {'append_to_log': False, 13:37:01 INFO - 'base_work_dir': '/builds/slave/test', 13:37:01 INFO - 'blob_upload_branch': 'Fx-Team', 13:37:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:37:01 INFO - 'branch': 'Fx-Team', 13:37:01 INFO - 'buildbot_json_path': 'buildprops.json', 13:37:01 INFO - 'config_files': ('talos/mac_config.py',), 13:37:01 INFO - 'default_actions': ('clobber', 13:37:01 INFO - 'read-buildbot-config', 13:37:01 INFO - 'download-and-extract', 13:37:01 INFO - 'populate-webroot', 13:37:01 INFO - 'create-virtualenv', 13:37:01 INFO - 'install', 13:37:01 INFO - 'run-tests'), 13:37:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:37:01 INFO - 'download_minidump_stackwalk': True, 13:37:01 INFO - 'download_symbols': 'ondemand', 13:37:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:37:01 INFO - 'tooltool.py': '/tools/tooltool.py', 13:37:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:37:01 INFO - '/tools/misc-python/virtualenv.py')}, 13:37:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:37:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:37:01 INFO - 'installer_path': 'installer.exe', 13:37:01 INFO - 'log_level': 'info', 13:37:01 INFO - 'log_name': 'talos', 13:37:01 INFO - 'log_to_console': True, 13:37:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:37:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:37:01 INFO - 'opt_config_files': (), 13:37:01 INFO - 'pip_index': False, 13:37:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:37:01 INFO - 'cmd': ('bash', 13:37:01 INFO - '-c', 13:37:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:37:01 INFO - 'enabled': True, 13:37:01 INFO - 'halt_on_failure': False, 13:37:01 INFO - 'name': 'check_screen_resolution'},), 13:37:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:37:01 INFO - 'cmd': ('bash', 13:37:01 INFO - '-c', 13:37:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:37:01 INFO - 'enabled': True, 13:37:01 INFO - 'halt_on_failure': False, 13:37:01 INFO - 'name': 'check_screen_resolution'},), 13:37:01 INFO - 'run_cmd_checks_enabled': True, 13:37:01 INFO - 'sps_profile': False, 13:37:01 INFO - 'sps_profile_interval': 0, 13:37:01 INFO - 'suite': 'dromaeojs', 13:37:01 INFO - 'system_bits': '32', 13:37:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:37:01 INFO - 'title': 't-yosemite-r7-0115', 13:37:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:37:01 INFO - 'use_talos_json': True, 13:37:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:37:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:37:01 INFO - 'work_dir': 'build'} 13:37:01 INFO - ##### 13:37:01 INFO - ##### Running clobber step. 13:37:01 INFO - ##### 13:37:01 INFO - Running pre-action listener: _resource_record_pre_action 13:37:01 INFO - Running main action method: clobber 13:37:01 INFO - rmtree: /builds/slave/test/build 13:37:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:37:03 INFO - Running post-action listener: _resource_record_post_action 13:37:03 INFO - ##### 13:37:03 INFO - ##### Finished clobber step (success) 13:37:03 INFO - ##### 13:37:03 INFO - ##### 13:37:03 INFO - ##### Running read-buildbot-config step. 13:37:03 INFO - ##### 13:37:03 INFO - Running pre-action listener: _resource_record_pre_action 13:37:03 INFO - Running main action method: read_buildbot_config 13:37:03 INFO - Using buildbot properties: 13:37:03 INFO - { 13:37:03 INFO - "project": "", 13:37:03 INFO - "product": "firefox", 13:37:03 INFO - "script_repo_revision": "production", 13:37:03 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:37:03 INFO - "repository": "", 13:37:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:37:03 INFO - "buildid": "20160509113556", 13:37:03 INFO - "pgo_build": "False", 13:37:03 INFO - "basedir": "/builds/slave/test", 13:37:03 INFO - "buildnumber": 5, 13:37:03 INFO - "slavename": "t-yosemite-r7-0115", 13:37:03 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 13:37:03 INFO - "platform": "yosemite_r7", 13:37:03 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:37:03 INFO - "branch": "fx-team", 13:37:03 INFO - "repo_path": "integration/fx-team", 13:37:03 INFO - "revision": "0530f8d36de834b921c7c68272e6c0831bedae63", 13:37:03 INFO - "stage_platform": "macosx64", 13:37:03 INFO - "builduid": "4938c7541df24952bba95bc276b62f49", 13:37:03 INFO - "slavebuilddir": "test" 13:37:03 INFO - } 13:37:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:37:03 INFO - Running post-action listener: _resource_record_post_action 13:37:03 INFO - ##### 13:37:03 INFO - ##### Finished read-buildbot-config step (success) 13:37:03 INFO - ##### 13:37:03 INFO - ##### 13:37:03 INFO - ##### Running download-and-extract step. 13:37:03 INFO - ##### 13:37:03 INFO - Running pre-action listener: _resource_record_pre_action 13:37:03 INFO - Running main action method: download_and_extract 13:37:03 INFO - mkdir: /builds/slave/test/build/tests 13:37:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:03 INFO - https://queue.taskcluster.net/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:37:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:37:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:37:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:37:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 13:37:05 INFO - Downloaded 1199 bytes. 13:37:05 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:37:05 INFO - Using the following test package requirements: 13:37:05 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:37:05 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:37:05 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:37:05 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:37:05 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:37:05 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:37:05 INFO - u'jsshell-mac.zip'], 13:37:05 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:37:05 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:37:05 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:37:05 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:37:05 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:37:05 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:37:05 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:37:05 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:37:05 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:37:05 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:37:05 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:37:05 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:37:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:05 INFO - https://queue.taskcluster.net/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:37:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:37:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:37:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:37:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:37:06 INFO - Downloaded 18146604 bytes. 13:37:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:37:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:37:09 INFO - Return code: 0 13:37:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:09 INFO - https://queue.taskcluster.net/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:37:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:37:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:37:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:37:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:37:10 INFO - Downloaded 18146604 bytes. 13:37:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:37:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:37:14 INFO - Return code: 0 13:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:14 INFO - https://queue.taskcluster.net/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:37:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:37:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:37:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:37:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:37:15 INFO - Downloaded 10840152 bytes. 13:37:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:37:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:37:15 INFO - Return code: 0 13:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:15 INFO - https://queue.taskcluster.net/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:37:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:37:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:37:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 13:37:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 13:37:18 INFO - Downloaded 110423827 bytes. 13:37:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:37:18 INFO - mkdir: /builds/slave/test/properties 13:37:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:37:18 INFO - Writing to file /builds/slave/test/properties/build_url 13:37:18 INFO - Contents: 13:37:18 INFO - build_url:https://queue.taskcluster.net/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:37:20 INFO - Running post-action listener: _resource_record_post_action 13:37:20 INFO - Running post-action listener: set_extra_try_arguments 13:37:20 INFO - ##### 13:37:20 INFO - ##### Finished download-and-extract step (success) 13:37:20 INFO - ##### 13:37:20 INFO - ##### 13:37:20 INFO - ##### Running populate-webroot step. 13:37:20 INFO - ##### 13:37:20 INFO - Running pre-action listener: _resource_record_pre_action 13:37:20 INFO - Running main action method: populate_webroot 13:37:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:37:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:37:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:37:20 INFO - '--tppagecycles', 13:37:20 INFO - '7'], 13:37:20 INFO - 'tests': ['tsvgm']}}, 13:37:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:37:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:37:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:37:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:37:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:37:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:37:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:20 INFO - 'talos_options': ['--disable-e10s'], 13:37:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:37:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:37:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:20 INFO - 'talos_options': ['--disable-e10s'], 13:37:20 INFO - 'tests': ['damp', 'tps']}, 13:37:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:20 INFO - 'tests': ['damp', 'tps']}, 13:37:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:37:20 INFO - 'tests': ['dromaeo_dom']}, 13:37:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:37:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:37:20 INFO - 'tests': ['a11yr', 13:37:20 INFO - 'ts_paint', 13:37:20 INFO - 'tpaint', 13:37:20 INFO - 'sessionrestore', 13:37:20 INFO - 'sessionrestore_no_auto_restore', 13:37:20 INFO - 'tabpaint']}, 13:37:20 INFO - 'other-e10s': {'tests': ['a11yr', 13:37:20 INFO - 'ts_paint', 13:37:20 INFO - 'tpaint', 13:37:20 INFO - 'sessionrestore', 13:37:20 INFO - 'sessionrestore_no_auto_restore', 13:37:20 INFO - 'tabpaint']}, 13:37:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:37:20 INFO - 'ts_paint', 13:37:20 INFO - 'tpaint', 13:37:20 INFO - 'sessionrestore', 13:37:20 INFO - 'sessionrestore_no_auto_restore', 13:37:20 INFO - 'tabpaint']}, 13:37:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:37:20 INFO - 'ts_paint', 13:37:20 INFO - 'tpaint', 13:37:20 INFO - 'sessionrestore', 13:37:20 INFO - 'sessionrestore_no_auto_restore', 13:37:20 INFO - 'tabpaint']}, 13:37:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:37:20 INFO - 'tests': ['a11yr', 13:37:20 INFO - 'ts_paint', 13:37:20 INFO - 'tpaint', 13:37:20 INFO - 'sessionrestore', 13:37:20 INFO - 'sessionrestore_no_auto_restore', 13:37:20 INFO - 'tabpaint']}, 13:37:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:37:20 INFO - 'tests': ['a11yr', 13:37:20 INFO - 'ts_paint', 13:37:20 INFO - 'tpaint', 13:37:20 INFO - 'sessionrestore', 13:37:20 INFO - 'sessionrestore_no_auto_restore', 13:37:20 INFO - 'tabpaint']}, 13:37:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:37:20 INFO - 'tests': ['tsvgx', 13:37:20 INFO - 'tsvgr_opacity', 13:37:20 INFO - 'tart', 13:37:20 INFO - 'tscrollx', 13:37:20 INFO - 'cart']}, 13:37:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:37:20 INFO - 'tsvgr_opacity', 13:37:20 INFO - 'tart', 13:37:20 INFO - 'tscrollx', 13:37:20 INFO - 'cart']}, 13:37:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:20 INFO - 'talos_options': ['--disable-e10s'], 13:37:20 INFO - 'tests': ['tp5o']}, 13:37:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:20 INFO - 'tests': ['tp5o']}, 13:37:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:20 INFO - 'talos_options': ['--disable-e10s', 13:37:20 INFO - '--xperf_path', 13:37:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:37:20 INFO - 'tests': ['tp5n']}, 13:37:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:37:20 INFO - 'talos_options': ['--xperf_path', 13:37:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:37:20 INFO - 'tests': ['tp5n']}}, 13:37:20 INFO - 'talos.zip': {'path': '', 13:37:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:37:20 INFO - Running post-action listener: _resource_record_post_action 13:37:20 INFO - ##### 13:37:20 INFO - ##### Finished populate-webroot step (success) 13:37:20 INFO - ##### 13:37:20 INFO - ##### 13:37:20 INFO - ##### Running create-virtualenv step. 13:37:20 INFO - ##### 13:37:20 INFO - Running pre-action listener: _resource_record_pre_action 13:37:20 INFO - Running main action method: create_virtualenv 13:37:20 INFO - Creating virtualenv /builds/slave/test/build/venv 13:37:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:37:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:37:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:37:20 INFO - Using real prefix '/tools/python27' 13:37:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:37:21 INFO - Installing distribute.............................................................................................................................................................................................done. 13:37:23 INFO - Installing pip.................done. 13:37:23 INFO - Return code: 0 13:37:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:23 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 0x10a734a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85294860a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d8d30>, '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 0x10a3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cfb20>, '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.XFTLacSiB9/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.5HTNtYWhll/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:37:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:37:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFTLacSiB9/Render', 13:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:23 INFO - 'HOME': '/Users/cltbld', 13:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:23 INFO - 'LOGNAME': 'cltbld', 13:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:23 INFO - 'MOZ_NO_REMOTE': '1', 13:37:23 INFO - 'NO_EM_RESTART': '1', 13:37:23 INFO - 'PAGER': '/bin/cat', 13:37:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:23 INFO - 'PWD': '/builds/slave/test', 13:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:23 INFO - 'SHELL': '/bin/bash', 13:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5HTNtYWhll/Listeners', 13:37:23 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 13:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:23 INFO - 'USER': 'cltbld', 13:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:23 INFO - 'XPC_FLAGS': '0x0', 13:37:23 INFO - 'XPC_SERVICE_NAME': '0', 13:37:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:37:24 INFO - Cleaning up... 13:37:24 INFO - Return code: 0 13:37:24 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a734a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85294860a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d8d30>, '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 0x10a3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cfb20>, '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.XFTLacSiB9/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.5HTNtYWhll/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:37:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:37:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFTLacSiB9/Render', 13:37:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:24 INFO - 'HOME': '/Users/cltbld', 13:37:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:24 INFO - 'LOGNAME': 'cltbld', 13:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:24 INFO - 'MOZ_NO_REMOTE': '1', 13:37:24 INFO - 'NO_EM_RESTART': '1', 13:37:24 INFO - 'PAGER': '/bin/cat', 13:37:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:24 INFO - 'PWD': '/builds/slave/test', 13:37:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:24 INFO - 'SHELL': '/bin/bash', 13:37:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5HTNtYWhll/Listeners', 13:37:24 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 13:37:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:24 INFO - 'USER': 'cltbld', 13:37:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:24 INFO - 'XPC_FLAGS': '0x0', 13:37:24 INFO - 'XPC_SERVICE_NAME': '0', 13:37:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:24 INFO - Downloading/unpacking psutil>=0.7.1 13:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:37:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:37:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:37:27 INFO - Installing collected packages: psutil 13:37:27 INFO - Running setup.py install for psutil 13:37:27 INFO - building 'psutil._psutil_osx' extension 13:37:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:37:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:37:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:37:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:37:28 INFO - building 'psutil._psutil_posix' extension 13:37:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:37:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:37:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:37:28 INFO - ^ 13:37:28 INFO - 1 warning generated. 13:37:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:37:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:37:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:37:28 INFO - Successfully installed psutil 13:37:28 INFO - Cleaning up... 13:37:28 INFO - Return code: 0 13:37:28 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a734a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85294860a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d8d30>, '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 0x10a3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cfb20>, '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.XFTLacSiB9/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.5HTNtYWhll/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:37:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:37:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFTLacSiB9/Render', 13:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:28 INFO - 'HOME': '/Users/cltbld', 13:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:28 INFO - 'LOGNAME': 'cltbld', 13:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:28 INFO - 'MOZ_NO_REMOTE': '1', 13:37:28 INFO - 'NO_EM_RESTART': '1', 13:37:28 INFO - 'PAGER': '/bin/cat', 13:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:28 INFO - 'PWD': '/builds/slave/test', 13:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:28 INFO - 'SHELL': '/bin/bash', 13:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5HTNtYWhll/Listeners', 13:37:28 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 13:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:28 INFO - 'USER': 'cltbld', 13:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:28 INFO - 'XPC_FLAGS': '0x0', 13:37:28 INFO - 'XPC_SERVICE_NAME': '0', 13:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:28 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:31 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:37:31 INFO - Installing collected packages: mozsystemmonitor 13:37:31 INFO - Running setup.py install for mozsystemmonitor 13:37:31 INFO - Successfully installed mozsystemmonitor 13:37:31 INFO - Cleaning up... 13:37:31 INFO - Return code: 0 13:37:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:37:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:31 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 0x10a734a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85294860a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d8d30>, '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 0x10a3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cfb20>, '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.XFTLacSiB9/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.5HTNtYWhll/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:37:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:37:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:37:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFTLacSiB9/Render', 13:37:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:31 INFO - 'HOME': '/Users/cltbld', 13:37:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:31 INFO - 'LOGNAME': 'cltbld', 13:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:31 INFO - 'MOZ_NO_REMOTE': '1', 13:37:31 INFO - 'NO_EM_RESTART': '1', 13:37:31 INFO - 'PAGER': '/bin/cat', 13:37:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:31 INFO - 'PWD': '/builds/slave/test', 13:37:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:31 INFO - 'SHELL': '/bin/bash', 13:37:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5HTNtYWhll/Listeners', 13:37:31 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 13:37:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:31 INFO - 'USER': 'cltbld', 13:37:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:31 INFO - 'XPC_FLAGS': '0x0', 13:37:31 INFO - 'XPC_SERVICE_NAME': '0', 13:37:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:31 INFO - Downloading/unpacking blobuploader==1.2.4 13:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:34 INFO - Downloading blobuploader-1.2.4.tar.gz 13:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:37:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:37:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:35 INFO - Downloading docopt-0.6.1.tar.gz 13:37:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:37:35 INFO - Installing collected packages: blobuploader, requests, docopt 13:37:35 INFO - Running setup.py install for blobuploader 13:37:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:37:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:37:35 INFO - Running setup.py install for requests 13:37:36 INFO - Running setup.py install for docopt 13:37:36 INFO - Successfully installed blobuploader requests docopt 13:37:36 INFO - Cleaning up... 13:37:36 INFO - Return code: 0 13:37:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:36 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 0x10a734a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85294860a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d8d30>, '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 0x10a3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cfb20>, '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.XFTLacSiB9/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.5HTNtYWhll/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:37:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:37:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:37:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFTLacSiB9/Render', 13:37:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:36 INFO - 'HOME': '/Users/cltbld', 13:37:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:36 INFO - 'LOGNAME': 'cltbld', 13:37:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:36 INFO - 'MOZ_NO_REMOTE': '1', 13:37:36 INFO - 'NO_EM_RESTART': '1', 13:37:36 INFO - 'PAGER': '/bin/cat', 13:37:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:36 INFO - 'PWD': '/builds/slave/test', 13:37:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:36 INFO - 'SHELL': '/bin/bash', 13:37:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5HTNtYWhll/Listeners', 13:37:36 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 13:37:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:36 INFO - 'USER': 'cltbld', 13:37:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:36 INFO - 'XPC_FLAGS': '0x0', 13:37:36 INFO - 'XPC_SERVICE_NAME': '0', 13:37:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:37:36 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-5uLo62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:37:36 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-mt_T6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:37:36 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Be9t45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:37:37 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-EiSAQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:37:37 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-hcgfmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:37:37 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-RVNy6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:37:37 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-9_f3s6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:37:37 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-7dCfVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:37:37 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-DTjX9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:37:37 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-1n4ow5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:37:37 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-nv_TbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:37:37 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-XLclFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:37:37 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-n0oEKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:37:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-mFIk4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-22zjJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:37:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-xz5cGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:37:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Ng951j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:37:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:37:38 INFO - Running setup.py install for manifestparser 13:37:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:37:38 INFO - Running setup.py install for mozcrash 13:37:38 INFO - Running setup.py install for mozdebug 13:37:38 INFO - Running setup.py install for mozdevice 13:37:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:37:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:37:39 INFO - Running setup.py install for mozfile 13:37:39 INFO - Running setup.py install for mozhttpd 13:37:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:37:39 INFO - Running setup.py install for mozinfo 13:37:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:37:39 INFO - Running setup.py install for mozInstall 13:37:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:37:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:37:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:37:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:37:39 INFO - Running setup.py install for mozleak 13:37:39 INFO - Running setup.py install for mozlog 13:37:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:37:39 INFO - Running setup.py install for moznetwork 13:37:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:37:40 INFO - Running setup.py install for mozprocess 13:37:40 INFO - Running setup.py install for mozprofile 13:37:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:37:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:37:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:37:40 INFO - Running setup.py install for mozrunner 13:37:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:37:40 INFO - Running setup.py install for mozscreenshot 13:37:40 INFO - Running setup.py install for moztest 13:37:40 INFO - Running setup.py install for mozversion 13:37:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:37:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:37:41 INFO - Cleaning up... 13:37:41 INFO - Return code: 0 13:37:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:41 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 0x10a734a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85294860a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d8d30>, '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 0x10a3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cfb20>, '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.XFTLacSiB9/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.5HTNtYWhll/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:37:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFTLacSiB9/Render', 13:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:41 INFO - 'HOME': '/Users/cltbld', 13:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:41 INFO - 'LOGNAME': 'cltbld', 13:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:41 INFO - 'MOZ_NO_REMOTE': '1', 13:37:41 INFO - 'NO_EM_RESTART': '1', 13:37:41 INFO - 'PAGER': '/bin/cat', 13:37:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:41 INFO - 'PWD': '/builds/slave/test', 13:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:41 INFO - 'SHELL': '/bin/bash', 13:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5HTNtYWhll/Listeners', 13:37:41 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 13:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:41 INFO - 'USER': 'cltbld', 13:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:41 INFO - 'XPC_FLAGS': '0x0', 13:37:41 INFO - 'XPC_SERVICE_NAME': '0', 13:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:37:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-CubAng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:37:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-O0Hrat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:37:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-7jKVmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:37:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-r0X5np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:37:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-korKjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:37:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-f6h4S2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:37:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Y5XqJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:37:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-4IldUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:37:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-qJJmhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:37:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-HZ6g4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:37:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-P94myO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:37:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-rAYbyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:37:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-0u2I8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:37:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-G4T7sM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-m42apQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:37:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-qVSZpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:37:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-4I4TvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:37:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:45 INFO - Downloading blessings-1.6.tar.gz 13:37:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:37:45 INFO - Installing collected packages: blessings 13:37:45 INFO - Running setup.py install for blessings 13:37:45 INFO - Successfully installed blessings 13:37:45 INFO - Cleaning up... 13:37:45 INFO - Return code: 0 13:37:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:37:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:37:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:37:46 INFO - Reading from file tmpfile_stdout 13:37:46 INFO - Current package versions: 13:37:46 INFO - blessings == 1.6 13:37:46 INFO - blobuploader == 1.2.4 13:37:46 INFO - docopt == 0.6.1 13:37:46 INFO - manifestparser == 1.1 13:37:46 INFO - mozInstall == 1.12 13:37:46 INFO - mozcrash == 0.17 13:37:46 INFO - mozdebug == 0.1 13:37:46 INFO - mozdevice == 0.48 13:37:46 INFO - mozfile == 1.2 13:37:46 INFO - mozhttpd == 0.7 13:37:46 INFO - mozinfo == 0.9 13:37:46 INFO - mozleak == 0.1 13:37:46 INFO - mozlog == 3.1 13:37:46 INFO - moznetwork == 0.27 13:37:46 INFO - mozprocess == 0.22 13:37:46 INFO - mozprofile == 0.28 13:37:46 INFO - mozrunner == 6.11 13:37:46 INFO - mozscreenshot == 0.1 13:37:46 INFO - mozsystemmonitor == 0.0 13:37:46 INFO - moztest == 0.7 13:37:46 INFO - mozversion == 1.4 13:37:46 INFO - psutil == 3.1.1 13:37:46 INFO - requests == 1.2.3 13:37:46 INFO - wsgiref == 0.1.2 13:37:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:46 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 0x10a734a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85294860a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d8d30>, '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 0x10a3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cfb20>, '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.XFTLacSiB9/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.5HTNtYWhll/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:37:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:37:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFTLacSiB9/Render', 13:37:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:46 INFO - 'HOME': '/Users/cltbld', 13:37:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:46 INFO - 'LOGNAME': 'cltbld', 13:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:46 INFO - 'MOZ_NO_REMOTE': '1', 13:37:46 INFO - 'NO_EM_RESTART': '1', 13:37:46 INFO - 'PAGER': '/bin/cat', 13:37:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:46 INFO - 'PWD': '/builds/slave/test', 13:37:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:46 INFO - 'SHELL': '/bin/bash', 13:37:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5HTNtYWhll/Listeners', 13:37:46 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 13:37:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:46 INFO - 'USER': 'cltbld', 13:37:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:46 INFO - 'XPC_FLAGS': '0x0', 13:37:46 INFO - 'XPC_SERVICE_NAME': '0', 13:37:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:37:46 INFO - Cleaning up... 13:37:46 INFO - Return code: 0 13:37:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:46 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 0x10a734a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85294860a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d8d30>, '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 0x10a3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cfb20>, '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.XFTLacSiB9/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.5HTNtYWhll/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:37:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:37:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFTLacSiB9/Render', 13:37:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:46 INFO - 'HOME': '/Users/cltbld', 13:37:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:46 INFO - 'LOGNAME': 'cltbld', 13:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:46 INFO - 'MOZ_NO_REMOTE': '1', 13:37:46 INFO - 'NO_EM_RESTART': '1', 13:37:46 INFO - 'PAGER': '/bin/cat', 13:37:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:46 INFO - 'PWD': '/builds/slave/test', 13:37:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:46 INFO - 'SHELL': '/bin/bash', 13:37:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5HTNtYWhll/Listeners', 13:37:46 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 13:37:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:46 INFO - 'USER': 'cltbld', 13:37:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:46 INFO - 'XPC_FLAGS': '0x0', 13:37:46 INFO - 'XPC_SERVICE_NAME': '0', 13:37:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:46 INFO - Downloading/unpacking jsonschema 13:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:37:49 INFO - Downloading/unpacking functools32 (from jsonschema) 13:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:50 INFO - Downloading functools32-3.2.3-2.tar.gz 13:37:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:37:50 INFO - warning: no files found matching '*.txt' 13:37:50 INFO - no previously-included directories found matching 'build' 13:37:50 INFO - no previously-included directories found matching 'dist' 13:37:50 INFO - no previously-included directories found matching '.git*' 13:37:50 INFO - Installing collected packages: jsonschema, functools32 13:37:50 INFO - Running setup.py install for functools32 13:37:50 INFO - warning: no files found matching '*.txt' 13:37:50 INFO - no previously-included directories found matching 'build' 13:37:50 INFO - no previously-included directories found matching 'dist' 13:37:50 INFO - no previously-included directories found matching '.git*' 13:37:50 INFO - Successfully installed jsonschema functools32 13:37:50 INFO - Cleaning up... 13:37:50 INFO - Return code: 0 13:37:50 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:50 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 0x10a734a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85294860a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d8d30>, '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 0x10a3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cfb20>, '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.XFTLacSiB9/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.5HTNtYWhll/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:37:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:37:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:37:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFTLacSiB9/Render', 13:37:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:50 INFO - 'HOME': '/Users/cltbld', 13:37:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:50 INFO - 'LOGNAME': 'cltbld', 13:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:50 INFO - 'MOZ_NO_REMOTE': '1', 13:37:50 INFO - 'NO_EM_RESTART': '1', 13:37:50 INFO - 'PAGER': '/bin/cat', 13:37:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:50 INFO - 'PWD': '/builds/slave/test', 13:37:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:50 INFO - 'SHELL': '/bin/bash', 13:37:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5HTNtYWhll/Listeners', 13:37:50 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 13:37:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:50 INFO - 'USER': 'cltbld', 13:37:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:50 INFO - 'XPC_FLAGS': '0x0', 13:37:50 INFO - 'XPC_SERVICE_NAME': '0', 13:37:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:51 INFO - Downloading/unpacking flake8 13:37: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 13:37: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 13:37: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 13:37: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 13:37: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 13:37: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 13:37:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:37:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:37:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:37:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:37:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:37:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:37:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:37:54 INFO - no previously-included directories found matching 'docs/_build' 13:37:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:37:54 INFO - Running setup.py install for pep8 13:37:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:37:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:37:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:37:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:37:55 INFO - no previously-included directories found matching 'docs/_build' 13:37:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:37:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:37:55 INFO - Cleaning up... 13:37:55 INFO - Return code: 0 13:37:55 INFO - Running post-action listener: _resource_record_post_action 13:37:55 INFO - Running post-action listener: _start_resource_monitoring 13:37:55 INFO - Starting resource monitoring. 13:37:55 INFO - ##### 13:37:55 INFO - ##### Finished create-virtualenv step (success) 13:37:55 INFO - ##### 13:37:55 INFO - ##### 13:37:55 INFO - ##### Running install step. 13:37:55 INFO - ##### 13:37:55 INFO - Running pre-action listener: _resource_record_pre_action 13:37:55 INFO - Running main action method: install 13:37:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:37:55 INFO - Reading from file tmpfile_stdout 13:37:55 INFO - Detecting whether we're running mozinstall >=1.0... 13:37:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:37:55 INFO - Reading from file tmpfile_stdout 13:37:55 INFO - Output received: 13:37:55 INFO - Usage: mozinstall [options] installer 13:37:55 INFO - Options: 13:37:55 INFO - -h, --help show this help message and exit 13:37:55 INFO - -d DEST, --destination=DEST 13:37:55 INFO - Directory to install application into. [default: 13:37:55 INFO - "/builds/slave/test"] 13:37:55 INFO - --app=APP Application being installed. [default: firefox] 13:37:55 INFO - mkdir: /builds/slave/test/build/application 13:37:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:38:29 INFO - Reading from file tmpfile_stdout 13:38:29 INFO - Output received: 13:38:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:38:29 INFO - Running post-action listener: _resource_record_post_action 13:38:29 INFO - ##### 13:38:29 INFO - ##### Finished install step (success) 13:38:29 INFO - ##### 13:38:29 INFO - ##### 13:38:29 INFO - ##### Running run-tests step. 13:38:29 INFO - ##### 13:38:29 INFO - Running pre-action listener: _resource_record_pre_action 13:38:29 INFO - Running main action method: run_tests 13:38:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:38:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:38:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:38:29 INFO - 2016-05-09 13:38:29.433 screenresolution[1637:7428] starting screenresolution argv=screenresolution get 13:38:29 INFO - 2016-05-09 13:38:29.435 screenresolution[1637:7428] Display 0: 1600x1200x32@60 13:38:29 INFO - 2016-05-09 13:38:29.441 screenresolution[1638:7430] starting screenresolution argv=screenresolution list 13:38:29 INFO - Available Modes on Display 0 13:38:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:38:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:38:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:38:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:38:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:38:29 INFO - Intel Iris: 13:38:29 INFO - Chipset Model: Intel Iris 13:38:29 INFO - Type: GPU 13:38:29 INFO - Bus: Built-In 13:38:29 INFO - VRAM (Dynamic, Max): 1536 MB 13:38:29 INFO - Vendor: Intel (0x8086) 13:38:29 INFO - Device ID: 0x0a2e 13:38:29 INFO - Revision ID: 0x0009 13:38:29 INFO - Displays: 13:38:29 INFO - Display: 13:38:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:38:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:38:29 INFO - Display Serial Number: 1600x1200 60 13:38:29 INFO - Main Display: Yes 13:38:29 INFO - Mirror: Off 13:38:29 INFO - Online: Yes 13:38:29 INFO - Rotation: Supported 13:38:29 INFO - Return code: 0 13:38:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:38:29 INFO - Python 2.7.3 13:38:29 INFO - Return code: 0 13:38:29 INFO - grabbing minidump binary from tooltool 13:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:38:29 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 0x10a5d8d30>, '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 0x10a3fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:38:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:38:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:38:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:38:29 INFO - Return code: 0 13:38:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:38:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:38:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:38:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:38:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:38:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:38:30 INFO - Return code: 0 13:38:30 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/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--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 13:38:30 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/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0115 --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 13:38:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFTLacSiB9/Render', 13:38:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:38:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:38:30 INFO - 'HOME': '/Users/cltbld', 13:38:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:38:30 INFO - 'LOGNAME': 'cltbld', 13:38:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:38:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:30 INFO - 'MOZ_NO_REMOTE': '1', 13:38:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:38:30 INFO - 'NO_EM_RESTART': '1', 13:38:30 INFO - 'PAGER': '/bin/cat', 13:38:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:30 INFO - 'PWD': '/builds/slave/test', 13:38:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:38:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:38:30 INFO - 'SHELL': '/bin/bash', 13:38:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5HTNtYWhll/Listeners', 13:38:30 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 13:38:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:38:30 INFO - 'USER': 'cltbld', 13:38:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:38:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:30 INFO - 'XPC_FLAGS': '0x0', 13:38:30 INFO - 'XPC_SERVICE_NAME': '0', 13:38:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:38:30 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/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--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 13:38:31 INFO - mozversion application_buildid: 20160509113556 13:38:31 INFO - mozversion application_changeset: 0530f8d36de834b921c7c68272e6c0831bedae63 13:38:31 INFO - mozversion application_display_name: Nightly 13:38:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:38:31 INFO - mozversion application_name: Firefox 13:38:31 INFO - mozversion application_remotingname: firefox 13:38:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:38:31 INFO - mozversion application_vendor: Mozilla 13:38:31 INFO - mozversion application_version: 49.0a1 13:38:31 INFO - mozversion platform_buildid: 20160509113556 13:38:31 INFO - mozversion platform_changeset: 0530f8d36de834b921c7c68272e6c0831bedae63 13:38:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:38:31 INFO - mozversion platform_version: 49.0a1 13:38:31 INFO - using testdate: 1462826311 13:38:31 INFO - actual date: 1462826311 13:38:31 INFO - starting webserver on 'localhost:49273' 13:38:31 INFO - SUITE-START | Running 2 tests 13:38:31 INFO - TEST-START | dromaeo_css 13:38:31 INFO - operating with platform_type : mac_ 13:38:31 INFO - Initialising browser for dromaeo_css test... 13:38:31 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp2bEuWf/profile http://localhost:49273/getInfo.html) 13:38:33 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 13:38:33 INFO - PROCESS | 1656 | colorDepth:24 13:38:33 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 13:38:33 INFO - PROCESS | 1656 | __metrics 13:38:33 INFO - PROCESS | 1656 | 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" 13:38:33 ERROR - PROCESS | 1656 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:38:34 INFO - TEST-INFO | 1656: exit 0 13:38:34 INFO - Browser initialized. 13:38:34 INFO - Running cycle 1/1 for dromaeo_css test... 13:38:34 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp2bEuWf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:38:46 INFO - PROCESS | 1658 | RSS: Main: 239005696 13:38:46 INFO - PROCESS | 1658 | 13:39:37 INFO - PROCESS | 1658 | 1462826377721 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:41:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 13:41:49 INFO - PROCESS | 1658 | RSS: Main: 363237376 13:41:49 INFO - PROCESS | 1658 | 13:44:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 13:44:53 INFO - PROCESS | 1658 | RSS: Main: 371183616 13:44:53 INFO - PROCESS | 1658 | 13:47:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 13:47:55 INFO - PROCESS | 1658 | RSS: Main: 367767552 13:47:55 INFO - PROCESS | 1658 | 13:50:58 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 13:50:58 INFO - PROCESS | 1658 | RSS: Main: 367828992 13:50:58 INFO - PROCESS | 1658 | 13:54:02 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 13:54:02 INFO - PROCESS | 1658 | RSS: Main: 335167488 13:54:02 INFO - PROCESS | 1658 | 13:57:06 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 13:57:06 INFO - PROCESS | 1658 | RSS: Main: 376745984 13:57:06 INFO - PROCESS | 1658 | 13:57:06 INFO - PROCESS | 1658 | __start_tp_report 13:57:06 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 13:57:06 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 13:57:06 INFO - PROCESS | 1658 | |i|pagename|runs| 13:57:06 INFO - PROCESS | 1658 | |0;dojo.html;3048.557959095931;3445.9999999999986;3466.705330046946;3471.1148657092444;3514.3674138655033;5586.720663966806;5638.40796716345;5649.096144616857;5718.914216286754;5737.5696822738255;5660.5471562275025;5708;5754.079347304432;5772.769089236436;5803.448672376128;5091.6690415123085;5137.871553211162;5201.349831271091;5203.375594928614;5285.7885684572575;5281.102212623844;5300.390455097655;5314.521693047625;5331.680099194042;5347.893042139155;4999.925001124986;5041.823536176216;5053.772580233898;5115.104856650071;5192.117340052181;4767.118083154627;5170.379554453452;5173.379194496647;5173.8189163379475;5260.132078207092;4053.695972802022;5148.768305426265;5157.7936882524655;5193.987172501364;5209;5447.700376479292;5489.2589500417735;5581.5255703265275;5597.832065038056;5664.830055098312;6072.977292302463;6117.204763380732;6155.076738489262;6160.784372546984;6181.6600086995195;5555.972220138914;6098.176746139259;6134.846628834315;6151.630902145886;6179.907301390483;5677.545796336283;5747.827565173052;5815.912761308584;5825.330087040044;5874.030784920483;5188.9740551297755;6171.876562468726;6197.225346831556;6328.6835658216905;6351.777687780998;6112.991356426229;6125.356837532084;6136.447719705158;6140.662263575547;6148.631082135086;6157.476614487819;6244.531660125418;6305.558610897193;6305.968470157712;6325.462335701425;6081.239845019372;6114.694265286718;6119.807225076679;6132.386761323832;6192.721327540272;6113.7153036952495;6178.66017369049;6183.5671502994355;6186;6192.8761424771255;4959.20652695567;4961.677490963076;4969.180085285887;4973.154563724175;5017;4073.1853629274265;4441.111777644484;4446.155230506193;4471.73169609818;4479.932801007987;5459.29029226206;5496.395396506381;5615.831525054255;5680.857978550569;5694;6273.341299163613;6276.995680691068;6290.370962903765;6297.622142671455;6311.842203944938;5668.461496157858;5693.886122277614;5695.060315048054;5702.7433765480655;5712.514436272963;5272.657277276965;5274.103402421598;5325.760340784596;5379.112446446371;5381.865453363619;6225.688715564294;6251.12484252205;6258;6290.496760259077;6318.336574659686;6201.379862013762;6286.905696414649;6288.465480434214;6290.0879372491645;6295.244570651551;6261.405166509175;6286.371362863677;6292.748290068346;6296.590721603081;6298.937010629835;4957.305977163128;6065.939340606714;6082.026875699867;6193.969030154821;6199.442050215503;6176.135341052346;6241.095041219089;6250.218722659668;6269.717862696099;6294.118823364641;4559.17934771731;4965;5492.615516913777;6115.816525504242;6132.049532322497;5880.029795083763;5979.073243647242;5991.580589358878;6096.237970253718;6104.572679912541;5828.125781132694;5876.529877609866;5907.17299578068;5918.023526118314;5959.582829201914;6022.09668549721;6095.652562707799;6096.466741106985;6096.568695548639;6121.47967422774;5899.46904778572;5919.348871624204;5923.555733319932;5929.970350148222;5948.4943779779205 13:57:06 INFO - PROCESS | 1658 | |1;ext.html;504.99505983093655;510.9744512774362;523.9677834665707;530.7929907336137;556.4741319453117;29687.406251874978;30176.54735178974;41606;41989.58010419895;42002.159956800846;41337.58662413382;43493.56506434942;43609.78195109028;43714;43720.78139609298;9560.235181185506;9746.171575416098;9755.853662195073;9757.804843903117;9767.951160244189;9556.23550115989;9666;9691.95154024229;9694;9715.61137554497;9694;9755.268354873377;9770.511474426286;9793.26550508711;9806.803863922718;9661.710148695514;9720.368176068532;9730.270229732798;9730.805383892353;9743.756406089833;9044.909550904538;9598;9651.662191823252;9661.227101831837;9681.612735490571;36639.63360366416;40437.393439098436;40555.98610034743;40598.79700601478;40601.79699101485;3210.4542227821325;3347.0795531228864;3428.4171690812373;3447.241606846515;3615.3130905128205;28565.000224992233;28967.710322896924;29007.70992290071;29287.70712292871;29370.853145734134;2448.9755102448926;2453.3130723397476;2453.6319552067152;2512.56030194717;2615.843049417041;388653;389040;389812;390225;390259.0487047575;35406;61504;61858.69070654618;61885;61901;32711.83644081812;35691.82154089213;36579.26841463209;36657.266854662754;36674.44988325177;15106.924465377602;15458.768118478232;15468.994515356466;15479.5356139316;15486.690266194613;12939.870601294055;13006.089573729938;13040;13053;13060.608181754562;8249.35012997391;8249.628766705513;8264.669413223524;8344.748287756764;8347.415680902279;3401.931961360759;3417;3417.3425888444085;3432.742544309137;3455.1362159460136;812.7155495576501;914.9451032938046;958.1089586684311;1061.1086687182803;1102.3771569063454;29923.251918702208;30632.017837681404;31117.17683759021;31492.842535787175;31729.8413507931;1054.2567489919588;1063.5958335832333;1066.6373433032807;1072.3512275073624;1134.9205555611193;14711.485098021518;14946.177960212293;14994.325255363536;15174.165420901956;15251.771223431657;663011;665269;669961;672041.4785482484;673473;57704.422955770744;58198.4180158193;58224;58242;58264.70867645634;1272.2748033620746;1354.0927578522414;1430.5851303122395;1451.6951440197465;1501.159350763576;23695.644565331877;29365.853170734008;29510.704892951653;29711.55432668555;30430.391392172474;50358.49641503685;50560.69112743256;51369.486305136474;51483;51524.22713659223;512564;518223;518434;518837;519311;18122.45632631023;25415.87292063528;26245.95016199407;26451.867740661175;26499;44233;44310.46119235096;44538;45075.873103172686;45648.31527527023;30427.695723042485;35427.468587971714;37034.33345499513;37830;37963.58327750516;29952.101436956928;35709.64290357063;36084.63915360813;36286.455703165004;36854;36749.26501469902;37076.887693369245;37176.44235336526;37336.81331593324;38335.80832095822;28901.7109828899;32101.839490802395;32440.188995275308;33219.833900830345;33243.50134748029;24833.751662483144;24842.627360589246;25010.499790004462;25335.113271035425;25569 13:57:06 INFO - PROCESS | 1658 | |2;jquery.html;388.62109443292786;389.1904837937091;391.2429449016152;392.14121074846094;411.9485064366954;18142.274309027638;20455.284065057727;21500.677489837664;21564.13743450262;21603.783962160374;20146.39560813178;20231.49421264469;21588.784112158875;21861.234856780015;21869.453263668416;5701.287339082614;6030.849228769272;6034.818955431322;6040.456358927696;6042.06348016056;5823.621464604808;5891.410858914117;5938.6436813791315;5996.340402555716;6002.249718785152;5720.056190728525;5864.794732184374;5869.7652093916195;5895.115732640117;5908.202392676976;5673.177389278531;5813.941860581383;5820.534357251409;5837.328707198684;5885.49973252272;4951.603871690256;5085.821996230115;5110.412302585213;5111.795528178868;5138.254953031789;26033.609495857578;26264.8686756565;26749.598756018677;26798;26808.32979175536;2089.226986015169;2111.926082587102;2275.306031660343;2387.1663708807328;2664.5736682130764;11418.828717569244;14882.330295136744;15229.695406091816;15390.384384624602;15657.843421565867;1739.9826001739948;1764.7176451767784;1774.290283886443;1797.973030404545;1931.7005864091088;120801.79198207993;124410;124936;126784.83037924033;130410;10679.359238445719;11242.100631949424;11274.774504509864;11287;11490;13981.440742370394;14041.85958140426;14059.648508787363;14070.226137562533;14096.295185240697;7303.8539229216185;8832.337574681924;8882.022977472472;8920.286377089817;9053.321000924958;9007.684731034382;9016.549172541347;9031.683891063782;9122.133397327243;9145.993940666522;6634.497602197966;6696.83257918556;6698.933010669831;6767.763128290486;6875.174979002451;1877.934272300463;1924.200872184484;1924.3841970569283;1932.3140285198833;1972.8421726261865;756.4137793210197;775.9146493885668;913.949732764691;920.7836158502746;932.7061975477703;15121.848781512044;18601.627967440847;20367.08348124338;21002.78997210039;21957.341279761633;879.1077056787366;951.3055469507299;991.8065977134389;1032.8347464405658;1075.9377130600506;10783.406912619881;10945.273631840688;11028.172887033505;11113.666590002313;11718.238314509701;631071;636639;637045;648995;651988;90322.90168641381;105519;142996;147499.26250368895;149041.03835846446;974.449436068619;975.770693886936;1016.4968340671415;1019.4494972714651;1027.686555600549;18219.45341639753;18805.811941880955;18926.52683682919;18965.130368442573;19109.331173408864;49542.50457495379;50829;51539.742301289756;52490.737546312026;52936.94126117533;81239;82821.58589207016;84041;85880;86312.27375452581;7172.497925145399;7605.277498637621;7645.885311720217;7862.52824830512;8703.651853925772;9334.71995840126;12563.685907852378;12579;13295.401706923018;13499.797503037265;10481.58073677075;11238.66284011481;11597.594084207012;11669.183157179255;11742.17804753659;10756.623518176826;10893.183011274028;11029.944850275697;11188.384638844942;11296.322220666787;9578.952105239428;11470.025047871024;11556.47995840206;11813.881861181279;11894;7103.325184107708;7502.174760776419;7612.695492180251;8098.380323935119;8811.735647930573;8505.277051450696;10763.461826908466;11339.262947908359;11459.885401145882;11675 13:57:06 INFO - PROCESS | 1658 | |3;mootools.html;1413.9858601413982;1449.3912556726175;1570.835062318457;1766.7526546283514;1927.7108433734938;7534.3972482201425;8000.0799908010595;8001.8799718004275;8030.357571394289;8031.116577176514;8076;8409.327253819694;8417.78955526112;8522.57387130644;8533.871991920127;1985.632657958275;2020.7272018277492;2296.402935236842;2364.255259593231;2365.0185173153122;2319.7332306784683;2344.5193735284224;2362.7400985891536;2366.4202270443766;2369.2892132360307;2318.6290193569034;2328.4528135888054;2350.341904266808;2365.727941286757;2368.549975504657;2306.6655334976417;2313.4910319729634;2347.565700345433;2350.6944097267415;2351.682522859426;2362.8582285062953;2387.3434805428647;2390.5577468168444;2392.557376885282;2394.5809483340345;8438.53588052663;8504.31965442763;8509.95745021277;8512.574371281411;8526.190011948855;5093.617978651579;5148.871278218037;5287.894242115136;5301.6818990860675;5324.54741346986;3248.5776849009476;3330.317284956578;3362.9327413451592;3396.3207358528393;3399.201187720884;968.6464440479244;973.6640858903667;982.2387649571567;986.9654562090292;988.461288597716;11163;11228.831567526411;11282.774344513064;11284;11285.210035297532;4379.255526560492;4501.18978583858;4577.633789296849;4625.352450656911;4634.2585186370015;8845.69040083594;8878.86681699775;8884.37809353352;8916.465012099296;8922.241609463139;4181.456410666594;5130.4356520782685;5200.609954253446;5209.244659524347;5210.47895210476;4071.796410179479;4280.422143010654;4846.709197448165;4902.142125128088;4917.754112294371;3699.7780133191554;3727.161388687578;3752.924941501209;3790.0524868782804;3815.6565909068318;1167.1538134852165;1181.7931861924128;1192.8687844337117;1204.7771162334996;1205.6443349212004;852.9892077887599;865.0830120072743;888.3293113699245;902.309733054214;930.2883294279882;5675.57433192504;5788.247527821356;5862.970685146547;5967.403259674084;6019.067044608093;855.4781583234222;955.1117460761419;965.7392504023874;972.8054389122204;1024.8565200871883;3874.7093967952515;3956.050547868548;4004.7797371144866;4044.1102957349335;4064.227796718614;8417.831643367099;10366.170706343475;10402.075616011061;10529.789404212026;10533.157347412189;8750.299976001905;10425.322354046963;10481.895181048245;10509.579616815241;10584.312019718695;7746.612669366509;8063;8067.596620168968;8088.919110808934;8099.190080991853;5662.122371032497;5745.3105627324985;5823.621464604787;5892;6044.063170208625;9514.096160864983;9674.95162524211;9810.75473113178;9816.558254878404;9856.457894815567;9770.951145244228;9911.355761875455;9929.702108936743;9931.205503559842;10035.849462257926;5963;6690.799276021728;6918.481113916577;6927.3419025192525;7011.754588589375;7632.542047476947;7763.14605393395;7987.36101111898;8073.031236251452;8185.222403871623;7005.299470052954;7048.964755176191;7071.82320441993;7243.239459856639;7399.741009064657;7052.118485189351;7156.928430715626;7201.3158749918675;7278.927210728037;7299.087614048244;6855.828604284933;6867.725290988305;6948.722051117898;6950.826229344307;6963.512554121163;4646.605038571624;4798.424189097331;4799.016201678717;4847.830325938575;5060.873478163076;4858.975705121452;4914.70511769295;4932.4081110267;4974.403071631427;5142.742862856768 13:57:06 INFO - PROCESS | 1658 | |4;prototype.html;305.9097566217966;318.6733598061986;320.17554796399264;325.71499937554637;329.2066120649236;5144.356955380577;5272.4727527247305;5282.418933917275;5289.709066001373;5324.946750532494;4647.442306923165;4693.131770622437;4781;5212.921806172911;5233.921491177626;3022.926860964359;3129.7965632233945;3769.9434508482395;3921.2157568486273;4040.6867767975377;3921.1765529238896;3980.765962551604;3996.240714264295;3996.6403023727858;4075.1645912587846;3874.4622272411616;3977.2840888640035;3982.3230050891416;3993.460882780831;4029.798510074499;2842.6873043965147;3840.3501583907014;4014.0767623446773;4024.5975402459812;4043.9797801010805;3494.5631796025496;3495.00784552805;3502.2820321833965;3516.9800757780467;3529.400001999666;5053.444121146671;5059.696418214919;5061.342025536656;5085.262636917626;5094.31226784383;3996.460477835484;4112.773797441139;4471.6646251531265;4586.770661466913;4615.630749540029;4771.785269662839;5091.8981620367385;5225.268462415265;5410.377806552257;5438.53772429344;3243.448613735647;3307.834608269577;3653.0684675407865;3807.9619203807883;3823.9044023899346;5078.746062696888;5126.897462050738;5130.102232109366;5287.603429742785;5740.311162660466;3640.199156185635;3839.6928245740273;3840.9629400062386;3856.341773037621;3881.223755248905;3275.4922986937063;3918.1967696622123;3926;3926.3521518949055;3926.7630696330566;4484.775761211927;4504.594586487232;4562.087582483517;4589.747563884019;4593.6554758392585;4487.304467807495;5004.199328107558;5188.45521220274;5272.894542109137;5531.557475401958;1108.8336749487642;1239.8016317389174;1241.3110723548518;1250.4873002069107;1254.4292346982284;693.6185098195972;731.6744048898189;772.7449941519285;810.2828996338166;855.619249433996;4216.388623649554;4649.279361698942;5078.324153029492;5082.542571168613;5090.720010399389;4847.95152048482;4997.600191984632;5494.15945658201;5557.41647127054;5600.691961942132;4074.511058672919;4443;4748.620110391161;5047.798088076436;5082.33929589151;2420.8547487150827;2444.804415646744;2501.8999240030194;2691.461707658476;2801.0196431249306;4729.905401891943;4797.160496913027;5113.463086375951;5213.1943681113;5323.467653234723;5010.571986983692;5356.8660783479945;5394.439669817902;5511.622094476381;5809.738561764731;4931.408231012229;5819.6508209507565;5835.67903765297;5889.852753681106;5909.91135132982;5417.593680474059;5671.427000989351;5682.687452190169;5693.4875861172695;5758.510526605118;4745.4542727585385;5054.898902022012;5252.921206181987;5742.19609254713;5754.251947246831;5706.404964872104;5895.7052147393315;5908.020813027512;5961.731722072604;6038.245219347582;3409.3351796399465;3451.4822776583715;3981.5023122109737;4010.2555388406136;4046.6964977626208;5051.848444546669;5212.530872221519;5394.298741163623;5591.6365436246515;5628.943710562842;4636.884077898079;4878.146324393288;5405.162199859028;5503.614746967673;5565.220869078414;4428.003699167662;4781.808727650855;4833.936533962523;4839.926646579377;5300.946990530046;4548.680882544025;5369.892602147857;5372.328458942632;5395.325584301962;5395.730213489388;5222.634415590872;5372.758225879923;5386;5499.175123731312;5573.832785016456;5430.83707488776;5432.18517222404;5479.082321187712;5485.4788795064405;5514.80698175562 13:57:06 INFO - PROCESS | 1658 | |5;yui.html;701.8912068629363;734.5666057026355;739.2416587909887;741.8590467811119;752.4394326226965;14839.777403338945;15617.921910390432;15631.452899148531;15641.530754077368;15648.608784780386;14220.360083796228;14294.14235145892;14298.928505357484;14313.78529322061;14321.355539000744;713.5754226235382;733.4755649710456;888.2893685051965;901.1574178143441;922.0594993107027;901.6032945503957;909.6134142989237;909.6361455417853;910.1080940678139;912.9911447850129;962.2254085461201;974.878140232471;977.946212958287;981.7692842182081;981.8036392721449;751.9887201691924;969.3795970578835;971.7473456901186;973.1339108193713;973.6446197138065;697.6337422852991;719.3597698048741;719.7948584653401;720.3480849830911;721.5454263813816;14696.191709455976;14707.926460367737;14788.926055369762;14790.778138327934;14790.926045369812;12174.513019479298;12337;12345.753084938251;12649.810252846215;12668.303243321614;10536.209784266133;10745.462726863625;10774.29967052147;11107.166962477766;11271.09831213501;3892.2020985697864;3916.1971795781797;3927.7054220933546;3943.3099207638547;4086.2440448516822;79222.20777792206;80327.19672803314;80535.38929221442;80638.5968070156;81434.77847832347;8985.101489851093;9186.86219706705;9494.477803720723;9551.904480955101;9689;17122;20399.79600203979;20674.483137921674;20682.68975965362;20701.27545535899;7093.467989900675;7179.174394944648;7281.162666293338;7382.630868456556;7431.888521672179;3973.4635824163947;4008.579099194601;4017.216642754693;4043.1711499142607;4122.154958233555;3420.3159368126476;3503.0366649171683;3503.8948831535095;3508.052825737088;3511.3328467591077;611.5016261746662;619.1610367951452;620.9658468784261;631.9052142178618;637.9075034120119;494;517.3972322244618;539.4335947255445;542.9602311573371;555.555555555554;7609.809754756065;7642.847143057108;7734.651940662684;7759.7672069838;7816.804579885435;486.7128394247411;515.0086084287731;548.1230031948929;549.7526113249054;555.4615407587368;3559.0746405934124;3579.2304654499408;3589.2821435712963;3638.326909521747;3785.981070094687;89799;90117.54941225384;96822.03177968271;97958.51020744993;99988;81358.59320703358;84066;84468.57765711132;85173.57413212894;86058;75695;76053.09867253141;76105.23894761203;76678;76934;7230.602316872622;7292.343689067797;7404.111506619241;7417.332440080419;7468.1785003648465;45382.77308613436;45877.85305367393;46064;46462.6061218164;47982;38514.80742596269;51938.74030629823;61020.08469873044;65917.68164636585;66078.33921660722;9267.53662316895;9446.811063778548;9567.569459374485;9804.558794854105;9841.45871977048;10367.377957322586;10425.322354046963;10535.05184533365;10618.150547955991;10656.520456579628;9940.204783617148;9982.35114717541;10012.749681258027;10015.048570385801;10217.846732298873;8094.433389662667;10101.949490252502;10155.593776248867;10260.897391025994;10299.330543514647;9805.509724513604;9822.361546499455;9830.213582913493;9869.457179855177;9941.30410871232;7979.84040319202;8246.134155913544;8308.87536686938;8314.459560128573;8333.833323333378;8360.540170290693;8419.70531031411;8635.654573817228;8853.468791872509;8885.733427997171 13:57:06 INFO - PROCESS | 1658 | __end_tp_report 13:57:06 INFO - PROCESS | 1658 | __start_cc_report 13:57:06 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1313 13:57:06 INFO - PROCESS | 1658 | __end_cc_report 13:57:06 INFO - PROCESS | 1658 | __startTimestamp1462827426486__endTimestamp 13:57:06 INFO - PROCESS | 1658 | 13:57:06 INFO - PROCESS | 1658 | ------- Summary: start ------- 13:57:06 INFO - PROCESS | 1658 | Number of tests: 6 13:57:06 INFO - PROCESS | 1658 | 13:57:06 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5675.61 Median:5876.53 stddev:655.26 (11.2%) stddev-sans-first:624.02 13:57:06 INFO - PROCESS | 1658 | Values: 3048.6 3446.0 3466.7 3471.1 3514.4 5586.7 5638.4 5649.1 5718.9 5737.6 5660.5 5708.0 5754.1 5772.8 5803.4 5091.7 5137.9 5201.3 5203.4 5285.8 5281.1 5300.4 5314.5 5331.7 5347.9 4999.9 5041.8 5053.8 5115.1 5192.1 4767.1 5170.4 5173.4 5173.8 5260.1 4053.7 5148.8 5157.8 5194.0 5209.0 5447.7 5489.3 5581.5 5597.8 5664.8 6073.0 6117.2 6155.1 6160.8 6181.7 5556.0 6098.2 6134.8 6151.6 6179.9 5677.5 5747.8 5815.9 5825.3 5874.0 5189.0 6171.9 6197.2 6328.7 6351.8 6113.0 6125.4 6136.4 6140.7 6148.6 6157.5 6244.5 6305.6 6306.0 6325.5 6081.2 6114.7 6119.8 6132.4 6192.7 6113.7 6178.7 6183.6 6186.0 6192.9 4959.2 4961.7 4969.2 4973.2 5017.0 4073.2 4441.1 4446.2 4471.7 4479.9 5459.3 5496.4 5615.8 5680.9 5694.0 6273.3 6277.0 6290.4 6297.6 6311.8 5668.5 5693.9 5695.1 5702.7 5712.5 5272.7 5274.1 5325.8 5379.1 5381.9 6225.7 6251.1 6258.0 6290.5 6318.3 6201.4 6286.9 6288.5 6290.1 6295.2 6261.4 6286.4 6292.7 6296.6 6298.9 4957.3 6065.9 6082.0 6194.0 6199.4 6176.1 6241.1 6250.2 6269.7 6294.1 4559.2 4965.0 5492.6 6115.8 6132.0 5880.0 5979.1 5991.6 6096.2 6104.6 5828.1 5876.5 5907.2 5918.0 5959.6 6022.1 6095.7 6096.5 6096.6 6121.5 5899.5 5919.3 5923.6 5930.0 5948.5 13:57:06 INFO - PROCESS | 1658 | 13:57:06 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:64823.89 Median:37126.67 stddev:144060.53 (388.0%) stddev-sans-first:144383.71 13:57:06 INFO - PROCESS | 1658 | Values: 505.0 511.0 524.0 530.8 556.5 29687.4 30176.5 41606.0 41989.6 42002.2 41337.6 43493.6 43609.8 43714.0 43720.8 9560.2 9746.2 9755.9 9757.8 9768.0 9556.2 9666.0 9692.0 9694.0 9715.6 9694.0 9755.3 9770.5 9793.3 9806.8 9661.7 9720.4 9730.3 9730.8 9743.8 9044.9 9598.0 9651.7 9661.2 9681.6 36639.6 40437.4 40556.0 40598.8 40601.8 3210.5 3347.1 3428.4 3447.2 3615.3 28565.0 28967.7 29007.7 29287.7 29370.9 2449.0 2453.3 2453.6 2512.6 2615.8 388653.0 389040.0 389812.0 390225.0 390259.0 35406.0 61504.0 61858.7 61885.0 61901.0 32711.8 35691.8 36579.3 36657.3 36674.4 15106.9 15458.8 15469.0 15479.5 15486.7 12939.9 13006.1 13040.0 13053.0 13060.6 8249.4 8249.6 8264.7 8344.7 8347.4 3401.9 3417.0 3417.3 3432.7 3455.1 812.7 914.9 958.1 1061.1 1102.4 29923.3 30632.0 31117.2 31492.8 31729.8 1054.3 1063.6 1066.6 1072.4 1134.9 14711.5 14946.2 14994.3 15174.2 15251.8 663011.0 665269.0 669961.0 672041.5 673473.0 57704.4 58198.4 58224.0 58242.0 58264.7 1272.3 1354.1 1430.6 1451.7 1501.2 23695.6 29365.9 29510.7 29711.6 30430.4 50358.5 50560.7 51369.5 51483.0 51524.2 512564.0 518223.0 518434.0 518837.0 519311.0 18122.5 25415.9 26246.0 26451.9 26499.0 44233.0 44310.5 44538.0 45075.9 45648.3 30427.7 35427.5 37034.3 37830.0 37963.6 29952.1 35709.6 36084.6 36286.5 36854.0 36749.3 37076.9 37176.4 37336.8 38335.8 28901.7 32101.8 32440.2 33219.8 33243.5 24833.8 24842.6 25010.5 25335.1 25569.0 13:57:06 INFO - PROCESS | 1658 | 13:57:06 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:36700.45 Median:26149.24 stddev:106884.04 (408.7%) stddev-sans-first:107149.10 13:57:06 INFO - PROCESS | 1658 | Values: 388.6 389.2 391.2 392.1 411.9 18142.3 20455.3 21500.7 21564.1 21603.8 20146.4 20231.5 21588.8 21861.2 21869.5 5701.3 6030.8 6034.8 6040.5 6042.1 5823.6 5891.4 5938.6 5996.3 6002.2 5720.1 5864.8 5869.8 5895.1 5908.2 5673.2 5813.9 5820.5 5837.3 5885.5 4951.6 5085.8 5110.4 5111.8 5138.3 26033.6 26264.9 26749.6 26798.0 26808.3 2089.2 2111.9 2275.3 2387.2 2664.6 11418.8 14882.3 15229.7 15390.4 15657.8 1740.0 1764.7 1774.3 1798.0 1931.7 120801.8 124410.0 124936.0 126784.8 130410.0 10679.4 11242.1 11274.8 11287.0 11490.0 13981.4 14041.9 14059.6 14070.2 14096.3 7303.9 8832.3 8882.0 8920.3 9053.3 9007.7 9016.5 9031.7 9122.1 9146.0 6634.5 6696.8 6698.9 6767.8 6875.2 1877.9 1924.2 1924.4 1932.3 1972.8 756.4 775.9 913.9 920.8 932.7 15121.8 18601.6 20367.1 21002.8 21957.3 879.1 951.3 991.8 1032.8 1075.9 10783.4 10945.3 11028.2 11113.7 11718.2 631071.0 636639.0 637045.0 648995.0 651988.0 90322.9 105519.0 142996.0 147499.3 149041.0 974.4 975.8 1016.5 1019.4 1027.7 18219.5 18805.8 18926.5 18965.1 19109.3 49542.5 50829.0 51539.7 52490.7 52936.9 81239.0 82821.6 84041.0 85880.0 86312.3 7172.5 7605.3 7645.9 7862.5 8703.7 9334.7 12563.7 12579.0 13295.4 13499.8 10481.6 11238.7 11597.6 11669.2 11742.2 10756.6 10893.2 11029.9 11188.4 11296.3 9579.0 11470.0 11556.5 11813.9 11894.0 7103.3 7502.2 7612.7 8098.4 8811.7 8505.3 10763.5 11339.3 11459.9 11675.0 13:57:06 INFO - PROCESS | 1658 | 13:57:06 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5497.90 Median:5209.86 stddev:3036.46 (58.3%) stddev-sans-first:3029.46 13:57:06 INFO - PROCESS | 1658 | Values: 1414.0 1449.4 1570.8 1766.8 1927.7 7534.4 8000.1 8001.9 8030.4 8031.1 8076.0 8409.3 8417.8 8522.6 8533.9 1985.6 2020.7 2296.4 2364.3 2365.0 2319.7 2344.5 2362.7 2366.4 2369.3 2318.6 2328.5 2350.3 2365.7 2368.5 2306.7 2313.5 2347.6 2350.7 2351.7 2362.9 2387.3 2390.6 2392.6 2394.6 8438.5 8504.3 8510.0 8512.6 8526.2 5093.6 5148.9 5287.9 5301.7 5324.5 3248.6 3330.3 3362.9 3396.3 3399.2 968.6 973.7 982.2 987.0 988.5 11163.0 11228.8 11282.8 11284.0 11285.2 4379.3 4501.2 4577.6 4625.4 4634.3 8845.7 8878.9 8884.4 8916.5 8922.2 4181.5 5130.4 5200.6 5209.2 5210.5 4071.8 4280.4 4846.7 4902.1 4917.8 3699.8 3727.2 3752.9 3790.1 3815.7 1167.2 1181.8 1192.9 1204.8 1205.6 853.0 865.1 888.3 902.3 930.3 5675.6 5788.2 5863.0 5967.4 6019.1 855.5 955.1 965.7 972.8 1024.9 3874.7 3956.1 4004.8 4044.1 4064.2 8417.8 10366.2 10402.1 10529.8 10533.2 8750.3 10425.3 10481.9 10509.6 10584.3 7746.6 8063.0 8067.6 8088.9 8099.2 5662.1 5745.3 5823.6 5892.0 6044.1 9514.1 9675.0 9810.8 9816.6 9856.5 9771.0 9911.4 9929.7 9931.2 10035.8 5963.0 6690.8 6918.5 6927.3 7011.8 7632.5 7763.1 7987.4 8073.0 8185.2 7005.3 7049.0 7071.8 7243.2 7399.7 7052.1 7156.9 7201.3 7278.9 7299.1 6855.8 6867.7 6948.7 6950.8 6963.5 4646.6 4798.4 4799.0 4847.8 5060.9 4859.0 4914.7 4932.4 4974.4 5142.7 13:57:06 INFO - PROCESS | 1658 | 13:57:06 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4378.97 Median:4904.78 stddev:1360.03 (27.7%) stddev-sans-first:1329.04 13:57:06 INFO - PROCESS | 1658 | Values: 305.9 318.7 320.2 325.7 329.2 5144.4 5272.5 5282.4 5289.7 5324.9 4647.4 4693.1 4781.0 5212.9 5233.9 3022.9 3129.8 3769.9 3921.2 4040.7 3921.2 3980.8 3996.2 3996.6 4075.2 3874.5 3977.3 3982.3 3993.5 4029.8 2842.7 3840.4 4014.1 4024.6 4044.0 3494.6 3495.0 3502.3 3517.0 3529.4 5053.4 5059.7 5061.3 5085.3 5094.3 3996.5 4112.8 4471.7 4586.8 4615.6 4771.8 5091.9 5225.3 5410.4 5438.5 3243.4 3307.8 3653.1 3808.0 3823.9 5078.7 5126.9 5130.1 5287.6 5740.3 3640.2 3839.7 3841.0 3856.3 3881.2 3275.5 3918.2 3926.0 3926.4 3926.8 4484.8 4504.6 4562.1 4589.7 4593.7 4487.3 5004.2 5188.5 5272.9 5531.6 1108.8 1239.8 1241.3 1250.5 1254.4 693.6 731.7 772.7 810.3 855.6 4216.4 4649.3 5078.3 5082.5 5090.7 4848.0 4997.6 5494.2 5557.4 5600.7 4074.5 4443.0 4748.6 5047.8 5082.3 2420.9 2444.8 2501.9 2691.5 2801.0 4729.9 4797.2 5113.5 5213.2 5323.5 5010.6 5356.9 5394.4 5511.6 5809.7 4931.4 5819.7 5835.7 5889.9 5909.9 5417.6 5671.4 5682.7 5693.5 5758.5 4745.5 5054.9 5252.9 5742.2 5754.3 5706.4 5895.7 5908.0 5961.7 6038.2 3409.3 3451.5 3981.5 4010.3 4046.7 5051.8 5212.5 5394.3 5591.6 5628.9 4636.9 4878.1 5405.2 5503.6 5565.2 4428.0 4781.8 4833.9 4839.9 5300.9 4548.7 5369.9 5372.3 5395.3 5395.7 5222.6 5372.8 5386.0 5499.2 5573.8 5430.8 5432.2 5479.1 5485.5 5514.8 13:57:06 INFO - PROCESS | 1658 | 13:57:06 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:17970.88 Median:3946.37 stddev:26188.95 (663.6%) stddev-sans-first:26230.32 13:57:06 INFO - PROCESS | 1658 | Values: 701.9 734.6 739.2 741.9 752.4 14839.8 15617.9 15631.5 15641.5 15648.6 14220.4 14294.1 14298.9 14313.8 14321.4 713.6 733.5 888.3 901.2 922.1 901.6 909.6 909.6 910.1 913.0 962.2 974.9 977.9 981.8 981.8 752.0 969.4 971.7 973.1 973.6 697.6 719.4 719.8 720.3 721.5 14696.2 14707.9 14788.9 14790.8 14790.9 12174.5 12337.0 12345.8 12649.8 12668.3 10536.2 10745.5 10774.3 11107.2 11271.1 3892.2 3916.2 3927.7 3943.3 4086.2 79222.2 80327.2 80535.4 80638.6 81434.8 8985.1 9186.9 9494.5 9551.9 9689.0 17122.0 20399.8 20674.5 20682.7 20701.3 7093.5 7179.2 7281.2 7382.6 7431.9 3973.5 4008.6 4017.2 4043.2 4122.2 3420.3 3503.0 3503.9 3508.1 3511.3 611.5 619.2 621.0 631.9 637.9 494.0 517.4 539.4 543.0 555.6 7609.8 7642.8 7734.7 7759.8 7816.8 486.7 515.0 548.1 549.8 555.5 3559.1 3579.2 3589.3 3638.3 3786.0 89799.0 90117.5 96822.0 97958.5 99988.0 81358.6 84066.0 84468.6 85173.6 86058.0 75695.0 76053.1 76105.2 76678.0 76934.0 7230.6 7292.3 7404.1 7417.3 7468.2 45382.8 45877.9 46064.0 46462.6 47982.0 38514.8 51938.7 61020.1 65917.7 66078.3 9267.5 9446.8 9567.6 9804.6 9841.5 10367.4 10425.3 10535.1 10618.2 10656.5 9940.2 9982.4 10012.7 10015.0 10217.8 8094.4 10101.9 10155.6 10260.9 10299.3 9805.5 9822.4 9830.2 9869.5 9941.3 7979.8 8246.1 8308.9 8314.5 8333.8 8360.5 8419.7 8635.7 8853.5 8885.7 13:57:06 INFO - PROCESS | 1658 | -------- Summary: end -------- 13:57:06 INFO - PROCESS | 1658 | 13:57:07 INFO - TEST-INFO | 1658: exit 0 13:57:07 INFO - TEST-OK | dromaeo_css | took 1116038ms 13:57:07 INFO - TEST-START | kraken 13:57:07 INFO - operating with platform_type : mac_ 13:57:07 INFO - Initialising browser for kraken test... 13:57:07 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpg3r8Uf/profile http://localhost:49273/getInfo.html) 13:57:09 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 13:57:09 INFO - PROCESS | 1668 | colorDepth:24 13:57:09 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 13:57:09 INFO - PROCESS | 1668 | __metrics 13:57:09 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:57:09 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:57:10 INFO - TEST-INFO | 1668: exit 0 13:57:10 INFO - Browser initialized. 13:57:10 INFO - Running cycle 1/1 for kraken test... 13:57:10 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpg3r8Uf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:57:22 INFO - PROCESS | 1670 | RSS: Main: 239017984 13:57:22 INFO - PROCESS | 1670 | 13:57:24 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 13:57:25 INFO - PROCESS | 1670 | RSS: Main: 287326208 13:57:25 INFO - PROCESS | 1670 | 13:57:28 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 13:57:29 INFO - PROCESS | 1670 | RSS: Main: 295653376 13:57:29 INFO - PROCESS | 1670 | 13:57:32 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 13:57:33 INFO - PROCESS | 1670 | RSS: Main: 385433600 13:57:33 INFO - PROCESS | 1670 | 13:57:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 13:57:36 INFO - PROCESS | 1670 | RSS: Main: 284282880 13:57:36 INFO - PROCESS | 1670 | 13:57:38 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:57:38 INFO - PROCESS | 1670 | RSS: Main: 287821824 13:57:38 INFO - PROCESS | 1670 | 13:57:41 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 13:57:42 INFO - PROCESS | 1670 | RSS: Main: 325967872 13:57:42 INFO - PROCESS | 1670 | 13:57:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 13:57:46 INFO - PROCESS | 1670 | RSS: Main: 328925184 13:57:46 INFO - PROCESS | 1670 | 13:57:49 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 13:57:49 INFO - PROCESS | 1670 | RSS: Main: 327499776 13:57:49 INFO - PROCESS | 1670 | 13:57:51 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:57:52 INFO - PROCESS | 1670 | RSS: Main: 315371520 13:57:52 INFO - PROCESS | 1670 | 13:57:54 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:57:54 INFO - PROCESS | 1670 | RSS: Main: 321765376 13:57:54 INFO - PROCESS | 1670 | 13:57:57 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:57:57 INFO - PROCESS | 1670 | RSS: Main: 323833856 13:57:57 INFO - PROCESS | 1670 | 13:58:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:58:01 INFO - PROCESS | 1670 | RSS: Main: 326139904 13:58:01 INFO - PROCESS | 1670 | 13:58:04 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:58:05 INFO - PROCESS | 1670 | RSS: Main: 322588672 13:58:05 INFO - PROCESS | 1670 | 13:58:06 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 13:58:07 INFO - PROCESS | 1670 | RSS: Main: 369467392 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | __start_tp_report 13:58:07 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 13:58:07 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 13:58:07 INFO - PROCESS | 1670 | |i|pagename|runs| 13:58:07 INFO - PROCESS | 1670 | |0;ai-astar;113;107;113;115;161;124;112;111;113;112 13:58:07 INFO - PROCESS | 1670 | |1;audio-beat-detection;157;113;143;198;132;168;164;119;166;121 13:58:07 INFO - PROCESS | 1670 | |2;audio-dft;190;186;186;186;184;186;183;183;184;186 13:58:07 INFO - PROCESS | 1670 | |3;audio-fft;81;74;58;126;57;63;57;60;112;56 13:58:07 INFO - PROCESS | 1670 | |4;audio-oscillator;91;99;132;80;81;79;101;80;78;100 13:58:07 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;85;83;86;83;86;88;85;70;118;130 13:58:07 INFO - PROCESS | 1670 | |6;imaging-darkroom;93;94;93;94;92;93;93;93;94;92 13:58:07 INFO - PROCESS | 1670 | |7;imaging-desaturate;86;86;86;87;87;84;84;84;85;83 13:58:07 INFO - PROCESS | 1670 | |8;json-parse-financial;60;59;62;60;60;58;58;122;62;57 13:58:07 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;62;71;73;77;74;68;67;67;68;68 13:58:07 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;86;87;94;104;106;86;81;85;85;86 13:58:07 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;139;138;126;156;135;126;138;144;242;158 13:58:07 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;169;168;168;170;168;169;166;167;167;166 13:58:07 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;68;67;67;67;66;65;65;65;66;68 13:58:07 INFO - PROCESS | 1670 | __end_tp_report 13:58:07 INFO - PROCESS | 1670 | __start_cc_report 13:58:07 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2237 13:58:07 INFO - PROCESS | 1670 | __end_cc_report 13:58:07 INFO - PROCESS | 1670 | __startTimestamp1462827487064__endTimestamp 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | ------- Summary: start ------- 13:58:07 INFO - PROCESS | 1670 | Number of tests: 14 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:118.10 Median:113.00 stddev:15.67 (13.9%) stddev-sans-first:16.52 13:58:07 INFO - PROCESS | 1670 | Values: 113.0 107.0 113.0 115.0 161.0 124.0 112.0 111.0 113.0 112.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:148.10 Median:160.50 stddev:27.16 (16.9%) stddev-sans-first:28.61 13:58:07 INFO - PROCESS | 1670 | Values: 157.0 113.0 143.0 198.0 132.0 168.0 164.0 119.0 166.0 121.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:185.40 Median:186.00 stddev:2.07 (1.1%) stddev-sans-first:1.36 13:58:07 INFO - PROCESS | 1670 | Values: 190.0 186.0 186.0 186.0 184.0 186.0 183.0 183.0 184.0 186.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:74.40 Median:59.00 stddev:25.10 (42.5%) stddev-sans-first:26.51 13:58:07 INFO - PROCESS | 1670 | Values: 81.0 74.0 58.0 126.0 57.0 63.0 57.0 60.0 112.0 56.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:80.00 stddev:16.89 (21.1%) stddev-sans-first:17.91 13:58:07 INFO - PROCESS | 1670 | Values: 91.0 99.0 132.0 80.0 81.0 79.0 101.0 80.0 78.0 100.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:85.00 stddev:18.10 (21.3%) stddev-sans-first:19.05 13:58:07 INFO - PROCESS | 1670 | Values: 85.0 83.0 86.0 83.0 86.0 88.0 85.0 70.0 118.0 130.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 13:58:07 INFO - PROCESS | 1670 | Values: 93.0 94.0 93.0 94.0 92.0 93.0 93.0 93.0 94.0 92.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:86.00 stddev:1.40 (1.6%) stddev-sans-first:1.45 13:58:07 INFO - PROCESS | 1670 | Values: 86.0 86.0 86.0 87.0 87.0 84.0 84.0 84.0 85.0 83.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:60.00 stddev:19.81 (33.0%) stddev-sans-first:20.91 13:58:07 INFO - PROCESS | 1670 | Values: 60.0 59.0 62.0 60.0 60.0 58.0 58.0 122.0 62.0 57.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.50 stddev:4.30 (6.2%) stddev-sans-first:3.61 13:58:07 INFO - PROCESS | 1670 | Values: 62.0 71.0 73.0 77.0 74.0 68.0 67.0 67.0 68.0 68.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:86.00 stddev:8.54 (9.9%) stddev-sans-first:8.93 13:58:07 INFO - PROCESS | 1670 | Values: 86.0 87.0 94.0 104.0 106.0 86.0 81.0 85.0 85.0 86.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:141.50 stddev:33.97 (24.0%) stddev-sans-first:35.79 13:58:07 INFO - PROCESS | 1670 | Values: 139.0 138.0 126.0 156.0 135.0 126.0 138.0 144.0 242.0 158.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:168.00 stddev:1.32 (0.8%) stddev-sans-first:1.32 13:58:07 INFO - PROCESS | 1670 | Values: 169.0 168.0 168.0 170.0 168.0 169.0 166.0 167.0 167.0 166.0 13:58:07 INFO - PROCESS | 1670 | 13:58:07 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.17 (1.8%) stddev-sans-first:1.09 13:58:07 INFO - PROCESS | 1670 | Values: 68.0 67.0 67.0 67.0 66.0 65.0 65.0 65.0 66.0 68.0 13:58:07 INFO - PROCESS | 1670 | -------- Summary: end -------- 13:58:07 INFO - PROCESS | 1670 | 13:58:08 INFO - TEST-INFO | 1670: exit 0 13:58:08 INFO - TEST-OK | kraken | took 60950ms 13:58:08 INFO - SUITE-END | took 1176s 13:58:08 INFO - Completed test suite (00:19:37) 13:58:08 INFO - javascript benchmark 13:58:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3048.557959095931, 3445.9999999999986, 3466.705330046946, 3471.1148657092444, 3514.3674138655033, 5586.720663966806, 5638.40796716345, 5649.096144616857, 5718.914216286754, 5737.5696822738255, 5660.5471562275025, 5708.0, 5754.079347304432, 5772.769089236436, 5803.448672376128, 5091.6690415123085, 5137.871553211162, 5201.349831271091, 5203.375594928614, 5285.7885684572575, 5281.102212623844, 5300.390455097655, 5314.521693047625, 5331.680099194042, 5347.893042139155, 4999.925001124986, 5041.823536176216, 5053.772580233898, 5115.104856650071, 5192.117340052181, 4767.118083154627, 5170.379554453452, 5173.379194496647, 5173.8189163379475, 5260.132078207092, 4053.695972802022, 5148.768305426265, 5157.7936882524655, 5193.987172501364, 5209.0, 5447.700376479292, 5489.2589500417735, 5581.5255703265275, 5597.832065038056, 5664.830055098312, 6072.977292302463, 6117.204763380732, 6155.076738489262, 6160.784372546984, 6181.6600086995195, 5555.972220138914, 6098.176746139259, 6134.846628834315, 6151.630902145886, 6179.907301390483, 5677.545796336283, 5747.827565173052, 5815.912761308584, 5825.330087040044, 5874.030784920483, 5188.9740551297755, 6171.876562468726, 6197.225346831556, 6328.6835658216905, 6351.777687780998, 6112.991356426229, 6125.356837532084, 6136.447719705158, 6140.662263575547, 6148.631082135086, 6157.476614487819, 6244.531660125418, 6305.558610897193, 6305.968470157712, 6325.462335701425, 6081.239845019372, 6114.694265286718, 6119.807225076679, 6132.386761323832, 6192.721327540272, 6113.7153036952495, 6178.66017369049, 6183.5671502994355, 6186.0, 6192.8761424771255, 4959.20652695567, 4961.677490963076, 4969.180085285887, 4973.154563724175, 5017.0, 4073.1853629274265, 4441.111777644484, 4446.155230506193, 4471.73169609818, 4479.932801007987, 5459.29029226206, 5496.395396506381, 5615.831525054255, 5680.857978550569, 5694.0, 6273.341299163613, 6276.995680691068, 6290.370962903765, 6297.622142671455, 6311.842203944938, 5668.461496157858, 5693.886122277614, 5695.060315048054, 5702.7433765480655, 5712.514436272963, 5272.657277276965, 5274.103402421598, 5325.760340784596, 5379.112446446371, 5381.865453363619, 6225.688715564294, 6251.12484252205, 6258.0, 6290.496760259077, 6318.336574659686, 6201.379862013762, 6286.905696414649, 6288.465480434214, 6290.0879372491645, 6295.244570651551, 6261.405166509175, 6286.371362863677, 6292.748290068346, 6296.590721603081, 6298.937010629835, 4957.305977163128, 6065.939340606714, 6082.026875699867, 6193.969030154821, 6199.442050215503, 6176.135341052346, 6241.095041219089, 6250.218722659668, 6269.717862696099, 6294.118823364641, 4559.17934771731, 4965.0, 5492.615516913777, 6115.816525504242, 6132.049532322497, 5880.029795083763, 5979.073243647242, 5991.580589358878, 6096.237970253718, 6104.572679912541, 5828.125781132694, 5876.529877609866, 5907.17299578068, 5918.023526118314, 5959.582829201914, 6022.09668549721, 6095.652562707799, 6096.466741106985, 6096.568695548639, 6121.47967422774, 5899.46904778572, 5919.348871624204, 5923.555733319932, 5929.970350148222, 5948.4943779779205], "value": 5635.235245241342, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.99505983093655, 510.9744512774362, 523.9677834665707, 530.7929907336137, 556.4741319453117, 29687.406251874978, 30176.54735178974, 41606.0, 41989.58010419895, 42002.159956800846, 41337.58662413382, 43493.56506434942, 43609.78195109028, 43714.0, 43720.78139609298, 9560.235181185506, 9746.171575416098, 9755.853662195073, 9757.804843903117, 9767.951160244189, 9556.23550115989, 9666.0, 9691.95154024229, 9694.0, 9715.61137554497, 9694.0, 9755.268354873377, 9770.511474426286, 9793.26550508711, 9806.803863922718, 9661.710148695514, 9720.368176068532, 9730.270229732798, 9730.805383892353, 9743.756406089833, 9044.909550904538, 9598.0, 9651.662191823252, 9661.227101831837, 9681.612735490571, 36639.63360366416, 40437.393439098436, 40555.98610034743, 40598.79700601478, 40601.79699101485, 3210.4542227821325, 3347.0795531228864, 3428.4171690812373, 3447.241606846515, 3615.3130905128205, 28565.000224992233, 28967.710322896924, 29007.70992290071, 29287.70712292871, 29370.853145734134, 2448.9755102448926, 2453.3130723397476, 2453.6319552067152, 2512.56030194717, 2615.843049417041, 388653.0, 389040.0, 389812.0, 390225.0, 390259.0487047575, 35406.0, 61504.0, 61858.69070654618, 61885.0, 61901.0, 32711.83644081812, 35691.82154089213, 36579.26841463209, 36657.266854662754, 36674.44988325177, 15106.924465377602, 15458.768118478232, 15468.994515356466, 15479.5356139316, 15486.690266194613, 12939.870601294055, 13006.089573729938, 13040.0, 13053.0, 13060.608181754562, 8249.35012997391, 8249.628766705513, 8264.669413223524, 8344.748287756764, 8347.415680902279, 3401.931961360759, 3417.0, 3417.3425888444085, 3432.742544309137, 3455.1362159460136, 812.7155495576501, 914.9451032938046, 958.1089586684311, 1061.1086687182803, 1102.3771569063454, 29923.251918702208, 30632.017837681404, 31117.17683759021, 31492.842535787175, 31729.8413507931, 1054.2567489919588, 1063.5958335832333, 1066.6373433032807, 1072.3512275073624, 1134.9205555611193, 14711.485098021518, 14946.177960212293, 14994.325255363536, 15174.165420901956, 15251.771223431657, 663011.0, 665269.0, 669961.0, 672041.4785482484, 673473.0, 57704.422955770744, 58198.4180158193, 58224.0, 58242.0, 58264.70867645634, 1272.2748033620746, 1354.0927578522414, 1430.5851303122395, 1451.6951440197465, 1501.159350763576, 23695.644565331877, 29365.853170734008, 29510.704892951653, 29711.55432668555, 30430.391392172474, 50358.49641503685, 50560.69112743256, 51369.486305136474, 51483.0, 51524.22713659223, 512564.0, 518223.0, 518434.0, 518837.0, 519311.0, 18122.45632631023, 25415.87292063528, 26245.95016199407, 26451.867740661175, 26499.0, 44233.0, 44310.46119235096, 44538.0, 45075.873103172686, 45648.31527527023, 30427.695723042485, 35427.468587971714, 37034.33345499513, 37830.0, 37963.58327750516, 29952.101436956928, 35709.64290357063, 36084.63915360813, 36286.455703165004, 36854.0, 36749.26501469902, 37076.887693369245, 37176.44235336526, 37336.81331593324, 38335.80832095822, 28901.7109828899, 32101.839490802395, 32440.188995275308, 33219.833900830345, 33243.50134748029, 24833.751662483144, 24842.627360589246, 25010.499790004462, 25335.113271035425, 25569.0], "value": 18260.871778393852, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.62109443292786, 389.1904837937091, 391.2429449016152, 392.14121074846094, 411.9485064366954, 18142.274309027638, 20455.284065057727, 21500.677489837664, 21564.13743450262, 21603.783962160374, 20146.39560813178, 20231.49421264469, 21588.784112158875, 21861.234856780015, 21869.453263668416, 5701.287339082614, 6030.849228769272, 6034.818955431322, 6040.456358927696, 6042.06348016056, 5823.621464604808, 5891.410858914117, 5938.6436813791315, 5996.340402555716, 6002.249718785152, 5720.056190728525, 5864.794732184374, 5869.7652093916195, 5895.115732640117, 5908.202392676976, 5673.177389278531, 5813.941860581383, 5820.534357251409, 5837.328707198684, 5885.49973252272, 4951.603871690256, 5085.821996230115, 5110.412302585213, 5111.795528178868, 5138.254953031789, 26033.609495857578, 26264.8686756565, 26749.598756018677, 26798.0, 26808.32979175536, 2089.226986015169, 2111.926082587102, 2275.306031660343, 2387.1663708807328, 2664.5736682130764, 11418.828717569244, 14882.330295136744, 15229.695406091816, 15390.384384624602, 15657.843421565867, 1739.9826001739948, 1764.7176451767784, 1774.290283886443, 1797.973030404545, 1931.7005864091088, 120801.79198207993, 124410.0, 124936.0, 126784.83037924033, 130410.0, 10679.359238445719, 11242.100631949424, 11274.774504509864, 11287.0, 11490.0, 13981.440742370394, 14041.85958140426, 14059.648508787363, 14070.226137562533, 14096.295185240697, 7303.8539229216185, 8832.337574681924, 8882.022977472472, 8920.286377089817, 9053.321000924958, 9007.684731034382, 9016.549172541347, 9031.683891063782, 9122.133397327243, 9145.993940666522, 6634.497602197966, 6696.83257918556, 6698.933010669831, 6767.763128290486, 6875.174979002451, 1877.934272300463, 1924.200872184484, 1924.3841970569283, 1932.3140285198833, 1972.8421726261865, 756.4137793210197, 775.9146493885668, 913.949732764691, 920.7836158502746, 932.7061975477703, 15121.848781512044, 18601.627967440847, 20367.08348124338, 21002.78997210039, 21957.341279761633, 879.1077056787366, 951.3055469507299, 991.8065977134389, 1032.8347464405658, 1075.9377130600506, 10783.406912619881, 10945.273631840688, 11028.172887033505, 11113.666590002313, 11718.238314509701, 631071.0, 636639.0, 637045.0, 648995.0, 651988.0, 90322.90168641381, 105519.0, 142996.0, 147499.26250368895, 149041.03835846446, 974.449436068619, 975.770693886936, 1016.4968340671415, 1019.4494972714651, 1027.686555600549, 18219.45341639753, 18805.811941880955, 18926.52683682919, 18965.130368442573, 19109.331173408864, 49542.50457495379, 50829.0, 51539.742301289756, 52490.737546312026, 52936.94126117533, 81239.0, 82821.58589207016, 84041.0, 85880.0, 86312.27375452581, 7172.497925145399, 7605.277498637621, 7645.885311720217, 7862.52824830512, 8703.651853925772, 9334.71995840126, 12563.685907852378, 12579.0, 13295.401706923018, 13499.797503037265, 10481.58073677075, 11238.66284011481, 11597.594084207012, 11669.183157179255, 11742.17804753659, 10756.623518176826, 10893.183011274028, 11029.944850275697, 11188.384638844942, 11296.322220666787, 9578.952105239428, 11470.025047871024, 11556.47995840206, 11813.881861181279, 11894.0, 7103.325184107708, 7502.174760776419, 7612.695492180251, 8098.380323935119, 8811.735647930573, 8505.277051450696, 10763.461826908466, 11339.262947908359, 11459.885401145882, 11675.0], "value": 9790.196835090263, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.9858601413982, 1449.3912556726175, 1570.835062318457, 1766.7526546283514, 1927.7108433734938, 7534.3972482201425, 8000.0799908010595, 8001.8799718004275, 8030.357571394289, 8031.116577176514, 8076.0, 8409.327253819694, 8417.78955526112, 8522.57387130644, 8533.871991920127, 1985.632657958275, 2020.7272018277492, 2296.402935236842, 2364.255259593231, 2365.0185173153122, 2319.7332306784683, 2344.5193735284224, 2362.7400985891536, 2366.4202270443766, 2369.2892132360307, 2318.6290193569034, 2328.4528135888054, 2350.341904266808, 2365.727941286757, 2368.549975504657, 2306.6655334976417, 2313.4910319729634, 2347.565700345433, 2350.6944097267415, 2351.682522859426, 2362.8582285062953, 2387.3434805428647, 2390.5577468168444, 2392.557376885282, 2394.5809483340345, 8438.53588052663, 8504.31965442763, 8509.95745021277, 8512.574371281411, 8526.190011948855, 5093.617978651579, 5148.871278218037, 5287.894242115136, 5301.6818990860675, 5324.54741346986, 3248.5776849009476, 3330.317284956578, 3362.9327413451592, 3396.3207358528393, 3399.201187720884, 968.6464440479244, 973.6640858903667, 982.2387649571567, 986.9654562090292, 988.461288597716, 11163.0, 11228.831567526411, 11282.774344513064, 11284.0, 11285.210035297532, 4379.255526560492, 4501.18978583858, 4577.633789296849, 4625.352450656911, 4634.2585186370015, 8845.69040083594, 8878.86681699775, 8884.37809353352, 8916.465012099296, 8922.241609463139, 4181.456410666594, 5130.4356520782685, 5200.609954253446, 5209.244659524347, 5210.47895210476, 4071.796410179479, 4280.422143010654, 4846.709197448165, 4902.142125128088, 4917.754112294371, 3699.7780133191554, 3727.161388687578, 3752.924941501209, 3790.0524868782804, 3815.6565909068318, 1167.1538134852165, 1181.7931861924128, 1192.8687844337117, 1204.7771162334996, 1205.6443349212004, 852.9892077887599, 865.0830120072743, 888.3293113699245, 902.309733054214, 930.2883294279882, 5675.57433192504, 5788.247527821356, 5862.970685146547, 5967.403259674084, 6019.067044608093, 855.4781583234222, 955.1117460761419, 965.7392504023874, 972.8054389122204, 1024.8565200871883, 3874.7093967952515, 3956.050547868548, 4004.7797371144866, 4044.1102957349335, 4064.227796718614, 8417.831643367099, 10366.170706343475, 10402.075616011061, 10529.789404212026, 10533.157347412189, 8750.299976001905, 10425.322354046963, 10481.895181048245, 10509.579616815241, 10584.312019718695, 7746.612669366509, 8063.0, 8067.596620168968, 8088.919110808934, 8099.190080991853, 5662.122371032497, 5745.3105627324985, 5823.621464604787, 5892.0, 6044.063170208625, 9514.096160864983, 9674.95162524211, 9810.75473113178, 9816.558254878404, 9856.457894815567, 9770.951145244228, 9911.355761875455, 9929.702108936743, 9931.205503559842, 10035.849462257926, 5963.0, 6690.799276021728, 6918.481113916577, 6927.3419025192525, 7011.754588589375, 7632.542047476947, 7763.14605393395, 7987.36101111898, 8073.031236251452, 8185.222403871623, 7005.299470052954, 7048.964755176191, 7071.82320441993, 7243.239459856639, 7399.741009064657, 7052.118485189351, 7156.928430715626, 7201.3158749918675, 7278.927210728037, 7299.087614048244, 6855.828604284933, 6867.725290988305, 6948.722051117898, 6950.826229344307, 6963.512554121163, 4646.605038571624, 4798.424189097331, 4799.016201678717, 4847.830325938575, 5060.873478163076, 4858.975705121452, 4914.70511769295, 4932.4081110267, 4974.403071631427, 5142.742862856768], "value": 4442.907912252128, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.9097566217966, 318.6733598061986, 320.17554796399264, 325.71499937554637, 329.2066120649236, 5144.356955380577, 5272.4727527247305, 5282.418933917275, 5289.709066001373, 5324.946750532494, 4647.442306923165, 4693.131770622437, 4781.0, 5212.921806172911, 5233.921491177626, 3022.926860964359, 3129.7965632233945, 3769.9434508482395, 3921.2157568486273, 4040.6867767975377, 3921.1765529238896, 3980.765962551604, 3996.240714264295, 3996.6403023727858, 4075.1645912587846, 3874.4622272411616, 3977.2840888640035, 3982.3230050891416, 3993.460882780831, 4029.798510074499, 2842.6873043965147, 3840.3501583907014, 4014.0767623446773, 4024.5975402459812, 4043.9797801010805, 3494.5631796025496, 3495.00784552805, 3502.2820321833965, 3516.9800757780467, 3529.400001999666, 5053.444121146671, 5059.696418214919, 5061.342025536656, 5085.262636917626, 5094.31226784383, 3996.460477835484, 4112.773797441139, 4471.6646251531265, 4586.770661466913, 4615.630749540029, 4771.785269662839, 5091.8981620367385, 5225.268462415265, 5410.377806552257, 5438.53772429344, 3243.448613735647, 3307.834608269577, 3653.0684675407865, 3807.9619203807883, 3823.9044023899346, 5078.746062696888, 5126.897462050738, 5130.102232109366, 5287.603429742785, 5740.311162660466, 3640.199156185635, 3839.6928245740273, 3840.9629400062386, 3856.341773037621, 3881.223755248905, 3275.4922986937063, 3918.1967696622123, 3926.0, 3926.3521518949055, 3926.7630696330566, 4484.775761211927, 4504.594586487232, 4562.087582483517, 4589.747563884019, 4593.6554758392585, 4487.304467807495, 5004.199328107558, 5188.45521220274, 5272.894542109137, 5531.557475401958, 1108.8336749487642, 1239.8016317389174, 1241.3110723548518, 1250.4873002069107, 1254.4292346982284, 693.6185098195972, 731.6744048898189, 772.7449941519285, 810.2828996338166, 855.619249433996, 4216.388623649554, 4649.279361698942, 5078.324153029492, 5082.542571168613, 5090.720010399389, 4847.95152048482, 4997.600191984632, 5494.15945658201, 5557.41647127054, 5600.691961942132, 4074.511058672919, 4443.0, 4748.620110391161, 5047.798088076436, 5082.33929589151, 2420.8547487150827, 2444.804415646744, 2501.8999240030194, 2691.461707658476, 2801.0196431249306, 4729.905401891943, 4797.160496913027, 5113.463086375951, 5213.1943681113, 5323.467653234723, 5010.571986983692, 5356.8660783479945, 5394.439669817902, 5511.622094476381, 5809.738561764731, 4931.408231012229, 5819.6508209507565, 5835.67903765297, 5889.852753681106, 5909.91135132982, 5417.593680474059, 5671.427000989351, 5682.687452190169, 5693.4875861172695, 5758.510526605118, 4745.4542727585385, 5054.898902022012, 5252.921206181987, 5742.19609254713, 5754.251947246831, 5706.404964872104, 5895.7052147393315, 5908.020813027512, 5961.731722072604, 6038.245219347582, 3409.3351796399465, 3451.4822776583715, 3981.5023122109737, 4010.2555388406136, 4046.6964977626208, 5051.848444546669, 5212.530872221519, 5394.298741163623, 5591.6365436246515, 5628.943710562842, 4636.884077898079, 4878.146324393288, 5405.162199859028, 5503.614746967673, 5565.220869078414, 4428.003699167662, 4781.808727650855, 4833.936533962523, 4839.926646579377, 5300.946990530046, 4548.680882544025, 5369.892602147857, 5372.328458942632, 5395.325584301962, 5395.730213489388, 5222.634415590872, 5372.758225879923, 5386.0, 5499.175123731312, 5573.832785016456, 5430.83707488776, 5432.18517222404, 5479.082321187712, 5485.4788795064405, 5514.80698175562], "value": 3942.5186021347145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.8912068629363, 734.5666057026355, 739.2416587909887, 741.8590467811119, 752.4394326226965, 14839.777403338945, 15617.921910390432, 15631.452899148531, 15641.530754077368, 15648.608784780386, 14220.360083796228, 14294.14235145892, 14298.928505357484, 14313.78529322061, 14321.355539000744, 713.5754226235382, 733.4755649710456, 888.2893685051965, 901.1574178143441, 922.0594993107027, 901.6032945503957, 909.6134142989237, 909.6361455417853, 910.1080940678139, 912.9911447850129, 962.2254085461201, 974.878140232471, 977.946212958287, 981.7692842182081, 981.8036392721449, 751.9887201691924, 969.3795970578835, 971.7473456901186, 973.1339108193713, 973.6446197138065, 697.6337422852991, 719.3597698048741, 719.7948584653401, 720.3480849830911, 721.5454263813816, 14696.191709455976, 14707.926460367737, 14788.926055369762, 14790.778138327934, 14790.926045369812, 12174.513019479298, 12337.0, 12345.753084938251, 12649.810252846215, 12668.303243321614, 10536.209784266133, 10745.462726863625, 10774.29967052147, 11107.166962477766, 11271.09831213501, 3892.2020985697864, 3916.1971795781797, 3927.7054220933546, 3943.3099207638547, 4086.2440448516822, 79222.20777792206, 80327.19672803314, 80535.38929221442, 80638.5968070156, 81434.77847832347, 8985.101489851093, 9186.86219706705, 9494.477803720723, 9551.904480955101, 9689.0, 17122.0, 20399.79600203979, 20674.483137921674, 20682.68975965362, 20701.27545535899, 7093.467989900675, 7179.174394944648, 7281.162666293338, 7382.630868456556, 7431.888521672179, 3973.4635824163947, 4008.579099194601, 4017.216642754693, 4043.1711499142607, 4122.154958233555, 3420.3159368126476, 3503.0366649171683, 3503.8948831535095, 3508.052825737088, 3511.3328467591077, 611.5016261746662, 619.1610367951452, 620.9658468784261, 631.9052142178618, 637.9075034120119, 494.0, 517.3972322244618, 539.4335947255445, 542.9602311573371, 555.555555555554, 7609.809754756065, 7642.847143057108, 7734.651940662684, 7759.7672069838, 7816.804579885435, 486.7128394247411, 515.0086084287731, 548.1230031948929, 549.7526113249054, 555.4615407587368, 3559.0746405934124, 3579.2304654499408, 3589.2821435712963, 3638.326909521747, 3785.981070094687, 89799.0, 90117.54941225384, 96822.03177968271, 97958.51020744993, 99988.0, 81358.59320703358, 84066.0, 84468.57765711132, 85173.57413212894, 86058.0, 75695.0, 76053.09867253141, 76105.23894761203, 76678.0, 76934.0, 7230.602316872622, 7292.343689067797, 7404.111506619241, 7417.332440080419, 7468.1785003648465, 45382.77308613436, 45877.85305367393, 46064.0, 46462.6061218164, 47982.0, 38514.80742596269, 51938.74030629823, 61020.08469873044, 65917.68164636585, 66078.33921660722, 9267.53662316895, 9446.811063778548, 9567.569459374485, 9804.558794854105, 9841.45871977048, 10367.377957322586, 10425.322354046963, 10535.05184533365, 10618.150547955991, 10656.520456579628, 9940.204783617148, 9982.35114717541, 10012.749681258027, 10015.048570385801, 10217.846732298873, 8094.433389662667, 10101.949490252502, 10155.593776248867, 10260.897391025994, 10299.330543514647, 9805.509724513604, 9822.361546499455, 9830.213582913493, 9869.457179855177, 9941.30410871232, 7979.84040319202, 8246.134155913544, 8308.87536686938, 8314.459560128573, 8333.833323333378, 8360.540170290693, 8419.70531031411, 8635.654573817228, 8853.468791872509, 8885.733427997171], "value": 6638.275937066302, "unit": "score"}], "name": "dromaeo_css", "value": 6995.110187220645, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 113.0, 115.0, 161.0, 124.0, 112.0, 111.0, 113.0, 112.0], "value": 118.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 113.0, 143.0, 198.0, 132.0, 168.0, 164.0, 119.0, 166.0, 121.0], "value": 148.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 186.0, 186.0, 186.0, 184.0, 186.0, 183.0, 183.0, 184.0, 186.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 58.0, 126.0, 57.0, 63.0, 57.0, 60.0, 112.0, 56.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 132.0, 80.0, 81.0, 79.0, 101.0, 80.0, 78.0, 100.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 83.0, 86.0, 88.0, 85.0, 70.0, 118.0, 130.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 92.0, 93.0, 93.0, 93.0, 94.0, 92.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 87.0, 87.0, 84.0, 84.0, 84.0, 85.0, 83.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 62.0, 60.0, 60.0, 58.0, 58.0, 122.0, 62.0, 57.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 71.0, 73.0, 77.0, 74.0, 68.0, 67.0, 67.0, 68.0, 68.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 94.0, 104.0, 106.0, 86.0, 81.0, 85.0, 85.0, 86.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 126.0, 156.0, 135.0, 126.0, 138.0, 144.0, 242.0, 158.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 168.0, 170.0, 168.0, 169.0, 166.0, 167.0, 167.0, 166.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 66.0, 65.0, 65.0, 65.0, 66.0, 68.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1497.5, "alertThreshold": 2.0}]} 13:58:08 INFO - Return code: 0 13:58:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:58:08 ERROR - # TBPL SUCCESS # 13:58:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:58:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:58:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:58:08 INFO - 2016-05-09 13:58:08.383 screenresolution[1674:9588] starting screenresolution argv=screenresolution get 13:58:08 INFO - 2016-05-09 13:58:08.386 screenresolution[1674:9588] Display 0: 1600x1200x32@60 13:58:08 INFO - 2016-05-09 13:58:08.392 screenresolution[1675:9591] starting screenresolution argv=screenresolution list 13:58:08 INFO - Available Modes on Display 0 13:58:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:58:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:58:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:58:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:58:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:58:08 INFO - Intel Iris: 13:58:08 INFO - Chipset Model: Intel Iris 13:58:08 INFO - Type: GPU 13:58:08 INFO - Bus: Built-In 13:58:08 INFO - VRAM (Dynamic, Max): 1536 MB 13:58:08 INFO - Vendor: Intel (0x8086) 13:58:08 INFO - Device ID: 0x0a2e 13:58:08 INFO - Revision ID: 0x0009 13:58:08 INFO - Displays: 13:58:08 INFO - Display: 13:58:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:58:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:58:08 INFO - Display Serial Number: 1600x1200 60 13:58:08 INFO - Main Display: Yes 13:58:08 INFO - Mirror: Off 13:58:08 INFO - Online: Yes 13:58:08 INFO - Rotation: Supported 13:58:08 INFO - Return code: 0 13:58:08 INFO - Running post-action listener: _resource_record_post_action 13:58:08 INFO - ##### 13:58:08 INFO - ##### Finished run-tests step (success) 13:58:08 INFO - ##### 13:58:08 INFO - Running post-run listener: _resource_record_post_run 13:58:09 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38012416; Write bytes: 693813760; Read time: 396; Write time: 2003 13:58:09 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 394951168; Write bytes: 389146112; Read time: 29744; Write time: 820 13:58:09 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36197888; Write bytes: 293690368; Read time: 375; Write time: 1149 13:58:09 INFO - Running post-run listener: _upload_blobber_files 13:58:09 INFO - Blob upload gear active. 13:58:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:58:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:58:09 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'] 13:58:09 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 13:58:10 INFO - (blobuploader) - INFO - Open directory for files ... 13:58:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:58:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:58:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:58:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:58:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:58:11 INFO - (blobuploader) - INFO - Done attempting. 13:58:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:58:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:58:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:58:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:58:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:58:13 INFO - (blobuploader) - INFO - Done attempting. 13:58:13 INFO - (blobuploader) - INFO - Iteration through files over. 13:58:13 INFO - Return code: 0 13:58:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:58:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:58:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04b661b6b986aec8beb2be6d8e8256478e8af019e02e0993618b10ee3e3881cae6cc7a689e263203a9cf7d2df2e1bff2e2a98fec90247d81164196dccdc78cd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1659e70d90918fde565c5cc589e41b5756cc33d375fb895db9aef921df8c69cd3c1fbe605bc9b34f2c40ccd66e304f40e53a3d7e0672c467144538d7d2ccc8bf"} 13:58:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:58:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:58:13 INFO - Contents: 13:58:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04b661b6b986aec8beb2be6d8e8256478e8af019e02e0993618b10ee3e3881cae6cc7a689e263203a9cf7d2df2e1bff2e2a98fec90247d81164196dccdc78cd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1659e70d90918fde565c5cc589e41b5756cc33d375fb895db9aef921df8c69cd3c1fbe605bc9b34f2c40ccd66e304f40e53a3d7e0672c467144538d7d2ccc8bf"} 13:58:13 INFO - Running post-run listener: copy_logs_to_upload_dir 13:58:13 INFO - Copying logs to upload dir... 13:58:13 INFO - mkdir: /builds/slave/test/build/upload/logs 13:58:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.613540 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-09 13:58:13.699644) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 13:58:13.703961) ========= 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.XFTLacSiB9/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.5HTNtYWhll/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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/04b661b6b986aec8beb2be6d8e8256478e8af019e02e0993618b10ee3e3881cae6cc7a689e263203a9cf7d2df2e1bff2e2a98fec90247d81164196dccdc78cd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1659e70d90918fde565c5cc589e41b5756cc33d375fb895db9aef921df8c69cd3c1fbe605bc9b34f2c40ccd66e304f40e53a3d7e0672c467144538d7d2ccc8bf"} build_url:https://queue.taskcluster.net/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010928 build_url: 'https://queue.taskcluster.net/v1/task/ZeTJN_ZqRaWS6QmIWsVAdg/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/04b661b6b986aec8beb2be6d8e8256478e8af019e02e0993618b10ee3e3881cae6cc7a689e263203a9cf7d2df2e1bff2e2a98fec90247d81164196dccdc78cd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1659e70d90918fde565c5cc589e41b5756cc33d375fb895db9aef921df8c69cd3c1fbe605bc9b34f2c40ccd66e304f40e53a3d7e0672c467144538d7d2ccc8bf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 13:58:13.730933) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 13:58:13.731245) ========= 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.XFTLacSiB9/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.5HTNtYWhll/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.005081 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 13:58:13.779267) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 13:58:13.782586) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 13:58:13.782913) ========= ========= Total master_lag: 0.17 =========