builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0020 starttime: 1464156899.25 results: success (0) buildid: 20160524211736 builduid: 99f089bccea44beb85aa5d1bb4f26ca4 revision: 242c458c4c61e94c8d12628612ef5e4e83992eaa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 23:14:59.254351) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 23:14:59.254817) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 23:14:59.255093) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 23:14:59.277908) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 23:14:59.278252) ========= 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.wf0NvDgFPd/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.VJe12OirBC/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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-24 23:14:59-- 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% 15.2M=0.001s 2016-05-24 23:14:59 (15.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135940 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 23:14:59.434625) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 23:14:59.434936) ========= 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.wf0NvDgFPd/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.VJe12OirBC/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.026818 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 23:14:59.482928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 23:14:59.483274) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 242c458c4c61e94c8d12628612ef5e4e83992eaa --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 242c458c4c61e94c8d12628612ef5e4e83992eaa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wf0NvDgFPd/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.VJe12OirBC/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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-24 23:14:59,548 truncating revision to first 12 chars 2016-05-24 23:14:59,548 Setting DEBUG logging. 2016-05-24 23:14:59,548 attempt 1/10 2016-05-24 23:14:59,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/242c458c4c61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 23:15:00,721 unpacking tar archive at: fx-team-242c458c4c61/testing/mozharness/ program finished with exit code 0 elapsedTime=1.409840 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 23:15:00.914560) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 23:15:00.915085) ========= script_repo_revision: 242c458c4c61e94c8d12628612ef5e4e83992eaa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 23:15:00.915739) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 23:15:00.916193) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 23:15:00.933539) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-24 23:15:00.933950) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wf0NvDgFPd/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.VJe12OirBC/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 23:15:01 INFO - MultiFileLogger online at 20160524 23:15:01 in /builds/slave/test 23:15:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:15:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:15:01 INFO - {'append_to_log': False, 23:15:01 INFO - 'base_work_dir': '/builds/slave/test', 23:15:01 INFO - 'blob_upload_branch': 'Fx-Team', 23:15:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:15:01 INFO - 'branch': 'Fx-Team', 23:15:01 INFO - 'buildbot_json_path': 'buildprops.json', 23:15:01 INFO - 'config_files': ('talos/mac_config.py',), 23:15:01 INFO - 'default_actions': ('clobber', 23:15:01 INFO - 'read-buildbot-config', 23:15:01 INFO - 'download-and-extract', 23:15:01 INFO - 'populate-webroot', 23:15:01 INFO - 'create-virtualenv', 23:15:01 INFO - 'install', 23:15:01 INFO - 'run-tests'), 23:15:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:15:01 INFO - 'download_minidump_stackwalk': True, 23:15:01 INFO - 'download_symbols': 'ondemand', 23:15:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:15:01 INFO - 'tooltool.py': '/tools/tooltool.py', 23:15:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:15:01 INFO - '/tools/misc-python/virtualenv.py')}, 23:15:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:15:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:15:01 INFO - 'installer_path': 'installer.exe', 23:15:01 INFO - 'log_level': 'info', 23:15:01 INFO - 'log_name': 'talos', 23:15:01 INFO - 'log_to_console': True, 23:15:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:15:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:15:01 INFO - 'opt_config_files': (), 23:15:01 INFO - 'pip_index': False, 23:15:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:15:01 INFO - 'cmd': ('bash', 23:15:01 INFO - '-c', 23:15:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:15:01 INFO - 'enabled': True, 23:15:01 INFO - 'halt_on_failure': False, 23:15:01 INFO - 'name': 'check_screen_resolution'},), 23:15:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:15:01 INFO - 'cmd': ('bash', 23:15:01 INFO - '-c', 23:15:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:15:01 INFO - 'enabled': True, 23:15:01 INFO - 'halt_on_failure': False, 23:15:01 INFO - 'name': 'check_screen_resolution'},), 23:15:01 INFO - 'run_cmd_checks_enabled': True, 23:15:01 INFO - 'sps_profile': False, 23:15:01 INFO - 'sps_profile_interval': 0, 23:15:01 INFO - 'suite': 'dromaeojs-e10s', 23:15:01 INFO - 'system_bits': '32', 23:15:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:15:01 INFO - 'title': 't-yosemite-r7-0020', 23:15:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:15:01 INFO - 'use_talos_json': True, 23:15:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:15:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:15:01 INFO - 'work_dir': 'build'} 23:15:01 INFO - ##### 23:15:01 INFO - ##### Running clobber step. 23:15:01 INFO - ##### 23:15:01 INFO - Running pre-action listener: _resource_record_pre_action 23:15:01 INFO - Running main action method: clobber 23:15:01 INFO - rmtree: /builds/slave/test/build 23:15:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:15:02 INFO - Running post-action listener: _resource_record_post_action 23:15:02 INFO - ##### 23:15:02 INFO - ##### Finished clobber step (success) 23:15:02 INFO - ##### 23:15:02 INFO - ##### 23:15:02 INFO - ##### Running read-buildbot-config step. 23:15:02 INFO - ##### 23:15:02 INFO - Running pre-action listener: _resource_record_pre_action 23:15:02 INFO - Running main action method: read_buildbot_config 23:15:02 INFO - Using buildbot properties: 23:15:02 INFO - { 23:15:02 INFO - "project": "", 23:15:02 INFO - "product": "firefox", 23:15:02 INFO - "script_repo_revision": "production", 23:15:02 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 23:15:02 INFO - "repository": "", 23:15:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 23:15:02 INFO - "buildid": "20160524211736", 23:15:02 INFO - "pgo_build": "False", 23:15:02 INFO - "basedir": "/builds/slave/test", 23:15:02 INFO - "buildnumber": 4, 23:15:02 INFO - "slavename": "t-yosemite-r7-0020", 23:15:02 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 23:15:02 INFO - "platform": "yosemite_r7", 23:15:02 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 23:15:02 INFO - "branch": "fx-team", 23:15:02 INFO - "repo_path": "integration/fx-team", 23:15:02 INFO - "revision": "242c458c4c61e94c8d12628612ef5e4e83992eaa", 23:15:02 INFO - "stage_platform": "macosx64", 23:15:02 INFO - "builduid": "99f089bccea44beb85aa5d1bb4f26ca4", 23:15:02 INFO - "slavebuilddir": "test" 23:15:02 INFO - } 23:15:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 23:15:02 INFO - Running post-action listener: _resource_record_post_action 23:15:02 INFO - ##### 23:15:02 INFO - ##### Finished read-buildbot-config step (success) 23:15:02 INFO - ##### 23:15:02 INFO - ##### 23:15:02 INFO - ##### Running download-and-extract step. 23:15:02 INFO - ##### 23:15:02 INFO - Running pre-action listener: _resource_record_pre_action 23:15:02 INFO - Running main action method: download_and_extract 23:15:03 INFO - mkdir: /builds/slave/test/build/tests 23:15: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')]} 23:15:03 INFO - https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:15:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 23:15:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 23:15:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/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 23:15:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/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 23:15:04 INFO - Downloaded 1199 bytes. 23:15:04 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 23:15:04 INFO - Using the following test package requirements: 23:15:04 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 23:15:04 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:15:04 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 23:15:04 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:15:04 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 23:15:04 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:15:04 INFO - u'jsshell-mac.zip'], 23:15:04 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:15:04 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 23:15:04 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 23:15:04 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:15:04 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 23:15:04 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:15:04 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 23:15:04 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:15:04 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 23:15:04 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:15:04 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 23:15:04 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:15:04 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')]} 23:15:04 INFO - https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:15:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:15:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:15:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/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 23:15:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/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 23:15:08 INFO - Downloaded 18632983 bytes. 23:15:08 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'] 23:15:08 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 23:15:11 INFO - Return code: 0 23:15:11 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 23:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:11 INFO - https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:15:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:15:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:15:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/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 23:15:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/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 23:15:13 INFO - Downloaded 18632983 bytes. 23:15:13 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'] 23:15:13 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 23:15:16 INFO - Return code: 0 23:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:16 INFO - https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:15:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 23:15:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 23:15:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/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 23:15:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/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 23:15:17 INFO - Downloaded 10840359 bytes. 23:15:17 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'] 23:15:17 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 23:15:17 INFO - Return code: 0 23:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:17 INFO - https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 23:15:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:15:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:15:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 23:15:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/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 23:15:21 INFO - Downloaded 110240957 bytes. 23:15:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:15:21 INFO - mkdir: /builds/slave/test/properties 23:15:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:15:21 INFO - Writing to file /builds/slave/test/properties/build_url 23:15:21 INFO - Contents: 23:15:21 INFO - build_url:https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:15:23 INFO - Running post-action listener: _resource_record_post_action 23:15:23 INFO - Running post-action listener: set_extra_try_arguments 23:15:23 INFO - ##### 23:15:23 INFO - ##### Finished download-and-extract step (success) 23:15:23 INFO - ##### 23:15:23 INFO - ##### 23:15:23 INFO - ##### Running populate-webroot step. 23:15:23 INFO - ##### 23:15:23 INFO - Running pre-action listener: _resource_record_pre_action 23:15:23 INFO - Running main action method: populate_webroot 23:15:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:15:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:15:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:15:23 INFO - '--tppagecycles', 23:15:23 INFO - '7'], 23:15:23 INFO - 'tests': ['tsvgm']}}, 23:15:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:15:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:15:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:15:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:15:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:15:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:15:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:15:23 INFO - 'talos_options': ['--disable-e10s'], 23:15:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:15:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:15:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:15:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:15:23 INFO - 'talos_options': ['--disable-e10s'], 23:15:23 INFO - 'tests': ['damp', 'tps']}, 23:15:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:15:23 INFO - 'tests': ['damp', 'tps']}, 23:15:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:15:23 INFO - 'tests': ['dromaeo_dom']}, 23:15:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:15:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:15:23 INFO - 'tests': ['a11yr', 23:15:23 INFO - 'ts_paint', 23:15:23 INFO - 'tpaint', 23:15:23 INFO - 'sessionrestore', 23:15:23 INFO - 'sessionrestore_no_auto_restore', 23:15:23 INFO - 'tabpaint']}, 23:15:23 INFO - 'other-e10s': {'tests': ['a11yr', 23:15:23 INFO - 'ts_paint', 23:15:23 INFO - 'tpaint', 23:15:23 INFO - 'sessionrestore', 23:15:23 INFO - 'sessionrestore_no_auto_restore', 23:15:23 INFO - 'tabpaint']}, 23:15:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:15:23 INFO - 'ts_paint', 23:15:23 INFO - 'tpaint', 23:15:23 INFO - 'sessionrestore', 23:15:23 INFO - 'sessionrestore_no_auto_restore', 23:15:23 INFO - 'tabpaint']}, 23:15:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:15:23 INFO - 'ts_paint', 23:15:23 INFO - 'tpaint', 23:15:23 INFO - 'sessionrestore', 23:15:23 INFO - 'sessionrestore_no_auto_restore', 23:15:23 INFO - 'tabpaint']}, 23:15:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:15:23 INFO - 'tests': ['a11yr', 23:15:23 INFO - 'ts_paint', 23:15:23 INFO - 'tpaint', 23:15:23 INFO - 'sessionrestore', 23:15:23 INFO - 'sessionrestore_no_auto_restore', 23:15:23 INFO - 'tabpaint']}, 23:15:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:15:23 INFO - 'tests': ['a11yr', 23:15:23 INFO - 'ts_paint', 23:15:23 INFO - 'tpaint', 23:15:23 INFO - 'sessionrestore', 23:15:23 INFO - 'sessionrestore_no_auto_restore', 23:15:23 INFO - 'tabpaint']}, 23:15:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:15:23 INFO - 'tests': ['tsvgx', 23:15:23 INFO - 'tsvgr_opacity', 23:15:23 INFO - 'tart', 23:15:23 INFO - 'tscrollx', 23:15:23 INFO - 'cart']}, 23:15:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:15:23 INFO - 'tsvgr_opacity', 23:15:23 INFO - 'tart', 23:15:23 INFO - 'tscrollx', 23:15:23 INFO - 'cart']}, 23:15:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:15:23 INFO - 'talos_options': ['--disable-e10s'], 23:15:23 INFO - 'tests': ['tp5o']}, 23:15:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:15:23 INFO - 'tests': ['tp5o']}, 23:15:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:15:23 INFO - 'talos_options': ['--disable-e10s', 23:15:23 INFO - '--xperf_path', 23:15:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:15:23 INFO - 'tests': ['tp5n']}, 23:15:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:15:23 INFO - 'talos_options': ['--xperf_path', 23:15:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:15:23 INFO - 'tests': ['tp5n']}}, 23:15:23 INFO - 'talos.zip': {'path': '', 23:15:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:15:23 INFO - Running post-action listener: _resource_record_post_action 23:15:23 INFO - ##### 23:15:23 INFO - ##### Finished populate-webroot step (success) 23:15:23 INFO - ##### 23:15:23 INFO - ##### 23:15:23 INFO - ##### Running create-virtualenv step. 23:15:23 INFO - ##### 23:15:23 INFO - Running pre-action listener: _resource_record_pre_action 23:15:23 INFO - Running main action method: create_virtualenv 23:15:23 INFO - Creating virtualenv /builds/slave/test/build/venv 23:15:23 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 23:15:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:15:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:15:23 INFO - Using real prefix '/tools/python27' 23:15:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:15:23 INFO - Installing distribute.............................................................................................................................................................................................done. 23:15:26 INFO - Installing pip.................done. 23:15:26 INFO - Return code: 0 23:15:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:15:26 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')]} 23:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:26 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')]} 23:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:26 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 0x1052a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a29d915a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105146d30>, '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 0x104f6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10513db20>, '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.wf0NvDgFPd/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.VJe12OirBC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 23:15:26 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 23:15:26 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 23:15:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wf0NvDgFPd/Render', 23:15:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:26 INFO - 'HOME': '/Users/cltbld', 23:15:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:26 INFO - 'LOGNAME': 'cltbld', 23:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:26 INFO - 'MOZ_NO_REMOTE': '1', 23:15:26 INFO - 'NO_EM_RESTART': '1', 23:15:26 INFO - 'PAGER': '/bin/cat', 23:15:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:26 INFO - 'PWD': '/builds/slave/test', 23:15:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:26 INFO - 'SHELL': '/bin/bash', 23:15:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJe12OirBC/Listeners', 23:15:26 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 23:15:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:26 INFO - 'USER': 'cltbld', 23:15:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:26 INFO - 'XPC_FLAGS': '0x0', 23:15:26 INFO - 'XPC_SERVICE_NAME': '0', 23:15:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:27 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 23:15:27 INFO - Cleaning up... 23:15:27 INFO - Return code: 0 23:15:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a29d915a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105146d30>, '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 0x104f6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10513db20>, '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.wf0NvDgFPd/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.VJe12OirBC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 23:15:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:15:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wf0NvDgFPd/Render', 23:15:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:27 INFO - 'HOME': '/Users/cltbld', 23:15:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:27 INFO - 'LOGNAME': 'cltbld', 23:15:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:27 INFO - 'MOZ_NO_REMOTE': '1', 23:15:27 INFO - 'NO_EM_RESTART': '1', 23:15:27 INFO - 'PAGER': '/bin/cat', 23:15:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:27 INFO - 'PWD': '/builds/slave/test', 23:15:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:27 INFO - 'SHELL': '/bin/bash', 23:15:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJe12OirBC/Listeners', 23:15:27 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 23:15:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:27 INFO - 'USER': 'cltbld', 23:15:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:27 INFO - 'XPC_FLAGS': '0x0', 23:15:27 INFO - 'XPC_SERVICE_NAME': '0', 23:15:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:27 INFO - Downloading/unpacking psutil>=3.1.1 23:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:15:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:15:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:15:29 INFO - Installing collected packages: psutil 23:15:29 INFO - Running setup.py install for psutil 23:15:30 INFO - building 'psutil._psutil_osx' extension 23:15:30 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 23:15:30 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 23:15:30 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 23:15:30 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 23:15:30 INFO - building 'psutil._psutil_posix' extension 23:15:30 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 23:15:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:15:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:15:30 INFO - ^ 23:15:30 INFO - 1 warning generated. 23:15:30 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 23:15:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:15:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:15:31 INFO - Successfully installed psutil 23:15:31 INFO - Cleaning up... 23:15:31 INFO - Return code: 0 23:15:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:15: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')]} 23:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15: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')]} 23:15:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a29d915a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105146d30>, '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 0x104f6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10513db20>, '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.wf0NvDgFPd/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.VJe12OirBC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 23:15: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:15: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 mozsystemmonitor==0.3 23:15:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wf0NvDgFPd/Render', 23:15:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:31 INFO - 'HOME': '/Users/cltbld', 23:15:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:31 INFO - 'LOGNAME': 'cltbld', 23:15:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:31 INFO - 'MOZ_NO_REMOTE': '1', 23:15:31 INFO - 'NO_EM_RESTART': '1', 23:15:31 INFO - 'PAGER': '/bin/cat', 23:15:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:31 INFO - 'PWD': '/builds/slave/test', 23:15:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:31 INFO - 'SHELL': '/bin/bash', 23:15:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJe12OirBC/Listeners', 23:15:31 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 23:15:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:31 INFO - 'USER': 'cltbld', 23:15:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:31 INFO - 'XPC_FLAGS': '0x0', 23:15:31 INFO - 'XPC_SERVICE_NAME': '0', 23:15:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:15: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 23:15: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 23:15: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 23:15: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 23:15: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 23:15: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 23:15:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:15:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:15:33 INFO - Installing collected packages: mozsystemmonitor 23:15:33 INFO - Running setup.py install for mozsystemmonitor 23:15:34 INFO - Successfully installed mozsystemmonitor 23:15:34 INFO - Cleaning up... 23:15:34 INFO - Return code: 0 23:15:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:15:34 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')]} 23:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:34 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')]} 23:15:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:34 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 0x1052a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a29d915a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105146d30>, '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 0x104f6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10513db20>, '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.wf0NvDgFPd/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.VJe12OirBC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 23:15:34 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 23:15:34 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 23:15:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wf0NvDgFPd/Render', 23:15:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:34 INFO - 'HOME': '/Users/cltbld', 23:15:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:34 INFO - 'LOGNAME': 'cltbld', 23:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:34 INFO - 'MOZ_NO_REMOTE': '1', 23:15:34 INFO - 'NO_EM_RESTART': '1', 23:15:34 INFO - 'PAGER': '/bin/cat', 23:15:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:34 INFO - 'PWD': '/builds/slave/test', 23:15:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:34 INFO - 'SHELL': '/bin/bash', 23:15:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJe12OirBC/Listeners', 23:15:34 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 23:15:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:34 INFO - 'USER': 'cltbld', 23:15:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:34 INFO - 'XPC_FLAGS': '0x0', 23:15:34 INFO - 'XPC_SERVICE_NAME': '0', 23:15:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:34 INFO - Downloading/unpacking blobuploader==1.2.4 23:15: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 23:15: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 23:15: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 23:15: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 23:15: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 23:15: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 23:15:36 INFO - Downloading blobuploader-1.2.4.tar.gz 23:15:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:15:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:15:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:38 INFO - Downloading docopt-0.6.1.tar.gz 23:15:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:15:38 INFO - Installing collected packages: blobuploader, requests, docopt 23:15:38 INFO - Running setup.py install for blobuploader 23:15:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:15:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:15:38 INFO - Running setup.py install for requests 23:15:38 INFO - Running setup.py install for docopt 23:15:38 INFO - Successfully installed blobuploader requests docopt 23:15:38 INFO - Cleaning up... 23:15:38 INFO - Return code: 0 23:15:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:39 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 0x1052a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a29d915a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105146d30>, '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 0x104f6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10513db20>, '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.wf0NvDgFPd/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.VJe12OirBC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 23:15:39 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 23:15:39 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 23:15:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wf0NvDgFPd/Render', 23:15:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:39 INFO - 'HOME': '/Users/cltbld', 23:15:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:39 INFO - 'LOGNAME': 'cltbld', 23:15:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:39 INFO - 'MOZ_NO_REMOTE': '1', 23:15:39 INFO - 'NO_EM_RESTART': '1', 23:15:39 INFO - 'PAGER': '/bin/cat', 23:15:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:39 INFO - 'PWD': '/builds/slave/test', 23:15:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:39 INFO - 'SHELL': '/bin/bash', 23:15:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJe12OirBC/Listeners', 23:15:39 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 23:15:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:39 INFO - 'USER': 'cltbld', 23:15:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:39 INFO - 'XPC_FLAGS': '0x0', 23:15:39 INFO - 'XPC_SERVICE_NAME': '0', 23:15:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:15:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Yc0fFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:15:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-EChO2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:15:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-W78z1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:15:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-WzgmTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:15:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-l4Mvna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:15:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-dAorqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:15:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-o2loAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:15:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-w7MOy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:15:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-HFuT60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:15:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-dO_Nwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:15:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-brDz3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:15:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-NmnjZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:15:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-ZSSGXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:15:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-ZmREqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-jO_pCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:15:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-SpZFE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:15:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:15:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-wGa1Gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:15:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:15:41 INFO - Running setup.py install for manifestparser 23:15:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:15:41 INFO - Running setup.py install for mozcrash 23:15:41 INFO - Running setup.py install for mozdebug 23:15:41 INFO - Running setup.py install for mozdevice 23:15:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:15:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:15:41 INFO - Running setup.py install for mozfile 23:15:41 INFO - Running setup.py install for mozhttpd 23:15:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:15:41 INFO - Running setup.py install for mozinfo 23:15:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:15:42 INFO - Running setup.py install for mozInstall 23:15:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:15:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:15:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:15:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:15:42 INFO - Running setup.py install for mozleak 23:15:42 INFO - Running setup.py install for mozlog 23:15:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:15:42 INFO - Running setup.py install for moznetwork 23:15:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:15:42 INFO - Running setup.py install for mozprocess 23:15:42 INFO - Running setup.py install for mozprofile 23:15:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:15:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:15:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:15:42 INFO - Running setup.py install for mozrunner 23:15:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:15:43 INFO - Running setup.py install for mozscreenshot 23:15:43 INFO - Running setup.py install for moztest 23:15:43 INFO - Running setup.py install for mozversion 23:15:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:15:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:15:43 INFO - Cleaning up... 23:15:43 INFO - Return code: 0 23:15:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:15:43 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')]} 23:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:43 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')]} 23:15:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:43 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 0x1052a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a29d915a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105146d30>, '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 0x104f6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10513db20>, '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.wf0NvDgFPd/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.VJe12OirBC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 23:15:43 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 23:15:43 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 23:15:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wf0NvDgFPd/Render', 23:15:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:43 INFO - 'HOME': '/Users/cltbld', 23:15:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:43 INFO - 'LOGNAME': 'cltbld', 23:15:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:43 INFO - 'MOZ_NO_REMOTE': '1', 23:15:43 INFO - 'NO_EM_RESTART': '1', 23:15:43 INFO - 'PAGER': '/bin/cat', 23:15:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:43 INFO - 'PWD': '/builds/slave/test', 23:15:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:43 INFO - 'SHELL': '/bin/bash', 23:15:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJe12OirBC/Listeners', 23:15:43 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 23:15:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:43 INFO - 'USER': 'cltbld', 23:15:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:43 INFO - 'XPC_FLAGS': '0x0', 23:15:43 INFO - 'XPC_SERVICE_NAME': '0', 23:15:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:15:43 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-BeYdDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:15:43 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)) 23:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:15:43 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-lovZe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:15:44 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)) 23:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:15:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Yi6pk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:15:44 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)) 23:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:15:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-KOxJU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:15:44 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)) 23:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:15:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-g8eYDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:15:44 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)) 23:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:15:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-z6s3Vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:15:44 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)) 23:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:15:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-SJmUOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:15:44 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)) 23:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:15:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-wTXry8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:15:44 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)) 23:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:15:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Gd1Rle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:15:44 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)) 23:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:15:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-k4KQfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 23:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:15:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-ylIkSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:15:45 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)) 23:15:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:15:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-xEljM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:15:45 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)) 23:15:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:15:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-FPexYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:15:45 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)) 23:15:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:15:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Ra6OIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:15:45 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)) 23:15:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-kgwBuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:45 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)) 23:15:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:15:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-eL7zZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:15:45 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)) 23:15:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:15:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-O1ePVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:15:45 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)) 23:15:45 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)) 23:15:45 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)) 23:15:45 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)) 23:15:45 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)) 23:15:45 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)) 23:15:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:48 INFO - Downloading blessings-1.6.tar.gz 23:15:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:15:48 INFO - Installing collected packages: blessings 23:15:48 INFO - Running setup.py install for blessings 23:15:48 INFO - Successfully installed blessings 23:15:48 INFO - Cleaning up... 23:15:48 INFO - Return code: 0 23:15:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:15:48 INFO - Reading from file tmpfile_stdout 23:15:48 INFO - Current package versions: 23:15:48 INFO - blessings == 1.6 23:15:48 INFO - blobuploader == 1.2.4 23:15:48 INFO - docopt == 0.6.1 23:15:48 INFO - manifestparser == 1.1 23:15:48 INFO - mozInstall == 1.12 23:15:48 INFO - mozcrash == 0.17 23:15:48 INFO - mozdebug == 0.1 23:15:48 INFO - mozdevice == 0.48 23:15:48 INFO - mozfile == 1.2 23:15:48 INFO - mozhttpd == 0.7 23:15:48 INFO - mozinfo == 0.9 23:15:48 INFO - mozleak == 0.1 23:15:48 INFO - mozlog == 3.2 23:15:48 INFO - moznetwork == 0.27 23:15:48 INFO - mozprocess == 0.22 23:15:48 INFO - mozprofile == 0.28 23:15:48 INFO - mozrunner == 6.11 23:15:48 INFO - mozscreenshot == 0.1 23:15:48 INFO - mozsystemmonitor == 0.3 23:15:48 INFO - moztest == 0.7 23:15:48 INFO - mozversion == 1.4 23:15:48 INFO - psutil == 3.1.1 23:15:48 INFO - requests == 1.2.3 23:15:48 INFO - wsgiref == 0.1.2 23:15:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:48 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 0x1052a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a29d915a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105146d30>, '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 0x104f6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10513db20>, '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.wf0NvDgFPd/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.VJe12OirBC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 23:15:48 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 23:15:48 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 23:15:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wf0NvDgFPd/Render', 23:15:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:48 INFO - 'HOME': '/Users/cltbld', 23:15:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:48 INFO - 'LOGNAME': 'cltbld', 23:15:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:48 INFO - 'MOZ_NO_REMOTE': '1', 23:15:48 INFO - 'NO_EM_RESTART': '1', 23:15:48 INFO - 'PAGER': '/bin/cat', 23:15:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:48 INFO - 'PWD': '/builds/slave/test', 23:15:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:48 INFO - 'SHELL': '/bin/bash', 23:15:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJe12OirBC/Listeners', 23:15:48 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 23:15:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:48 INFO - 'USER': 'cltbld', 23:15:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:48 INFO - 'XPC_FLAGS': '0x0', 23:15:48 INFO - 'XPC_SERVICE_NAME': '0', 23:15:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:48 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)) 23:15:48 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)) 23:15:48 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)) 23:15:48 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)) 23:15:48 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)) 23:15:48 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)) 23:15:48 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)) 23:15:48 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)) 23:15:48 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)) 23:15:48 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)) 23:15:48 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)) 23:15:48 INFO - Cleaning up... 23:15:48 INFO - Return code: 0 23:15:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:48 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 0x1052a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a29d915a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105146d30>, '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 0x104f6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10513db20>, '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.wf0NvDgFPd/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.VJe12OirBC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 23:15:48 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 23:15:48 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 23:15:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wf0NvDgFPd/Render', 23:15:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:48 INFO - 'HOME': '/Users/cltbld', 23:15:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:48 INFO - 'LOGNAME': 'cltbld', 23:15:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:48 INFO - 'MOZ_NO_REMOTE': '1', 23:15:48 INFO - 'NO_EM_RESTART': '1', 23:15:48 INFO - 'PAGER': '/bin/cat', 23:15:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:48 INFO - 'PWD': '/builds/slave/test', 23:15:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:48 INFO - 'SHELL': '/bin/bash', 23:15:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJe12OirBC/Listeners', 23:15:48 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 23:15:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:48 INFO - 'USER': 'cltbld', 23:15:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:48 INFO - 'XPC_FLAGS': '0x0', 23:15:48 INFO - 'XPC_SERVICE_NAME': '0', 23:15:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:49 INFO - Downloading/unpacking jsonschema 23:15:49 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 23:15:49 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 23:15:49 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 23:15:49 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 23:15:49 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 23:15:49 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 23:15:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:15:51 INFO - Downloading/unpacking functools32 (from jsonschema) 23:15: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 23:15: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 23:15: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 23:15: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 23:15: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 23:15: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 23:15:52 INFO - Downloading functools32-3.2.3-2.tar.gz 23:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:15:52 INFO - warning: no files found matching '*.txt' 23:15:52 INFO - no previously-included directories found matching 'build' 23:15:52 INFO - no previously-included directories found matching 'dist' 23:15:52 INFO - no previously-included directories found matching '.git*' 23:15:52 INFO - Installing collected packages: jsonschema, functools32 23:15:52 INFO - Running setup.py install for functools32 23:15:52 INFO - warning: no files found matching '*.txt' 23:15:52 INFO - no previously-included directories found matching 'build' 23:15:52 INFO - no previously-included directories found matching 'dist' 23:15:52 INFO - no previously-included directories found matching '.git*' 23:15:52 INFO - Successfully installed jsonschema functools32 23:15:52 INFO - Cleaning up... 23:15:52 INFO - Return code: 0 23:15:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:52 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 0x1052a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a29d915a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105146d30>, '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 0x104f6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10513db20>, '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.wf0NvDgFPd/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.VJe12OirBC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 23:15:52 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 23:15:52 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 23:15:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wf0NvDgFPd/Render', 23:15:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:52 INFO - 'HOME': '/Users/cltbld', 23:15:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:52 INFO - 'LOGNAME': 'cltbld', 23:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:52 INFO - 'MOZ_NO_REMOTE': '1', 23:15:52 INFO - 'NO_EM_RESTART': '1', 23:15:52 INFO - 'PAGER': '/bin/cat', 23:15:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:52 INFO - 'PWD': '/builds/slave/test', 23:15:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:52 INFO - 'SHELL': '/bin/bash', 23:15:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJe12OirBC/Listeners', 23:15:52 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 23:15:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:52 INFO - 'USER': 'cltbld', 23:15:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:52 INFO - 'XPC_FLAGS': '0x0', 23:15:52 INFO - 'XPC_SERVICE_NAME': '0', 23:15:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:52 INFO - Downloading/unpacking flake8 23:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:15:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:15:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 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 23:15:55 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 23:15:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:15:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:15:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:15:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:15:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:15:56 INFO - no previously-included directories found matching 'docs/_build' 23:15:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:15:56 INFO - Running setup.py install for pep8 23:15:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:15:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:15:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:15:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:15:56 INFO - no previously-included directories found matching 'docs/_build' 23:15:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:15:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:15:56 INFO - Cleaning up... 23:15:56 INFO - Return code: 0 23:15:56 INFO - Running post-action listener: _resource_record_post_action 23:15:56 INFO - Running post-action listener: _start_resource_monitoring 23:15:56 INFO - Starting resource monitoring. 23:15:56 INFO - ##### 23:15:56 INFO - ##### Finished create-virtualenv step (success) 23:15:56 INFO - ##### 23:15:56 INFO - ##### 23:15:56 INFO - ##### Running install step. 23:15:56 INFO - ##### 23:15:56 INFO - Running pre-action listener: _resource_record_pre_action 23:15:56 INFO - Running main action method: install 23:15:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:15:57 INFO - Reading from file tmpfile_stdout 23:15:57 INFO - Detecting whether we're running mozinstall >=1.0... 23:15:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:15:57 INFO - Reading from file tmpfile_stdout 23:15:57 INFO - Output received: 23:15:57 INFO - Usage: mozinstall [options] installer 23:15:57 INFO - Options: 23:15:57 INFO - -h, --help show this help message and exit 23:15:57 INFO - -d DEST, --destination=DEST 23:15:57 INFO - Directory to install application into. [default: 23:15:57 INFO - "/builds/slave/test"] 23:15:57 INFO - --app=APP Application being installed. [default: firefox] 23:15:57 INFO - mkdir: /builds/slave/test/build/application 23:15:57 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'] 23:15:57 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 23:16:30 INFO - Reading from file tmpfile_stdout 23:16:30 INFO - Output received: 23:16:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:16:30 INFO - Running post-action listener: _resource_record_post_action 23:16:30 INFO - ##### 23:16:30 INFO - ##### Finished install step (success) 23:16:30 INFO - ##### 23:16:30 INFO - ##### 23:16:30 INFO - ##### Running run-tests step. 23:16:30 INFO - ##### 23:16:30 INFO - Running pre-action listener: _resource_record_pre_action 23:16:30 INFO - Running main action method: run_tests 23:16:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:16:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:16:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:16:30 INFO - 2016-05-24 23:16:30.563 screenresolution[1633:7804] starting screenresolution argv=screenresolution get 23:16:30 INFO - 2016-05-24 23:16:30.566 screenresolution[1633:7804] Display 0: 1600x1200x32@60 23:16:30 INFO - 2016-05-24 23:16:30.572 screenresolution[1634:7806] starting screenresolution argv=screenresolution list 23:16:30 INFO - Available Modes on Display 0 23:16:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:16:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:16:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:16:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:16:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:16:30 INFO - Intel Iris: 23:16:30 INFO - Chipset Model: Intel Iris 23:16:30 INFO - Type: GPU 23:16:30 INFO - Bus: Built-In 23:16:30 INFO - VRAM (Dynamic, Max): 1536 MB 23:16:30 INFO - Vendor: Intel (0x8086) 23:16:30 INFO - Device ID: 0x0a2e 23:16:30 INFO - Revision ID: 0x0009 23:16:30 INFO - Displays: 23:16:30 INFO - Display: 23:16:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:16:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:16:30 INFO - Display Serial Number: 1600x1200 60 23:16:30 INFO - Main Display: Yes 23:16:30 INFO - Mirror: Off 23:16:30 INFO - Online: Yes 23:16:30 INFO - Rotation: Supported 23:16:30 INFO - Return code: 0 23:16:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:16:30 INFO - Python 2.7.3 23:16:30 INFO - Return code: 0 23:16:30 INFO - grabbing minidump binary from tooltool 23:16:30 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')]} 23:16:30 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105146d30>, '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 0x104f6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10513db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:16:30 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 23:16:30 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 23:16:30 INFO - Calling ['/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'] with output_timeout 600 23:16:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:16:30 INFO - Return code: 0 23:16:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:16:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:16:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:16:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:16:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:16:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:16:32 INFO - Return code: 0 23:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0020 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wf0NvDgFPd/Render', 23:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:16:32 INFO - 'HOME': '/Users/cltbld', 23:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:16:32 INFO - 'LOGNAME': 'cltbld', 23:16:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:16:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:32 INFO - 'MOZ_NO_REMOTE': '1', 23:16:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:16:32 INFO - 'NO_EM_RESTART': '1', 23:16:32 INFO - 'PAGER': '/bin/cat', 23:16:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:32 INFO - 'PWD': '/builds/slave/test', 23:16:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:16:32 INFO - 'SHELL': '/bin/bash', 23:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJe12OirBC/Listeners', 23:16:32 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 23:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:16:32 INFO - 'USER': 'cltbld', 23:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:32 INFO - 'XPC_FLAGS': '0x0', 23:16:32 INFO - 'XPC_SERVICE_NAME': '0', 23:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:16:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:16:32 INFO - mozversion application_buildid: 20160524211736 23:16:32 INFO - mozversion application_changeset: 242c458c4c61e94c8d12628612ef5e4e83992eaa 23:16:32 INFO - mozversion application_display_name: Nightly 23:16:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:16:32 INFO - mozversion application_name: Firefox 23:16:32 INFO - mozversion application_remotingname: firefox 23:16:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:16:32 INFO - mozversion application_vendor: Mozilla 23:16:32 INFO - mozversion application_version: 49.0a1 23:16:32 INFO - mozversion platform_buildid: 20160524211736 23:16:32 INFO - mozversion platform_changeset: 242c458c4c61e94c8d12628612ef5e4e83992eaa 23:16:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:16:32 INFO - mozversion platform_version: 49.0a1 23:16:32 INFO - using testdate: 1464156992 23:16:32 INFO - actual date: 1464156992 23:16:32 INFO - starting webserver on 'localhost:49271' 23:16:32 INFO - SUITE-START | Running 2 tests 23:16:32 INFO - TEST-START | dromaeo_css 23:16:32 INFO - operating with platform_type : mac_ 23:16:32 INFO - Initialising browser for dromaeo_css test... 23:16:32 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpwTcy32/profile http://localhost:49271/getInfo.html) 23:16:35 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 23:16:35 INFO - PROCESS | 1652 | colorDepth:24 23:16:35 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 23:16:35 INFO - PROCESS | 1652 | __metrics 23:16:35 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 23:16:35 INFO - TEST-INFO | 1652: exit 0 23:16:35 INFO - Browser initialized. 23:16:35 INFO - Running cycle 1/1 for dromaeo_css test... 23:16:36 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpwTcy32/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:16:48 INFO - PROCESS | 1655 | RSS: Main: 250097664 23:16:48 INFO - PROCESS | 1655 | 23:17:37 INFO - PROCESS | 1655 | 1464157057774 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] 23:19:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 23:19:49 INFO - PROCESS | 1655 | RSS: Main: 261505024 23:19:49 INFO - PROCESS | 1655 | 23:22:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 23:22:52 INFO - PROCESS | 1655 | RSS: Main: 273629184 23:22:52 INFO - PROCESS | 1655 | 23:25:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 23:25:55 INFO - PROCESS | 1655 | RSS: Main: 276176896 23:25:55 INFO - PROCESS | 1655 | 23:28:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 23:28:57 INFO - PROCESS | 1655 | RSS: Main: 277471232 23:28:57 INFO - PROCESS | 1655 | 23:32:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 23:32:01 INFO - PROCESS | 1655 | RSS: Main: 280494080 23:32:01 INFO - PROCESS | 1655 | 23:35:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 23:35:04 INFO - PROCESS | 1655 | RSS: Main: 281432064 23:35:04 INFO - PROCESS | 1655 | 23:35:04 INFO - PROCESS | 1655 | __start_tp_report 23:35:04 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 23:35:04 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 23:35:04 INFO - PROCESS | 1655 | |i|pagename|runs| 23:35:04 INFO - PROCESS | 1655 | |0;dojo.html;3518.753687241893;3524.929501409972;3531.735119866011;3533.787972721637;3565.429531274995;5810.680412577307;5864.941350586499;5947.910781338283;5952.136940143682;5966.254218222723;5739.311282646088;5843.211166492522;5863.912041319373;5901.557383196256;5968.432998865101;5268.657537260085;5309.123994540896;5382.5963052771;5417.377001644822;5424.810131645393;388.5518061622796;5299.072289874656;5431.321084864392;5439.5920305977015;5500.147477141049;4754.7147171169845;5002.474740152305;5321.707306098163;5355.812546560852;5439;4950.653454258203;4963.230699241587;5273.129933560957;5392.703401312926;5408.702521361323;5265.157574788054;5298.337707786527;5330.626856120073;5338.958903013914;5339.866503337409;5723.685197314146;5739.684317362543;5769.596128271022;5799.101139323411;5832.096025116114;5742.454466825645;6185.938140618581;6190.0405437157315;6295.433410992987;6320.841978950517;6218.4714299284615;6332.556721029529;6345.238571371418;6351.809445716636;6354.809355719336;6033;6035.879282414371;6052.636841789506;6069.362716914705;6082.787102451393;6206.534509911775;6311.990081586924;6327.019312006646;6473.28793832678;6500.739970401226;6242.09489624002;6299.023651334051;6305.779297724557;6311.558190926591;6315.210598675166;6316.905246421307;6332.810015699536;6343.460805831556;6354.682265886687;6368.490520758328;6194.907076393858;6227.626342419469;6252;6260.092286618506;6301.306856245809;6164.845878852975;6215.595986260879;6220.937790622126;6248.50011999039;6260.342664020212;5082.466341034138;5087.31321271631;5087.618428617869;5103.770330335144;5117.1045067113855;4455.509893911667;4484.68607197493;4511.70673906195;4582.1293954148605;4599.08018396322;5898.793542226002;6040.335563088057;6041.395860414012;6057.575969682168;6060.484858786965;6366.6180029198395;6373.012183111625;6379.106925030499;6402.03969404593;6405.775797847146;5840.766369345179;5854.268216472941;5894.764209431661;5913.586048976616;5964.135200396005;5512.476314750093;5518.255035570147;5520.66875987442;5526.336839579196;5530.336359636869;6332.366763323815;6474.546781725234;6494.285628580852;6515.934840651627;6535;6215.005599104122;6231.501479881689;6270.090836828543;6344.048392741126;6366.904496432465;6525;6597.934020659732;6603.867922641424;6610.636414997221;6651.600903945779;6168.19813424252;6187.721552530057;6274.6548939808745;6307.558470906993;6310.5898116622275;6195.256569211723;6289.559730818982;6300.0234963580715;6303.401176888188;6313.1792866928845;6243.157173781481;6267.341929097562;6274.780382686584;6299.212598425197;6300.991841305369;4731.787069581808;6128.142060111499;6141.578063794062;6223.96888015557;6263.906041409291;5638.85902852432;5965.28416590012;6026.789062382795;6089.51283897296;6129.172561704113;6231.137760336316;6231.781887633911;6251.281102673251;6278.340774218641;6303.149074875017;5999.370066142992;6082.148499210026;6132.78535251264;6193.504519638508;6211.440970312694 23:35:04 INFO - PROCESS | 1655 | |1;ext.html;506.3215291509377;511.4297558222581;523.275263759693;530.4986787485827;545.9617826752126;34496.06783423409;42554.36168457472;42586.36120458188;42648.78675606621;42811.3578296325;43398.56601433985;43967.12065758683;43986.90032749175;44059.55940440595;44144.3378349325;9588.376755510886;9824.803503929917;9830.459324737136;9831.852522212137;9838;9698.030196980317;9745.317827752026;9779.657711980082;9793.90206097937;9797.510124493783;9739.805203895918;9771.951140244324;9791.363561368524;9849.261305402088;9864.506774661275;9778.217742580577;9804.950975245149;9855.310128291023;9865.260105492116;9882.357646752975;9625.085616866387;9700.854487182698;9709.320347575673;9745.171660408874;9771.560279787427;39725.40411893824;39763.60236397628;40603.39094913549;40864.79567602173;40879.7956010221;3328.0182346207926;3382.391169589473;3474.5830500339866;3544.3620148373284;3897.3569361055393;29021;29097;29115.85442072776;29148.125556233346;29672.554911676343;2513.1455305196323;2554.131595257603;2698.0287096645193;2708.3635345693747;2716.4023914738727;503281;505068;510145;512932;514836;52898.73550632299;59258.40741592615;59887.40112598818;60147;60272;35540.11149721226;35780;36107.4583881242;38167.80916095457;63901.680491597246;17241.39655112065;17343.739843902353;17398.739018914726;17409.95540267588;17449.7382539262;13105.934470327587;13206.33968301581;13221.933890330487;13226.867731322563;13234.338283085806;19542.120604572545;19973.20107195696;19992.60014799725;19999.200031998556;20041.599168016557;8373.330133589297;8374.246317831425;8381.455205411628;8385.6226469809;8403.243708066304;893.2942975049768;946.7159852044359;953.6471505542994;996.2378780233128;1060.8196606576746;24907.8791454385;26190.86904565465;28345.57481637777;29459.410811783644;29925.85037074801;1009.2632378363688;1086.625114335553;1089.536946797608;1093.824988001916;1146.7534480086822;15300.770488442682;15432.537023889077;15507;15653.776239448103;15663.373465061497;177001;183162;183194;183839;184203;60083;61641;62282.688586556775;62303;62319;1306.7451846889765;1370.520317888751;1408.1551069358509;1423.2172305232082;1436.1239643817264;29186.7081329184;29229.123126306244;29695.554566681087;29983.400331993675;30125.246868827577;50742;52162.47837521576;52302;52445;52537.99999999847;568057;568245;579735;592325;599320;26306;26555.468890622466;26647.20058398251;27051;27106.72893271042;46356;47454.76272618753;47897;48660.270095949294;48845.755771220916;37766.43350349802;38864.80567597144;39245.6075439242;39864.60135398609;40294.59705402909;37612.61486466231;38294.617053829104;38313.76628523285;38684.6131538681;39046;31778.205544861565;37205.069873253386;37209.25581488409;37784;38817.22365552617;33442.33115337631;34225.82887085548;34250.97247082592;34380;34387.96836094921;24752.752472475044;25267.989280428577;25287;25702.485950281265;26618 23:35:04 INFO - PROCESS | 1655 | |2;jquery.html;387.57754048087577;388.53958059699244;389.6434762192594;392.3349921882409;414.42188147534193;14505.92747036266;18564.90717546412;20325.898370508163;20832.68750968735;20907;17379.391721289758;20023.599528009432;20085.39743807688;20511;21216.89391553044;5782.421757824205;5897.587168898168;5972.6715030673295;6149.723262453178;6157.6305421674615;6173.876522469548;6206.069089636568;6217.689115544227;6219.533534984872;6233.594816336924;6108;6154.569180157391;6221.4400703936635;6223.564350495467;6251.249850017982;5442.265294185274;6079.331273559905;6147.907781383283;6275.592086514384;6278.529110316745;5866.501347385477;6062.393760623902;6070.332263451017;6073.939260607382;6085.4827339676185;13514.256715880623;20334.593308133753;24473;25392.36519087019;25590.744092559024;1916.93290734825;2000.7098970649174;2057.845661575388;2237.2952519956325;2446.4617784087473;9490.288228382899;9933.950330248375;10023.749406264902;10225.642102526375;10331.070203681706;1733.2547004787937;1780.5815633326158;1812.5649844037468;1815.5279627296866;1822.4077174918204;83536;121072;125219.3739031308;128274;128452.35773821165;11553.36456494884;11571.826422603668;11696.649100526998;11720.706982325339;11906.583269585697;14392.640183995483;14873.405063797543;14956.40174393012;14968.176750278839;15044.323005464561;8640.697575584825;8779.912200878038;8805.207531322083;8872.556372181365;8886.933567971755;8700;8717.041125476193;8729.91270087291;8831.823363532822;8852.468851868909;4586.4266966629175;4624.1676498230645;4670.276107203389;4675.95324046762;4696.295555666609;1653.4378311374192;1716.5279548124365;1929.922803087889;1962.0974351798047;2007.3676791810533;883.2908322396183;918.4168053286211;931.5901003558413;953.9141477267079;973.704972386724;17604.38384656531;20298.797012029987;20974;21010.844403557952;21166.2591809289;950.8954015058338;952.8427809411509;967.4969016111723;994.0258546624349;1051.8632577764843;8985.081149804208;10711.571537138583;11012.229143960007;11094.278871873303;11096.722581935515;566981;570586;571496;583945;584007;124608.75391246153;124669.37665311797;127507;129619.35190323988;131021;1001.7445551384496;1003.0621369019843;1012.2964539645023;1026.0150255754559;1031.437866362834;17994.820051799314;18056.381824635617;18141;18196.636067278847;18499.167537461115;47813.5218647823;49737;50041.74979125081;51422.485775143265;51667.74166129145;69056;72977;73573.632131839;73992;75848;6983.406410455168;7389.630518473948;7450.813729656585;7802.414818888493;8398.790030249293;9148.954255228682;12139;12746.171498852545;13232.206067635972;13281.933590331986;5222.616006758288;6499;10899.29154604948;10909.781804364027;11413.315201087962;10857.728556785827;10925.453727313761;11097;11187.993080622782;11329.603463878724;6502.024696295404;7199.49603527769;10995.175361848052;11063.557457701601;11246.606368777053;7401.259874012556;7404.925950740424;7476.626168691435;7519.661615227437;7726.690932362642;9075.727728168165;10223.079922806983;11076.612318568812;11127.443627818739;11448.255863368855 23:35:04 INFO - PROCESS | 1655 | |3;mootools.html;1559.243766773115;1614.096106180539;1849.953751156221;1922.0485859499559;1938.670426027577;8138.633761480733;8521.10528394519;8769.123087691236;9296.302777291705;9310.674126405569;8834.690785822497;8930.419522731017;8947.64209431622;9039.096090390974;9071.183593476586;2171.0447403142643;2303.884805759714;2391.8086553075755;2420.8668523231304;2428.7449817769143;2401.3396316013063;2403.7115546134487;2406.290144407404;2410.144398738449;2444.303373538542;2400.003998340695;2404.3147702904675;2411.144043864429;2419.4677171022345;2420.298113547078;2362.6692263083182;2417.2627348658566;2418.2503423938633;2419.9395015124674;2435.6346548017764;2390.1873363056643;2400.939976500584;2409.8674572898485;2415.9128392223392;2427.490227052321;7811.531308121531;9024.23294020009;9088;9092.636294548276;9202.355835091546;5407.891842163135;5489.039418101856;5570.637908535932;5591.049521581341;5599.832005039855;3601.7118630509494;3614.1868079682126;3619.565652121736;3686.3364594373;3690.2435000825035;1031.443020768784;1034.8499467577235;1039.0129377091794;1042.166266986408;1046.9005444482762;12082.6375208743;12299.016078713681;12347.938260308641;12355.25868447887;12358;4871.610271178297;4871.707697538159;4990.051890140861;4991.176455884738;4999.125153098266;10054.447005414626;10293.55890175376;10309.07218350352;10322.12261957727;10329;5417.024935511567;5426.972865135649;5441.5102640762525;5470.589705772083;5547.362053363834;4442.600165985077;4832.63755218353;4924.926126108111;5039.596832253411;5048.066107770074;4070.8778736637355;4086.5096188457574;4086.884653644166;4105.404716316118;4120.773357465308;1312.061875758837;1321.973560528784;1324.1591589340837;1330.1819381080759;1332.4403750424844;822.7490615362252;886.9512176830336;915.8900931888082;917.655879045358;925.9537023148815;5722.170285308607;5754.913676294859;5844.678542680108;5885.293764748258;5887.116932460165;456.9588737013685;460.75810199645457;462.44506592088953;944.3059351376733;1005.7787286796893;3947.467091942551;4001.3591029920112;4044.757314561077;4083.224187404384;4096.446979657768;10765.461726913623;10813.134949204044;10972.23194376386;10986.285891417032;11149.498272577754;10961.890381096246;11029.779404411867;11075.16936229786;11098.334099954029;11098.722531936766;8133.430659853753;8318.209770071833;8333.125021872738;8444;8700.78248043804;5741.25363702716;5849.298084229919;5940.8811823764145;6006.729697163551;6008.098785182257;10131.544080516247;10219.48902554884;10244.64143754965;10323.122534584645;10340.069393754598;10277.897221027693;10338.328008679411;10486.423246721504;10570.312929659547;10680.946595266974;6610.900836487361;6995.635851009004;7248.275172482709;7250.709971601077;7421.591812450367;8515.616797244264;8581.65673373058;8602;8664.610092545976;8667.609957551798;7521.924780752122;7674.309312161932;7707.884381734167;7762.223777622193;7795.610219488889;7443.292887175709;7488.850222995618;7513.812154696177;7577.355924746458;7727.768166955;7078.504504684623;7121.252268511732;7145.071140751669;7166.641667916688;7172.641367931687;4779.593734532604;4920.40955085392;4994.575461085703;5005.549500544968;5044.864905396256;4920.065187614342;4967.254911763264;5006.4743201963265;5025.723585202702;5205.271262023244 23:35:04 INFO - PROCESS | 1655 | |4;prototype.html;316.6580093499021;317.74262847093854;323.74262461343227;324.1604245005437;330.04616657858776;5666.235058267132;5674.886502269957;5679.949209396264;5688.83378907324;5709.515525963248;5236.554892834104;5574;5580.637258578189;5582.581306402016;5637.943620563793;4061.1471590965857;4126.793660316987;4268.701190916637;4272.786360681954;4285.978570107145;3979.1643754811525;4244.299690551055;4262.65898728101;4266.040140968288;4269.402283680287;3630.7640003399824;3736.458213559032;4096;4239.59723826236;4245.257080011002;4165.520965089023;4225.239456897757;4251.532331443539;4272.252355837716;4275.657947364219;3505.7545971781988;3645.453182022691;3693.907652308687;3693.9287606594057;3711.870084547028;5287.365516138049;5312.751503396698;5314;5344.6793192408195;5373.247745315659;3895.1235971906376;4289.828406863721;4860.756962151878;4904.877378065541;4911.754412279408;5200.323957885488;5877.823665290048;5918.970405147989;5926.7925622603;5934.97032514839;3781.659650631429;3860.6911447084167;4030.919381612351;4048.4534587830562;4064.593540645941;5841.47426731592;6205.720742566596;6255.96872015637;6256.2492500899725;6274.654893980829;3708.2398108387715;3822.8661996830533;4007.2185923745174;4045.91908161841;4081.285774989365;4067.9593204067583;4068;4086.1214838809797;4094.774787386663;4106.281400754856;4081.5306239782212;4545.2954792007295;4691.179043667345;4781.928271075936;4782.3543821584335;5360.731963401814;5671.829845104653;5928.347881733006;5959.612625179349;5959.910601340984;1197.7664355450604;1377.8553251908606;1395.6301580081285;1402.144691738039;1403;741.0292516803005;820.827626198505;855.7432770168924;863.9136086391436;912.8676341930516;4663.626909847204;5350.3312085989255;5437.537809286176;5491.203775452537;5504.504594586427;5593.524550413261;5726.427357264323;6045.546584006217;6067.543789490491;6095.847603809941;4553.954460455419;4867.294242334841;5061.316722242524;5392.325959255093;5411.675299482044;2706.864656767154;2764.1707487753592;3169.3219229750825;3191.9042428727175;3230.660780618048;5577.856769165298;5854.502367298742;6264.812055638338;6277.654728989949;6307.180066591405;6038.728257228435;6250.156228909039;6259.812205633838;6264.467520260829;6282.968585157045;5026.673266237756;5905.202797622438;5935.40645935403;6298.496120310364;6325.209348831396;5308.814191503279;5771.740271687868;5888.586739182651;6139.754409823557;6209.379062093935;5115.8745076083205;5380.811671591632;5659.943400565941;6171;6217.285012223652;5587.580931430078;6021.163545118781;6449;6456.7740129095255;6560.737570497126;3334.433146365075;3413.283210525718;3567.500549922961;4034.636882680573;4046.6964977627385;4850.466448690711;6217.347178546188;6229.719662615103;6249.468795152463;6251.812445626639;5544.972275138599;5641.887162256814;6030.216071910623;6043.58256697307;6075.635461872302;5140.485951404829;5172.939547392851;5453.890922181455;6007.429294217042;6053.636781793107;4935.432425271139;4950.480199579137;5452.890942181213;5806.2451881255165;5886.735096920564;4871.368091689182;5675.694590244184;5736.971315143398;6060;6095.299040610386;5199.038177937094;5727.7661711933215;5890.440408161217;5896.941030589639;6015.308239552507 23:35:04 INFO - PROCESS | 1655 | |5;yui.html;638.7636574467448;640.9615423074616;701.4353445476389;714.4141803720947;731.864605048066;12621.873781262184;15090.019148755324;15226.847731522681;15261.008034477765;15264.313105910232;14824.332905019273;15299.388024479007;15352.002119862202;15664.216789160553;15697.21513924305;923.2060428031921;925.1858364639108;935.0836180543038;938.9952750556907;942.7124726958275;737.6459299536226;900.779309069279;925.9861102083474;932.9673461428852;935.6958988328786;933.7432206143297;934.7522906429801;979.5151400056982;980.8970058143899;981.1954197558006;928.1182876267573;943.8867335919665;979.6179489998906;983.0218932162509;983.6016413352604;685.8525417035362;689.6276010954078;728.6830228850414;729.854029194158;729.8540291941633;14920.552383428514;14945.850541494554;14955.252237388197;14987.100773953598;14995;9403.341766076377;12179.939100304442;12338.383080845922;12340.629781106582;12515.311657858814;9560.084174326828;9816.759152061966;9830.016998300185;10003.549840257207;10359.430231337274;3384.7461440392067;3427.2117412994903;3437.656234376567;3519.4017017107153;3522.5420695309444;82728;83813.58093209495;83967.16032839715;84094;85336.57331713302;9064.365494415459;9113.088691130833;9167.12912273333;9177.770555736026;9443.858342124873;20114.798852011292;21038.368848934555;21070.367888963356;21270;21367.35897923065;7154.356107950205;7156.606386648784;7191.820204494929;7257.237990010973;7337.633118344062;3947.8618248361167;4022.396640503889;4029.0128918415176;4034.8184331705143;4036.192761447722;3428.5714285714284;3513.1392808762016;3530.435130379127;3532.452469867175;3532.8410221540094;620.1039497925544;629.5624540944;631.3307893632764;640.0878747784388;641.9550631455846;497.55956505915174;547.9808206712826;581.9214406055213;599.7301214453513;614.3917521653531;8185.140560241208;8198.139195384518;8286.171382861787;8301.626426810808;8403.873941890877;487.7171240680397;536.407269966689;557.6977756937515;591.3967752892027;596.7081269052453;3108.3006323577015;3808.904777380588;3832.7700337979254;3895.7272990890365;4021.6581590564547;78493;85921.57039214764;87526.56236718775;88997.11002889888;89431.55284223668;76517;78001.60999194968;78512;78565.60717196377;79052.6047369771;67131.66434167896;67716.322836771;67744;68405;68438;7610.505317154367;7615.771526854203;7811.453198276066;7823.295903368724;7835.5690437026515;43695.78152109219;44053.77973110114;44152;44375.778121109186;44388.556114439736;56778.432215678964;57709.42290577041;59181.70409147927;60359.396406037136;60448;9885.110340069428;10016.449095299828;10079.748006299902;10341.673123547142;10344;9076.228520575562;10926.235163538477;10982.231243812856;11368.976792088752;11403.315801051964;10477.37135771856;10531.947340263556;10584.576616935236;10742.3017503862;10893.945530272298;10426;10471.581136754445;10631.149508039183;10770.784584308427;10784;8483.133710583661;10322.174226062047;10429.843552346872;10479.266451348332;10623.840642390525;8387.161283871808;8892.59983300766;8904.530752425777;9127.315451341043;9214.723558293263;7778.416618753503;8360.581970901552;9096;9242.815143696955;9496.240300775784 23:35:04 INFO - PROCESS | 1655 | __end_tp_report 23:35:04 INFO - PROCESS | 1655 | __start_cc_report 23:35:04 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,422 23:35:04 INFO - PROCESS | 1655 | __end_cc_report 23:35:04 INFO - PROCESS | 1655 | __startTimestamp1464158104024__endTimestamp 23:35:04 INFO - PROCESS | 1655 | 23:35:04 INFO - PROCESS | 1655 | ------- Summary: start ------- 23:35:04 INFO - PROCESS | 1655 | Number of tests: 6 23:35:04 INFO - PROCESS | 1655 | 23:35:04 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5829.88 Median:6089.51 stddev:770.88 (12.7%) stddev-sans-first:751.62 23:35:04 INFO - PROCESS | 1655 | Values: 3518.8 3524.9 3531.7 3533.8 3565.4 5810.7 5864.9 5947.9 5952.1 5966.3 5739.3 5843.2 5863.9 5901.6 5968.4 5268.7 5309.1 5382.6 5417.4 5424.8 388.6 5299.1 5431.3 5439.6 5500.1 4754.7 5002.5 5321.7 5355.8 5439.0 4950.7 4963.2 5273.1 5392.7 5408.7 5265.2 5298.3 5330.6 5339.0 5339.9 5723.7 5739.7 5769.6 5799.1 5832.1 5742.5 6185.9 6190.0 6295.4 6320.8 6218.5 6332.6 6345.2 6351.8 6354.8 6033.0 6035.9 6052.6 6069.4 6082.8 6206.5 6312.0 6327.0 6473.3 6500.7 6242.1 6299.0 6305.8 6311.6 6315.2 6316.9 6332.8 6343.5 6354.7 6368.5 6194.9 6227.6 6252.0 6260.1 6301.3 6164.8 6215.6 6220.9 6248.5 6260.3 5082.5 5087.3 5087.6 5103.8 5117.1 4455.5 4484.7 4511.7 4582.1 4599.1 5898.8 6040.3 6041.4 6057.6 6060.5 6366.6 6373.0 6379.1 6402.0 6405.8 5840.8 5854.3 5894.8 5913.6 5964.1 5512.5 5518.3 5520.7 5526.3 5530.3 6332.4 6474.5 6494.3 6515.9 6535.0 6215.0 6231.5 6270.1 6344.0 6366.9 6525.0 6597.9 6603.9 6610.6 6651.6 6168.2 6187.7 6274.7 6307.6 6310.6 6195.3 6289.6 6300.0 6303.4 6313.2 6243.2 6267.3 6274.8 6299.2 6301.0 4731.8 6128.1 6141.6 6224.0 6263.9 5638.9 5965.3 6026.8 6089.5 6129.2 6231.1 6231.8 6251.3 6278.3 6303.1 5999.4 6082.1 6132.8 6193.5 6211.4 23:35:04 INFO - PROCESS | 1655 | 23:35:04 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:57803.86 Median:21381.08 stddev:122940.45 (575.0%) stddev-sans-first:123210.06 23:35:04 INFO - PROCESS | 1655 | Values: 506.3 511.4 523.3 530.5 546.0 34496.1 42554.4 42586.4 42648.8 42811.4 43398.6 43967.1 43986.9 44059.6 44144.3 9588.4 9824.8 9830.5 9831.9 9838.0 9698.0 9745.3 9779.7 9793.9 9797.5 9739.8 9772.0 9791.4 9849.3 9864.5 9778.2 9805.0 9855.3 9865.3 9882.4 9625.1 9700.9 9709.3 9745.2 9771.6 39725.4 39763.6 40603.4 40864.8 40879.8 3328.0 3382.4 3474.6 3544.4 3897.4 29021.0 29097.0 29115.9 29148.1 29672.6 2513.1 2554.1 2698.0 2708.4 2716.4 503281.0 505068.0 510145.0 512932.0 514836.0 52898.7 59258.4 59887.4 60147.0 60272.0 35540.1 35780.0 36107.5 38167.8 63901.7 17241.4 17343.7 17398.7 17410.0 17449.7 13105.9 13206.3 13221.9 13226.9 13234.3 19542.1 19973.2 19992.6 19999.2 20041.6 8373.3 8374.2 8381.5 8385.6 8403.2 893.3 946.7 953.6 996.2 1060.8 24907.9 26190.9 28345.6 29459.4 29925.9 1009.3 1086.6 1089.5 1093.8 1146.8 15300.8 15432.5 15507.0 15653.8 15663.4 177001.0 183162.0 183194.0 183839.0 184203.0 60083.0 61641.0 62282.7 62303.0 62319.0 1306.7 1370.5 1408.2 1423.2 1436.1 29186.7 29229.1 29695.6 29983.4 30125.2 50742.0 52162.5 52302.0 52445.0 52538.0 568057.0 568245.0 579735.0 592325.0 599320.0 26306.0 26555.5 26647.2 27051.0 27106.7 46356.0 47454.8 47897.0 48660.3 48845.8 37766.4 38864.8 39245.6 39864.6 40294.6 37612.6 38294.6 38313.8 38684.6 39046.0 31778.2 37205.1 37209.3 37784.0 38817.2 33442.3 34225.8 34251.0 34380.0 34388.0 24752.8 25268.0 25287.0 25702.5 26618.0 23:35:04 INFO - PROCESS | 1655 | 23:35:04 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:33853.63 Median:12989.16 stddev:96254.66 (741.0%) stddev-sans-first:96491.88 23:35:04 INFO - PROCESS | 1655 | Values: 387.6 388.5 389.6 392.3 414.4 14505.9 18564.9 20325.9 20832.7 20907.0 17379.4 20023.6 20085.4 20511.0 21216.9 5782.4 5897.6 5972.7 6149.7 6157.6 6173.9 6206.1 6217.7 6219.5 6233.6 6108.0 6154.6 6221.4 6223.6 6251.2 5442.3 6079.3 6147.9 6275.6 6278.5 5866.5 6062.4 6070.3 6073.9 6085.5 13514.3 20334.6 24473.0 25392.4 25590.7 1916.9 2000.7 2057.8 2237.3 2446.5 9490.3 9934.0 10023.7 10225.6 10331.1 1733.3 1780.6 1812.6 1815.5 1822.4 83536.0 121072.0 125219.4 128274.0 128452.4 11553.4 11571.8 11696.6 11720.7 11906.6 14392.6 14873.4 14956.4 14968.2 15044.3 8640.7 8779.9 8805.2 8872.6 8886.9 8700.0 8717.0 8729.9 8831.8 8852.5 4586.4 4624.2 4670.3 4676.0 4696.3 1653.4 1716.5 1929.9 1962.1 2007.4 883.3 918.4 931.6 953.9 973.7 17604.4 20298.8 20974.0 21010.8 21166.3 950.9 952.8 967.5 994.0 1051.9 8985.1 10711.6 11012.2 11094.3 11096.7 566981.0 570586.0 571496.0 583945.0 584007.0 124608.8 124669.4 127507.0 129619.4 131021.0 1001.7 1003.1 1012.3 1026.0 1031.4 17994.8 18056.4 18141.0 18196.6 18499.2 47813.5 49737.0 50041.7 51422.5 51667.7 69056.0 72977.0 73573.6 73992.0 75848.0 6983.4 7389.6 7450.8 7802.4 8398.8 9149.0 12139.0 12746.2 13232.2 13281.9 5222.6 6499.0 10899.3 10909.8 11413.3 10857.7 10925.5 11097.0 11188.0 11329.6 6502.0 7199.5 10995.2 11063.6 11246.6 7401.3 7404.9 7476.6 7519.7 7726.7 9075.7 10223.1 11076.6 11127.4 11448.3 23:35:04 INFO - PROCESS | 1655 | 23:35:04 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5841.95 Median:4806.12 stddev:3311.17 (68.9%) stddev-sans-first:3304.81 23:35:04 INFO - PROCESS | 1655 | Values: 1559.2 1614.1 1850.0 1922.0 1938.7 8138.6 8521.1 8769.1 9296.3 9310.7 8834.7 8930.4 8947.6 9039.1 9071.2 2171.0 2303.9 2391.8 2420.9 2428.7 2401.3 2403.7 2406.3 2410.1 2444.3 2400.0 2404.3 2411.1 2419.5 2420.3 2362.7 2417.3 2418.3 2419.9 2435.6 2390.2 2400.9 2409.9 2415.9 2427.5 7811.5 9024.2 9088.0 9092.6 9202.4 5407.9 5489.0 5570.6 5591.0 5599.8 3601.7 3614.2 3619.6 3686.3 3690.2 1031.4 1034.8 1039.0 1042.2 1046.9 12082.6 12299.0 12347.9 12355.3 12358.0 4871.6 4871.7 4990.1 4991.2 4999.1 10054.4 10293.6 10309.1 10322.1 10329.0 5417.0 5427.0 5441.5 5470.6 5547.4 4442.6 4832.6 4924.9 5039.6 5048.1 4070.9 4086.5 4086.9 4105.4 4120.8 1312.1 1322.0 1324.2 1330.2 1332.4 822.7 887.0 915.9 917.7 926.0 5722.2 5754.9 5844.7 5885.3 5887.1 457.0 460.8 462.4 944.3 1005.8 3947.5 4001.4 4044.8 4083.2 4096.4 10765.5 10813.1 10972.2 10986.3 11149.5 10961.9 11029.8 11075.2 11098.3 11098.7 8133.4 8318.2 8333.1 8444.0 8700.8 5741.3 5849.3 5940.9 6006.7 6008.1 10131.5 10219.5 10244.6 10323.1 10340.1 10277.9 10338.3 10486.4 10570.3 10680.9 6610.9 6995.6 7248.3 7250.7 7421.6 8515.6 8581.7 8602.0 8664.6 8667.6 7521.9 7674.3 7707.9 7762.2 7795.6 7443.3 7488.9 7513.8 7577.4 7727.8 7078.5 7121.3 7145.1 7166.6 7172.6 4779.6 4920.4 4994.6 5005.5 5044.9 4920.1 4967.3 5006.5 5025.7 5205.3 23:35:04 INFO - PROCESS | 1655 | 23:35:04 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4758.66 Median:5355.53 stddev:1499.33 (28.0%) stddev-sans-first:1466.00 23:35:04 INFO - PROCESS | 1655 | Values: 316.7 317.7 323.7 324.2 330.0 5666.2 5674.9 5679.9 5688.8 5709.5 5236.6 5574.0 5580.6 5582.6 5637.9 4061.1 4126.8 4268.7 4272.8 4286.0 3979.2 4244.3 4262.7 4266.0 4269.4 3630.8 3736.5 4096.0 4239.6 4245.3 4165.5 4225.2 4251.5 4272.3 4275.7 3505.8 3645.5 3693.9 3693.9 3711.9 5287.4 5312.8 5314.0 5344.7 5373.2 3895.1 4289.8 4860.8 4904.9 4911.8 5200.3 5877.8 5919.0 5926.8 5935.0 3781.7 3860.7 4030.9 4048.5 4064.6 5841.5 6205.7 6256.0 6256.2 6274.7 3708.2 3822.9 4007.2 4045.9 4081.3 4068.0 4068.0 4086.1 4094.8 4106.3 4081.5 4545.3 4691.2 4781.9 4782.4 5360.7 5671.8 5928.3 5959.6 5959.9 1197.8 1377.9 1395.6 1402.1 1403.0 741.0 820.8 855.7 863.9 912.9 4663.6 5350.3 5437.5 5491.2 5504.5 5593.5 5726.4 6045.5 6067.5 6095.8 4554.0 4867.3 5061.3 5392.3 5411.7 2706.9 2764.2 3169.3 3191.9 3230.7 5577.9 5854.5 6264.8 6277.7 6307.2 6038.7 6250.2 6259.8 6264.5 6283.0 5026.7 5905.2 5935.4 6298.5 6325.2 5308.8 5771.7 5888.6 6139.8 6209.4 5115.9 5380.8 5659.9 6171.0 6217.3 5587.6 6021.2 6449.0 6456.8 6560.7 3334.4 3413.3 3567.5 4034.6 4046.7 4850.5 6217.3 6229.7 6249.5 6251.8 5545.0 5641.9 6030.2 6043.6 6075.6 5140.5 5172.9 5453.9 6007.4 6053.6 4935.4 4950.5 5452.9 5806.2 5886.7 4871.4 5675.7 5737.0 6060.0 6095.3 5199.0 5727.8 5890.4 5896.9 6015.3 23:35:04 INFO - PROCESS | 1655 | 23:35:04 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:17520.11 Median:635.05 stddev:24758.99 (3898.8%) stddev-sans-first:24796.00 23:35:04 INFO - PROCESS | 1655 | Values: 638.8 641.0 701.4 714.4 731.9 12621.9 15090.0 15226.8 15261.0 15264.3 14824.3 15299.4 15352.0 15664.2 15697.2 923.2 925.2 935.1 939.0 942.7 737.6 900.8 926.0 933.0 935.7 933.7 934.8 979.5 980.9 981.2 928.1 943.9 979.6 983.0 983.6 685.9 689.6 728.7 729.9 729.9 14920.6 14945.9 14955.3 14987.1 14995.0 9403.3 12179.9 12338.4 12340.6 12515.3 9560.1 9816.8 9830.0 10003.5 10359.4 3384.7 3427.2 3437.7 3519.4 3522.5 82728.0 83813.6 83967.2 84094.0 85336.6 9064.4 9113.1 9167.1 9177.8 9443.9 20114.8 21038.4 21070.4 21270.0 21367.4 7154.4 7156.6 7191.8 7257.2 7337.6 3947.9 4022.4 4029.0 4034.8 4036.2 3428.6 3513.1 3530.4 3532.5 3532.8 620.1 629.6 631.3 640.1 642.0 497.6 548.0 581.9 599.7 614.4 8185.1 8198.1 8286.2 8301.6 8403.9 487.7 536.4 557.7 591.4 596.7 3108.3 3808.9 3832.8 3895.7 4021.7 78493.0 85921.6 87526.6 88997.1 89431.6 76517.0 78001.6 78512.0 78565.6 79052.6 67131.7 67716.3 67744.0 68405.0 68438.0 7610.5 7615.8 7811.5 7823.3 7835.6 43695.8 44053.8 44152.0 44375.8 44388.6 56778.4 57709.4 59181.7 60359.4 60448.0 9885.1 10016.4 10079.7 10341.7 10344.0 9076.2 10926.2 10982.2 11369.0 11403.3 10477.4 10531.9 10584.6 10742.3 10893.9 10426.0 10471.6 10631.1 10770.8 10784.0 8483.1 10322.2 10429.8 10479.3 10623.8 8387.2 8892.6 8904.5 9127.3 9214.7 7778.4 8360.6 9096.0 9242.8 9496.2 23:35:04 INFO - PROCESS | 1655 | -------- Summary: end -------- 23:35:04 INFO - PROCESS | 1655 | 23:35:04 INFO - TEST-INFO | 1655: exit 0 23:35:04 INFO - TEST-OK | dromaeo_css | took 1112413ms 23:35:04 INFO - TEST-START | kraken 23:35:04 INFO - operating with platform_type : mac_ 23:35:04 INFO - Initialising browser for kraken test... 23:35:04 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpYb2Svw/profile http://localhost:49271/getInfo.html) 23:35:07 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 23:35:07 INFO - PROCESS | 1667 | colorDepth:24 23:35:07 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 23:35:07 INFO - PROCESS | 1667 | __metrics 23:35:07 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 23:35:08 INFO - TEST-INFO | 1667: exit 0 23:35:08 INFO - Browser initialized. 23:35:08 INFO - Running cycle 1/1 for kraken test... 23:35:08 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpYb2Svw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:35:20 INFO - PROCESS | 1670 | RSS: Main: 250007552 23:35:20 INFO - PROCESS | 1670 | 23:35:23 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 23:35:24 INFO - PROCESS | 1670 | RSS: Main: 261615616 23:35:24 INFO - PROCESS | 1670 | 23:35:26 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 23:35:27 INFO - PROCESS | 1670 | RSS: Main: 266403840 23:35:27 INFO - PROCESS | 1670 | 23:35:30 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 23:35:31 INFO - PROCESS | 1670 | RSS: Main: 266563584 23:35:31 INFO - PROCESS | 1670 | 23:35:33 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 23:35:34 INFO - PROCESS | 1670 | RSS: Main: 267333632 23:35:34 INFO - PROCESS | 1670 | 23:35:36 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:35:36 INFO - PROCESS | 1670 | RSS: Main: 268423168 23:35:36 INFO - PROCESS | 1670 | 23:35:39 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 23:35:40 INFO - PROCESS | 1670 | RSS: Main: 268816384 23:35:40 INFO - PROCESS | 1670 | 23:35:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 23:35:43 INFO - PROCESS | 1670 | RSS: Main: 268619776 23:35:43 INFO - PROCESS | 1670 | 23:35:46 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 23:35:47 INFO - PROCESS | 1670 | RSS: Main: 268931072 23:35:47 INFO - PROCESS | 1670 | 23:35:49 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:35:49 INFO - PROCESS | 1670 | RSS: Main: 268423168 23:35:49 INFO - PROCESS | 1670 | 23:35:51 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:35:52 INFO - PROCESS | 1670 | RSS: Main: 268476416 23:35:52 INFO - PROCESS | 1670 | 23:35:54 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:35:55 INFO - PROCESS | 1670 | RSS: Main: 268713984 23:35:55 INFO - PROCESS | 1670 | 23:35:57 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:35:58 INFO - PROCESS | 1670 | RSS: Main: 268754944 23:35:58 INFO - PROCESS | 1670 | 23:36:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:36:02 INFO - PROCESS | 1670 | RSS: Main: 268722176 23:36:02 INFO - PROCESS | 1670 | 23:36:03 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 23:36:04 INFO - PROCESS | 1670 | RSS: Main: 270839808 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | __start_tp_report 23:36:04 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 23:36:04 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 23:36:04 INFO - PROCESS | 1670 | |i|pagename|runs| 23:36:04 INFO - PROCESS | 1670 | |0;ai-astar;117;109;116;120;156;113;114;115;117;117 23:36:04 INFO - PROCESS | 1670 | |1;audio-beat-detection;180;143;144;161;119;137;137;121;164;116 23:36:04 INFO - PROCESS | 1670 | |2;audio-dft;201;218;264;256;199;197;193;187;267;201 23:36:04 INFO - PROCESS | 1670 | |3;audio-fft;68;83;75;138;57;58;59;60;126;58 23:36:04 INFO - PROCESS | 1670 | |4;audio-oscillator;73;88;121;71;74;88;143;72;62;137 23:36:04 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;89;86;89;86;88;86;89;86;89;86 23:36:04 INFO - PROCESS | 1670 | |6;imaging-darkroom;95;100;153;150;94;95;92;95;95;94 23:36:04 INFO - PROCESS | 1670 | |7;imaging-desaturate;78;141;140;93;114;152;94;148;145;91 23:36:04 INFO - PROCESS | 1670 | |8;json-parse-financial;58;58;56;58;56;58;59;76;59;58 23:36:04 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;61;85;73;73;72;75;71;73;109;103 23:36:04 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;84;88;88;86;83;85;87;85;90;92 23:36:04 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;151;129;156;145;147;126;143;145;178;128 23:36:04 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;163;227;225;168;166;164;162;167;165;161 23:36:04 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;80;89;90;85;84;59;86;64;72;75 23:36:04 INFO - PROCESS | 1670 | __end_tp_report 23:36:04 INFO - PROCESS | 1670 | __start_cc_report 23:36:04 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1089 23:36:04 INFO - PROCESS | 1670 | __end_cc_report 23:36:04 INFO - PROCESS | 1670 | __startTimestamp1464158164243__endTimestamp 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | ------- Summary: start ------- 23:36:04 INFO - PROCESS | 1670 | Number of tests: 14 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:119.40 Median:117.00 stddev:13.19 (11.3%) stddev-sans-first:13.96 23:36:04 INFO - PROCESS | 1670 | Values: 117.0 109.0 116.0 120.0 156.0 113.0 114.0 115.0 117.0 117.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:143.50 stddev:21.05 (14.7%) stddev-sans-first:17.33 23:36:04 INFO - PROCESS | 1670 | Values: 180.0 143.0 144.0 161.0 119.0 137.0 137.0 121.0 164.0 116.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:218.30 Median:209.50 stddev:31.49 (15.0%) stddev-sans-first:32.77 23:36:04 INFO - PROCESS | 1670 | Values: 201.0 218.0 264.0 256.0 199.0 197.0 193.0 187.0 267.0 201.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:78.20 Median:59.50 stddev:29.75 (50.0%) stddev-sans-first:31.32 23:36:04 INFO - PROCESS | 1670 | Values: 68.0 83.0 75.0 138.0 57.0 58.0 59.0 60.0 126.0 58.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:72.50 stddev:29.66 (40.9%) stddev-sans-first:30.57 23:36:04 INFO - PROCESS | 1670 | Values: 73.0 88.0 121.0 71.0 74.0 88.0 143.0 72.0 62.0 137.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.50 stddev:1.51 (1.7%) stddev-sans-first:1.48 23:36:04 INFO - PROCESS | 1670 | Values: 89.0 86.0 89.0 86.0 88.0 86.0 89.0 86.0 89.0 86.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:106.30 Median:94.50 stddev:23.92 (25.3%) stddev-sans-first:25.02 23:36:04 INFO - PROCESS | 1670 | Values: 95.0 100.0 153.0 150.0 94.0 95.0 92.0 95.0 95.0 94.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:119.60 Median:115.00 stddev:28.52 (24.8%) stddev-sans-first:25.97 23:36:04 INFO - PROCESS | 1670 | Values: 78.0 141.0 140.0 93.0 114.0 152.0 94.0 148.0 145.0 91.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:58.00 stddev:5.85 (10.1%) stddev-sans-first:6.18 23:36:04 INFO - PROCESS | 1670 | Values: 58.0 58.0 56.0 58.0 56.0 58.0 59.0 76.0 59.0 58.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:79.50 Median:73.00 stddev:15.17 (20.8%) stddev-sans-first:14.54 23:36:04 INFO - PROCESS | 1670 | Values: 61.0 85.0 73.0 73.0 72.0 75.0 71.0 73.0 109.0 103.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:87.50 stddev:2.78 (3.2%) stddev-sans-first:2.76 23:36:04 INFO - PROCESS | 1670 | Values: 84.0 88.0 88.0 86.0 83.0 85.0 87.0 85.0 90.0 92.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:146.00 stddev:15.49 (10.6%) stddev-sans-first:16.27 23:36:04 INFO - PROCESS | 1670 | Values: 151.0 129.0 156.0 145.0 147.0 126.0 143.0 145.0 178.0 128.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:166.50 stddev:26.02 (15.6%) stddev-sans-first:27.12 23:36:04 INFO - PROCESS | 1670 | Values: 163.0 227.0 225.0 168.0 166.0 164.0 162.0 167.0 165.0 161.0 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.40 Median:84.50 stddev:10.64 (12.6%) stddev-sans-first:11.27 23:36:04 INFO - PROCESS | 1670 | Values: 80.0 89.0 90.0 85.0 84.0 59.0 86.0 64.0 72.0 75.0 23:36:04 INFO - PROCESS | 1670 | -------- Summary: end -------- 23:36:04 INFO - PROCESS | 1670 | 23:36:04 INFO - TEST-INFO | 1670: exit 0 23:36:04 INFO - TEST-OK | kraken | took 60317ms 23:36:04 INFO - SUITE-END | took 1172s 23:36:05 INFO - Completed test suite (00:19:33) 23:36:05 INFO - javascript benchmark 23:36:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7190.096397939626, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3518.753687241893, 3524.929501409972, 3531.735119866011, 3533.787972721637, 3565.429531274995, 5810.680412577307, 5864.941350586499, 5947.910781338283, 5952.136940143682, 5966.254218222723, 5739.311282646088, 5843.211166492522, 5863.912041319373, 5901.557383196256, 5968.432998865101, 5268.657537260085, 5309.123994540896, 5382.5963052771, 5417.377001644822, 5424.810131645393, 388.5518061622796, 5299.072289874656, 5431.321084864392, 5439.5920305977015, 5500.147477141049, 4754.7147171169845, 5002.474740152305, 5321.707306098163, 5355.812546560852, 5439.0, 4950.653454258203, 4963.230699241587, 5273.129933560957, 5392.703401312926, 5408.702521361323, 5265.157574788054, 5298.337707786527, 5330.626856120073, 5338.958903013914, 5339.866503337409, 5723.685197314146, 5739.684317362543, 5769.596128271022, 5799.101139323411, 5832.096025116114, 5742.454466825645, 6185.938140618581, 6190.0405437157315, 6295.433410992987, 6320.841978950517, 6218.4714299284615, 6332.556721029529, 6345.238571371418, 6351.809445716636, 6354.809355719336, 6033.0, 6035.879282414371, 6052.636841789506, 6069.362716914705, 6082.787102451393, 6206.534509911775, 6311.990081586924, 6327.019312006646, 6473.28793832678, 6500.739970401226, 6242.09489624002, 6299.023651334051, 6305.779297724557, 6311.558190926591, 6315.210598675166, 6316.905246421307, 6332.810015699536, 6343.460805831556, 6354.682265886687, 6368.490520758328, 6194.907076393858, 6227.626342419469, 6252.0, 6260.092286618506, 6301.306856245809, 6164.845878852975, 6215.595986260879, 6220.937790622126, 6248.50011999039, 6260.342664020212, 5082.466341034138, 5087.31321271631, 5087.618428617869, 5103.770330335144, 5117.1045067113855, 4455.509893911667, 4484.68607197493, 4511.70673906195, 4582.1293954148605, 4599.08018396322, 5898.793542226002, 6040.335563088057, 6041.395860414012, 6057.575969682168, 6060.484858786965, 6366.6180029198395, 6373.012183111625, 6379.106925030499, 6402.03969404593, 6405.775797847146, 5840.766369345179, 5854.268216472941, 5894.764209431661, 5913.586048976616, 5964.135200396005, 5512.476314750093, 5518.255035570147, 5520.66875987442, 5526.336839579196, 5530.336359636869, 6332.366763323815, 6474.546781725234, 6494.285628580852, 6515.934840651627, 6535.0, 6215.005599104122, 6231.501479881689, 6270.090836828543, 6344.048392741126, 6366.904496432465, 6525.0, 6597.934020659732, 6603.867922641424, 6610.636414997221, 6651.600903945779, 6168.19813424252, 6187.721552530057, 6274.6548939808745, 6307.558470906993, 6310.5898116622275, 6195.256569211723, 6289.559730818982, 6300.0234963580715, 6303.401176888188, 6313.1792866928845, 6243.157173781481, 6267.341929097562, 6274.780382686584, 6299.212598425197, 6300.991841305369, 4731.787069581808, 6128.142060111499, 6141.578063794062, 6223.96888015557, 6263.906041409291, 5638.85902852432, 5965.28416590012, 6026.789062382795, 6089.51283897296, 6129.172561704113, 6231.137760336316, 6231.781887633911, 6251.281102673251, 6278.340774218641, 6303.149074875017, 5999.370066142992, 6082.148499210026, 6132.78535251264, 6193.504519638508, 6211.440970312694], "value": 5784.664131771224, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.3215291509377, 511.4297558222581, 523.275263759693, 530.4986787485827, 545.9617826752126, 34496.06783423409, 42554.36168457472, 42586.36120458188, 42648.78675606621, 42811.3578296325, 43398.56601433985, 43967.12065758683, 43986.90032749175, 44059.55940440595, 44144.3378349325, 9588.376755510886, 9824.803503929917, 9830.459324737136, 9831.852522212137, 9838.0, 9698.030196980317, 9745.317827752026, 9779.657711980082, 9793.90206097937, 9797.510124493783, 9739.805203895918, 9771.951140244324, 9791.363561368524, 9849.261305402088, 9864.506774661275, 9778.217742580577, 9804.950975245149, 9855.310128291023, 9865.260105492116, 9882.357646752975, 9625.085616866387, 9700.854487182698, 9709.320347575673, 9745.171660408874, 9771.560279787427, 39725.40411893824, 39763.60236397628, 40603.39094913549, 40864.79567602173, 40879.7956010221, 3328.0182346207926, 3382.391169589473, 3474.5830500339866, 3544.3620148373284, 3897.3569361055393, 29021.0, 29097.0, 29115.85442072776, 29148.125556233346, 29672.554911676343, 2513.1455305196323, 2554.131595257603, 2698.0287096645193, 2708.3635345693747, 2716.4023914738727, 503281.0, 505068.0, 510145.0, 512932.0, 514836.0, 52898.73550632299, 59258.40741592615, 59887.40112598818, 60147.0, 60272.0, 35540.11149721226, 35780.0, 36107.4583881242, 38167.80916095457, 63901.680491597246, 17241.39655112065, 17343.739843902353, 17398.739018914726, 17409.95540267588, 17449.7382539262, 13105.934470327587, 13206.33968301581, 13221.933890330487, 13226.867731322563, 13234.338283085806, 19542.120604572545, 19973.20107195696, 19992.60014799725, 19999.200031998556, 20041.599168016557, 8373.330133589297, 8374.246317831425, 8381.455205411628, 8385.6226469809, 8403.243708066304, 893.2942975049768, 946.7159852044359, 953.6471505542994, 996.2378780233128, 1060.8196606576746, 24907.8791454385, 26190.86904565465, 28345.57481637777, 29459.410811783644, 29925.85037074801, 1009.2632378363688, 1086.625114335553, 1089.536946797608, 1093.824988001916, 1146.7534480086822, 15300.770488442682, 15432.537023889077, 15507.0, 15653.776239448103, 15663.373465061497, 177001.0, 183162.0, 183194.0, 183839.0, 184203.0, 60083.0, 61641.0, 62282.688586556775, 62303.0, 62319.0, 1306.7451846889765, 1370.520317888751, 1408.1551069358509, 1423.2172305232082, 1436.1239643817264, 29186.7081329184, 29229.123126306244, 29695.554566681087, 29983.400331993675, 30125.246868827577, 50742.0, 52162.47837521576, 52302.0, 52445.0, 52537.99999999847, 568057.0, 568245.0, 579735.0, 592325.0, 599320.0, 26306.0, 26555.468890622466, 26647.20058398251, 27051.0, 27106.72893271042, 46356.0, 47454.76272618753, 47897.0, 48660.270095949294, 48845.755771220916, 37766.43350349802, 38864.80567597144, 39245.6075439242, 39864.60135398609, 40294.59705402909, 37612.61486466231, 38294.617053829104, 38313.76628523285, 38684.6131538681, 39046.0, 31778.205544861565, 37205.069873253386, 37209.25581488409, 37784.0, 38817.22365552617, 33442.33115337631, 34225.82887085548, 34250.97247082592, 34380.0, 34387.96836094921, 24752.752472475044, 25267.989280428577, 25287.0, 25702.485950281265, 26618.0], "value": 19248.20227926223, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.57754048087577, 388.53958059699244, 389.6434762192594, 392.3349921882409, 414.42188147534193, 14505.92747036266, 18564.90717546412, 20325.898370508163, 20832.68750968735, 20907.0, 17379.391721289758, 20023.599528009432, 20085.39743807688, 20511.0, 21216.89391553044, 5782.421757824205, 5897.587168898168, 5972.6715030673295, 6149.723262453178, 6157.6305421674615, 6173.876522469548, 6206.069089636568, 6217.689115544227, 6219.533534984872, 6233.594816336924, 6108.0, 6154.569180157391, 6221.4400703936635, 6223.564350495467, 6251.249850017982, 5442.265294185274, 6079.331273559905, 6147.907781383283, 6275.592086514384, 6278.529110316745, 5866.501347385477, 6062.393760623902, 6070.332263451017, 6073.939260607382, 6085.4827339676185, 13514.256715880623, 20334.593308133753, 24473.0, 25392.36519087019, 25590.744092559024, 1916.93290734825, 2000.7098970649174, 2057.845661575388, 2237.2952519956325, 2446.4617784087473, 9490.288228382899, 9933.950330248375, 10023.749406264902, 10225.642102526375, 10331.070203681706, 1733.2547004787937, 1780.5815633326158, 1812.5649844037468, 1815.5279627296866, 1822.4077174918204, 83536.0, 121072.0, 125219.3739031308, 128274.0, 128452.35773821165, 11553.36456494884, 11571.826422603668, 11696.649100526998, 11720.706982325339, 11906.583269585697, 14392.640183995483, 14873.405063797543, 14956.40174393012, 14968.176750278839, 15044.323005464561, 8640.697575584825, 8779.912200878038, 8805.207531322083, 8872.556372181365, 8886.933567971755, 8700.0, 8717.041125476193, 8729.91270087291, 8831.823363532822, 8852.468851868909, 4586.4266966629175, 4624.1676498230645, 4670.276107203389, 4675.95324046762, 4696.295555666609, 1653.4378311374192, 1716.5279548124365, 1929.922803087889, 1962.0974351798047, 2007.3676791810533, 883.2908322396183, 918.4168053286211, 931.5901003558413, 953.9141477267079, 973.704972386724, 17604.38384656531, 20298.797012029987, 20974.0, 21010.844403557952, 21166.2591809289, 950.8954015058338, 952.8427809411509, 967.4969016111723, 994.0258546624349, 1051.8632577764843, 8985.081149804208, 10711.571537138583, 11012.229143960007, 11094.278871873303, 11096.722581935515, 566981.0, 570586.0, 571496.0, 583945.0, 584007.0, 124608.75391246153, 124669.37665311797, 127507.0, 129619.35190323988, 131021.0, 1001.7445551384496, 1003.0621369019843, 1012.2964539645023, 1026.0150255754559, 1031.437866362834, 17994.820051799314, 18056.381824635617, 18141.0, 18196.636067278847, 18499.167537461115, 47813.5218647823, 49737.0, 50041.74979125081, 51422.485775143265, 51667.74166129145, 69056.0, 72977.0, 73573.632131839, 73992.0, 75848.0, 6983.406410455168, 7389.630518473948, 7450.813729656585, 7802.414818888493, 8398.790030249293, 9148.954255228682, 12139.0, 12746.171498852545, 13232.206067635972, 13281.933590331986, 5222.616006758288, 6499.0, 10899.29154604948, 10909.781804364027, 11413.315201087962, 10857.728556785827, 10925.453727313761, 11097.0, 11187.993080622782, 11329.603463878724, 6502.024696295404, 7199.49603527769, 10995.175361848052, 11063.557457701601, 11246.606368777053, 7401.259874012556, 7404.925950740424, 7476.626168691435, 7519.661615227437, 7726.690932362642, 9075.727728168165, 10223.079922806983, 11076.612318568812, 11127.443627818739, 11448.255863368855], "value": 9366.72479992626, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1559.243766773115, 1614.096106180539, 1849.953751156221, 1922.0485859499559, 1938.670426027577, 8138.633761480733, 8521.10528394519, 8769.123087691236, 9296.302777291705, 9310.674126405569, 8834.690785822497, 8930.419522731017, 8947.64209431622, 9039.096090390974, 9071.183593476586, 2171.0447403142643, 2303.884805759714, 2391.8086553075755, 2420.8668523231304, 2428.7449817769143, 2401.3396316013063, 2403.7115546134487, 2406.290144407404, 2410.144398738449, 2444.303373538542, 2400.003998340695, 2404.3147702904675, 2411.144043864429, 2419.4677171022345, 2420.298113547078, 2362.6692263083182, 2417.2627348658566, 2418.2503423938633, 2419.9395015124674, 2435.6346548017764, 2390.1873363056643, 2400.939976500584, 2409.8674572898485, 2415.9128392223392, 2427.490227052321, 7811.531308121531, 9024.23294020009, 9088.0, 9092.636294548276, 9202.355835091546, 5407.891842163135, 5489.039418101856, 5570.637908535932, 5591.049521581341, 5599.832005039855, 3601.7118630509494, 3614.1868079682126, 3619.565652121736, 3686.3364594373, 3690.2435000825035, 1031.443020768784, 1034.8499467577235, 1039.0129377091794, 1042.166266986408, 1046.9005444482762, 12082.6375208743, 12299.016078713681, 12347.938260308641, 12355.25868447887, 12358.0, 4871.610271178297, 4871.707697538159, 4990.051890140861, 4991.176455884738, 4999.125153098266, 10054.447005414626, 10293.55890175376, 10309.07218350352, 10322.12261957727, 10329.0, 5417.024935511567, 5426.972865135649, 5441.5102640762525, 5470.589705772083, 5547.362053363834, 4442.600165985077, 4832.63755218353, 4924.926126108111, 5039.596832253411, 5048.066107770074, 4070.8778736637355, 4086.5096188457574, 4086.884653644166, 4105.404716316118, 4120.773357465308, 1312.061875758837, 1321.973560528784, 1324.1591589340837, 1330.1819381080759, 1332.4403750424844, 822.7490615362252, 886.9512176830336, 915.8900931888082, 917.655879045358, 925.9537023148815, 5722.170285308607, 5754.913676294859, 5844.678542680108, 5885.293764748258, 5887.116932460165, 456.9588737013685, 460.75810199645457, 462.44506592088953, 944.3059351376733, 1005.7787286796893, 3947.467091942551, 4001.3591029920112, 4044.757314561077, 4083.224187404384, 4096.446979657768, 10765.461726913623, 10813.134949204044, 10972.23194376386, 10986.285891417032, 11149.498272577754, 10961.890381096246, 11029.779404411867, 11075.16936229786, 11098.334099954029, 11098.722531936766, 8133.430659853753, 8318.209770071833, 8333.125021872738, 8444.0, 8700.78248043804, 5741.25363702716, 5849.298084229919, 5940.8811823764145, 6006.729697163551, 6008.098785182257, 10131.544080516247, 10219.48902554884, 10244.64143754965, 10323.122534584645, 10340.069393754598, 10277.897221027693, 10338.328008679411, 10486.423246721504, 10570.312929659547, 10680.946595266974, 6610.900836487361, 6995.635851009004, 7248.275172482709, 7250.709971601077, 7421.591812450367, 8515.616797244264, 8581.65673373058, 8602.0, 8664.610092545976, 8667.609957551798, 7521.924780752122, 7674.309312161932, 7707.884381734167, 7762.223777622193, 7795.610219488889, 7443.292887175709, 7488.850222995618, 7513.812154696177, 7577.355924746458, 7727.768166955, 7078.504504684623, 7121.252268511732, 7145.071140751669, 7166.641667916688, 7172.641367931687, 4779.593734532604, 4920.40955085392, 4994.575461085703, 5005.549500544968, 5044.864905396256, 4920.065187614342, 4967.254911763264, 5006.4743201963265, 5025.723585202702, 5205.271262023244], "value": 4647.613026741249, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.6580093499021, 317.74262847093854, 323.74262461343227, 324.1604245005437, 330.04616657858776, 5666.235058267132, 5674.886502269957, 5679.949209396264, 5688.83378907324, 5709.515525963248, 5236.554892834104, 5574.0, 5580.637258578189, 5582.581306402016, 5637.943620563793, 4061.1471590965857, 4126.793660316987, 4268.701190916637, 4272.786360681954, 4285.978570107145, 3979.1643754811525, 4244.299690551055, 4262.65898728101, 4266.040140968288, 4269.402283680287, 3630.7640003399824, 3736.458213559032, 4096.0, 4239.59723826236, 4245.257080011002, 4165.520965089023, 4225.239456897757, 4251.532331443539, 4272.252355837716, 4275.657947364219, 3505.7545971781988, 3645.453182022691, 3693.907652308687, 3693.9287606594057, 3711.870084547028, 5287.365516138049, 5312.751503396698, 5314.0, 5344.6793192408195, 5373.247745315659, 3895.1235971906376, 4289.828406863721, 4860.756962151878, 4904.877378065541, 4911.754412279408, 5200.323957885488, 5877.823665290048, 5918.970405147989, 5926.7925622603, 5934.97032514839, 3781.659650631429, 3860.6911447084167, 4030.919381612351, 4048.4534587830562, 4064.593540645941, 5841.47426731592, 6205.720742566596, 6255.96872015637, 6256.2492500899725, 6274.654893980829, 3708.2398108387715, 3822.8661996830533, 4007.2185923745174, 4045.91908161841, 4081.285774989365, 4067.9593204067583, 4068.0, 4086.1214838809797, 4094.774787386663, 4106.281400754856, 4081.5306239782212, 4545.2954792007295, 4691.179043667345, 4781.928271075936, 4782.3543821584335, 5360.731963401814, 5671.829845104653, 5928.347881733006, 5959.612625179349, 5959.910601340984, 1197.7664355450604, 1377.8553251908606, 1395.6301580081285, 1402.144691738039, 1403.0, 741.0292516803005, 820.827626198505, 855.7432770168924, 863.9136086391436, 912.8676341930516, 4663.626909847204, 5350.3312085989255, 5437.537809286176, 5491.203775452537, 5504.504594586427, 5593.524550413261, 5726.427357264323, 6045.546584006217, 6067.543789490491, 6095.847603809941, 4553.954460455419, 4867.294242334841, 5061.316722242524, 5392.325959255093, 5411.675299482044, 2706.864656767154, 2764.1707487753592, 3169.3219229750825, 3191.9042428727175, 3230.660780618048, 5577.856769165298, 5854.502367298742, 6264.812055638338, 6277.654728989949, 6307.180066591405, 6038.728257228435, 6250.156228909039, 6259.812205633838, 6264.467520260829, 6282.968585157045, 5026.673266237756, 5905.202797622438, 5935.40645935403, 6298.496120310364, 6325.209348831396, 5308.814191503279, 5771.740271687868, 5888.586739182651, 6139.754409823557, 6209.379062093935, 5115.8745076083205, 5380.811671591632, 5659.943400565941, 6171.0, 6217.285012223652, 5587.580931430078, 6021.163545118781, 6449.0, 6456.7740129095255, 6560.737570497126, 3334.433146365075, 3413.283210525718, 3567.500549922961, 4034.636882680573, 4046.6964977627385, 4850.466448690711, 6217.347178546188, 6229.719662615103, 6249.468795152463, 6251.812445626639, 5544.972275138599, 5641.887162256814, 6030.216071910623, 6043.58256697307, 6075.635461872302, 5140.485951404829, 5172.939547392851, 5453.890922181455, 6007.429294217042, 6053.636781793107, 4935.432425271139, 4950.480199579137, 5452.890942181213, 5806.2451881255165, 5886.735096920564, 4871.368091689182, 5675.694590244184, 5736.971315143398, 6060.0, 6095.299040610386, 5199.038177937094, 5727.7661711933215, 5890.440408161217, 5896.941030589639, 6015.308239552507], "value": 4273.452454766915, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.7636574467448, 640.9615423074616, 701.4353445476389, 714.4141803720947, 731.864605048066, 12621.873781262184, 15090.019148755324, 15226.847731522681, 15261.008034477765, 15264.313105910232, 14824.332905019273, 15299.388024479007, 15352.002119862202, 15664.216789160553, 15697.21513924305, 923.2060428031921, 925.1858364639108, 935.0836180543038, 938.9952750556907, 942.7124726958275, 737.6459299536226, 900.779309069279, 925.9861102083474, 932.9673461428852, 935.6958988328786, 933.7432206143297, 934.7522906429801, 979.5151400056982, 980.8970058143899, 981.1954197558006, 928.1182876267573, 943.8867335919665, 979.6179489998906, 983.0218932162509, 983.6016413352604, 685.8525417035362, 689.6276010954078, 728.6830228850414, 729.854029194158, 729.8540291941633, 14920.552383428514, 14945.850541494554, 14955.252237388197, 14987.100773953598, 14995.0, 9403.341766076377, 12179.939100304442, 12338.383080845922, 12340.629781106582, 12515.311657858814, 9560.084174326828, 9816.759152061966, 9830.016998300185, 10003.549840257207, 10359.430231337274, 3384.7461440392067, 3427.2117412994903, 3437.656234376567, 3519.4017017107153, 3522.5420695309444, 82728.0, 83813.58093209495, 83967.16032839715, 84094.0, 85336.57331713302, 9064.365494415459, 9113.088691130833, 9167.12912273333, 9177.770555736026, 9443.858342124873, 20114.798852011292, 21038.368848934555, 21070.367888963356, 21270.0, 21367.35897923065, 7154.356107950205, 7156.606386648784, 7191.820204494929, 7257.237990010973, 7337.633118344062, 3947.8618248361167, 4022.396640503889, 4029.0128918415176, 4034.8184331705143, 4036.192761447722, 3428.5714285714284, 3513.1392808762016, 3530.435130379127, 3532.452469867175, 3532.8410221540094, 620.1039497925544, 629.5624540944, 631.3307893632764, 640.0878747784388, 641.9550631455846, 497.55956505915174, 547.9808206712826, 581.9214406055213, 599.7301214453513, 614.3917521653531, 8185.140560241208, 8198.139195384518, 8286.171382861787, 8301.626426810808, 8403.873941890877, 487.7171240680397, 536.407269966689, 557.6977756937515, 591.3967752892027, 596.7081269052453, 3108.3006323577015, 3808.904777380588, 3832.7700337979254, 3895.7272990890365, 4021.6581590564547, 78493.0, 85921.57039214764, 87526.56236718775, 88997.11002889888, 89431.55284223668, 76517.0, 78001.60999194968, 78512.0, 78565.60717196377, 79052.6047369771, 67131.66434167896, 67716.322836771, 67744.0, 68405.0, 68438.0, 7610.505317154367, 7615.771526854203, 7811.453198276066, 7823.295903368724, 7835.5690437026515, 43695.78152109219, 44053.77973110114, 44152.0, 44375.778121109186, 44388.556114439736, 56778.432215678964, 57709.42290577041, 59181.70409147927, 60359.396406037136, 60448.0, 9885.110340069428, 10016.449095299828, 10079.748006299902, 10341.673123547142, 10344.0, 9076.228520575562, 10926.235163538477, 10982.231243812856, 11368.976792088752, 11403.315801051964, 10477.37135771856, 10531.947340263556, 10584.576616935236, 10742.3017503862, 10893.945530272298, 10426.0, 10471.581136754445, 10631.149508039183, 10770.784584308427, 10784.0, 8483.133710583661, 10322.174226062047, 10429.843552346872, 10479.266451348332, 10623.840642390525, 8387.161283871808, 8892.59983300766, 8904.530752425777, 9127.315451341043, 9214.723558293263, 7778.416618753503, 8360.581970901552, 9096.0, 9242.815143696955, 9496.240300775784], "value": 6669.627650959518, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 109.0, 116.0, 120.0, 156.0, 113.0, 114.0, 115.0, 117.0, 117.0], "value": 119.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 143.0, 144.0, 161.0, 119.0, 137.0, 137.0, 121.0, 164.0, 116.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 218.0, 264.0, 256.0, 199.0, 197.0, 193.0, 187.0, 267.0, 201.0], "value": 218.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 83.0, 75.0, 138.0, 57.0, 58.0, 59.0, 60.0, 126.0, 58.0], "value": 78.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 88.0, 121.0, 71.0, 74.0, 88.0, 143.0, 72.0, 62.0, 137.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 88.0, 86.0, 89.0, 86.0, 89.0, 86.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 153.0, 150.0, 94.0, 95.0, 92.0, 95.0, 95.0, 94.0], "value": 106.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 141.0, 140.0, 93.0, 114.0, 152.0, 94.0, 148.0, 145.0, 91.0], "value": 119.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 56.0, 58.0, 56.0, 58.0, 59.0, 76.0, 59.0, 58.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 85.0, 73.0, 73.0, 72.0, 75.0, 71.0, 73.0, 109.0, 103.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 88.0, 86.0, 83.0, 85.0, 87.0, 85.0, 90.0, 92.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 129.0, 156.0, 145.0, 147.0, 126.0, 143.0, 145.0, 178.0, 128.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 227.0, 225.0, 168.0, 166.0, 164.0, 162.0, 167.0, 165.0, 161.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 89.0, 90.0, 85.0, 84.0, 59.0, 86.0, 64.0, 72.0, 75.0], "value": 78.4, "unit": "score"}]}]} 23:36:05 INFO - Return code: 0 23:36:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:36:05 ERROR - # TBPL SUCCESS # 23:36:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:36:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:36:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:36:05 INFO - 2016-05-24 23:36:05.559 screenresolution[1675:10334] starting screenresolution argv=screenresolution get 23:36:05 INFO - 2016-05-24 23:36:05.562 screenresolution[1675:10334] Display 0: 1600x1200x32@60 23:36:05 INFO - 2016-05-24 23:36:05.568 screenresolution[1676:10337] starting screenresolution argv=screenresolution list 23:36:05 INFO - Available Modes on Display 0 23:36:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:36:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:36:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:36:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:36:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:36:05 INFO - Intel Iris: 23:36:05 INFO - Chipset Model: Intel Iris 23:36:05 INFO - Type: GPU 23:36:05 INFO - Bus: Built-In 23:36:05 INFO - VRAM (Dynamic, Max): 1536 MB 23:36:05 INFO - Vendor: Intel (0x8086) 23:36:05 INFO - Device ID: 0x0a2e 23:36:05 INFO - Revision ID: 0x0009 23:36:05 INFO - Displays: 23:36:05 INFO - Display: 23:36:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:36:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:36:05 INFO - Display Serial Number: 1600x1200 60 23:36:05 INFO - Main Display: Yes 23:36:05 INFO - Mirror: Off 23:36:05 INFO - Online: Yes 23:36:05 INFO - Rotation: Supported 23:36:05 INFO - Return code: 0 23:36:05 INFO - Running post-action listener: _resource_record_post_action 23:36:05 INFO - ##### 23:36:05 INFO - ##### Finished run-tests step (success) 23:36:05 INFO - ##### 23:36:05 INFO - Running post-run listener: _resource_record_post_run 23:36:05 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 38106624; Write bytes: 698252800; Read time: 395; Write time: 2158 23:36:05 INFO - TinderboxPrint: CPU usage
54.2% 23:36:05 INFO - TinderboxPrint: I/O read bytes / time
38,106,624 / 395 23:36:05 INFO - TinderboxPrint: I/O write bytes / time
698,252,800 / 2,158 23:36:05 INFO - TinderboxPrint: CPU idle
2,211.6 (45.8%) 23:36:05 INFO - TinderboxPrint: CPU system
970.1 (20.1%) 23:36:05 INFO - TinderboxPrint: CPU user
1,651.8 (34.2%) 23:36:05 INFO - TinderboxPrint: Swap in / out
434,565,120 / 0 23:36:05 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380060160; Write bytes: 374806016; Read time: 28252; Write time: 720 23:36:05 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 37131776; Write bytes: 298510336; Read time: 380; Write time: 1385 23:36:06 INFO - Running post-run listener: _upload_blobber_files 23:36:06 INFO - Blob upload gear active. 23:36:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:36:06 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 23:36:06 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'] 23:36:06 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 23:36:06 INFO - (blobuploader) - INFO - Open directory for files ... 23:36:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:36:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:36:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:36:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:36:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:36:07 INFO - (blobuploader) - INFO - Done attempting. 23:36:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:36:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:36:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:36:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:36:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:36:08 INFO - (blobuploader) - INFO - Done attempting. 23:36:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:36:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:36:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:36:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:36:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:36:10 INFO - (blobuploader) - INFO - Done attempting. 23:36:10 INFO - (blobuploader) - INFO - Iteration through files over. 23:36:10 INFO - Return code: 0 23:36:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:36:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:36:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/783b30d52c21667d9be38935f965ad14ae7da59b3f540535b3aeea0a985cab06ff08dfe21bef4335fed05bb11cea1c4faef9d0a2cc2552ebc2449dce406379d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed0d517c5e73d818a49628f9bb0959957a80b142b113a5165ec8bd61532567dca9875d880600c48eccb0ed3fcff211259159665d3862a7e7999d9dcbd55867df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/831fb1f37e6f3d8add7c9c8877dfa89418b60bd380681038ce29352f9e4e702be7fc92bd93e3723d40f71e2346f2d3126a9be057252caedb281f3e366d8e31ec"} 23:36:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:36:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:36:10 INFO - Contents: 23:36:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/783b30d52c21667d9be38935f965ad14ae7da59b3f540535b3aeea0a985cab06ff08dfe21bef4335fed05bb11cea1c4faef9d0a2cc2552ebc2449dce406379d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed0d517c5e73d818a49628f9bb0959957a80b142b113a5165ec8bd61532567dca9875d880600c48eccb0ed3fcff211259159665d3862a7e7999d9dcbd55867df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/831fb1f37e6f3d8add7c9c8877dfa89418b60bd380681038ce29352f9e4e702be7fc92bd93e3723d40f71e2346f2d3126a9be057252caedb281f3e366d8e31ec"} 23:36:10 INFO - Running post-run listener: copy_logs_to_upload_dir 23:36:10 INFO - Copying logs to upload dir... 23:36:10 INFO - mkdir: /builds/slave/test/build/upload/logs 23:36:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.838437 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-24 23:36:11.116829) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 23:36:11.120701) ========= 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.wf0NvDgFPd/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.VJe12OirBC/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/783b30d52c21667d9be38935f965ad14ae7da59b3f540535b3aeea0a985cab06ff08dfe21bef4335fed05bb11cea1c4faef9d0a2cc2552ebc2449dce406379d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed0d517c5e73d818a49628f9bb0959957a80b142b113a5165ec8bd61532567dca9875d880600c48eccb0ed3fcff211259159665d3862a7e7999d9dcbd55867df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/831fb1f37e6f3d8add7c9c8877dfa89418b60bd380681038ce29352f9e4e702be7fc92bd93e3723d40f71e2346f2d3126a9be057252caedb281f3e366d8e31ec"} build_url:https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011143 build_url: 'https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/783b30d52c21667d9be38935f965ad14ae7da59b3f540535b3aeea0a985cab06ff08dfe21bef4335fed05bb11cea1c4faef9d0a2cc2552ebc2449dce406379d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed0d517c5e73d818a49628f9bb0959957a80b142b113a5165ec8bd61532567dca9875d880600c48eccb0ed3fcff211259159665d3862a7e7999d9dcbd55867df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/831fb1f37e6f3d8add7c9c8877dfa89418b60bd380681038ce29352f9e4e702be7fc92bd93e3723d40f71e2346f2d3126a9be057252caedb281f3e366d8e31ec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 23:36:11.151514) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 23:36:11.151842) ========= 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.wf0NvDgFPd/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.VJe12OirBC/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.005242 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 23:36:11.204191) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 23:36:11.204709) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 23:36:11.205210) ========= ========= Total master_lag: 0.47 =========