builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0319 starttime: 1464782786.63 results: success (0) buildid: 20160601022352 builduid: ac933db4822840c1a7a5cc9f1a1f6cc0 revision: ef722ca61b23d3e8a41bd157701914fab4940205 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:26.631645) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:26.632079) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:26.632366) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:26.652990) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:26.653347) ========= 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.zrGhatqU2I/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.9e33vyaV4m/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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-06-01 05:06:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 37.4M=0s 2016-06-01 05:06:27 (37.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.348747 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:27.021761) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:27.022075) ========= 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.zrGhatqU2I/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.9e33vyaV4m/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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.020659 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:27.071159) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-01 05:06:27.071519) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ef722ca61b23d3e8a41bd157701914fab4940205 --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 ef722ca61b23d3e8a41bd157701914fab4940205 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zrGhatqU2I/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.9e33vyaV4m/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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-06-01 05:06:27,153 truncating revision to first 12 chars 2016-06-01 05:06:27,153 Setting DEBUG logging. 2016-06-01 05:06:27,153 attempt 1/10 2016-06-01 05:06:27,153 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ef722ca61b23?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 05:06:27,936 unpacking tar archive at: fx-team-ef722ca61b23/testing/mozharness/ program finished with exit code 0 elapsedTime=2.115700 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-01 05:06:29.208634) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:29.208963) ========= script_repo_revision: ef722ca61b23d3e8a41bd157701914fab4940205 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:29.209444) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:29.209766) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 05:06:29.226847) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-06-01 05:06:29.227207) ========= /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.zrGhatqU2I/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.9e33vyaV4m/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:06:29 INFO - MultiFileLogger online at 20160601 05:06:29 in /builds/slave/test 05:06:29 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 05:06:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:06:29 INFO - {'append_to_log': False, 05:06:29 INFO - 'base_work_dir': '/builds/slave/test', 05:06:29 INFO - 'blob_upload_branch': 'Fx-Team', 05:06:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:06:29 INFO - 'branch': 'Fx-Team', 05:06:29 INFO - 'buildbot_json_path': 'buildprops.json', 05:06:29 INFO - 'config_files': ('talos/mac_config.py',), 05:06:29 INFO - 'default_actions': ('clobber', 05:06:29 INFO - 'read-buildbot-config', 05:06:29 INFO - 'download-and-extract', 05:06:29 INFO - 'populate-webroot', 05:06:29 INFO - 'create-virtualenv', 05:06:29 INFO - 'install', 05:06:29 INFO - 'run-tests'), 05:06:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:06:29 INFO - 'download_minidump_stackwalk': True, 05:06:29 INFO - 'download_symbols': 'ondemand', 05:06:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:06:29 INFO - 'tooltool.py': '/tools/tooltool.py', 05:06:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:06:29 INFO - '/tools/misc-python/virtualenv.py')}, 05:06:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:06:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:06:29 INFO - 'installer_path': 'installer.exe', 05:06:29 INFO - 'log_level': 'info', 05:06:29 INFO - 'log_name': 'talos', 05:06:29 INFO - 'log_to_console': True, 05:06:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:06:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:06:29 INFO - 'opt_config_files': (), 05:06:29 INFO - 'pip_index': False, 05:06:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:06:29 INFO - 'cmd': ('bash', 05:06:29 INFO - '-c', 05:06:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:06:29 INFO - 'enabled': True, 05:06:29 INFO - 'halt_on_failure': False, 05:06:29 INFO - 'name': 'check_screen_resolution'},), 05:06:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:06:29 INFO - 'cmd': ('bash', 05:06:29 INFO - '-c', 05:06:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:06:29 INFO - 'enabled': True, 05:06:29 INFO - 'halt_on_failure': False, 05:06:29 INFO - 'name': 'check_screen_resolution'},), 05:06:29 INFO - 'run_cmd_checks_enabled': True, 05:06:29 INFO - 'sps_profile': False, 05:06:29 INFO - 'sps_profile_interval': 0, 05:06:29 INFO - 'suite': 'dromaeojs-e10s', 05:06:29 INFO - 'system_bits': '32', 05:06:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:06:29 INFO - 'title': 't-yosemite-r7-0319', 05:06:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:06:29 INFO - 'use_talos_json': True, 05:06:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:06:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:06:29 INFO - 'work_dir': 'build'} 05:06:29 INFO - ##### 05:06:29 INFO - ##### Running clobber step. 05:06:29 INFO - ##### 05:06:29 INFO - Running pre-action listener: _resource_record_pre_action 05:06:29 INFO - Running main action method: clobber 05:06:29 INFO - rmtree: /builds/slave/test/build 05:06:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:06:31 INFO - Running post-action listener: _resource_record_post_action 05:06:31 INFO - ##### 05:06:31 INFO - ##### Finished clobber step (success) 05:06:31 INFO - ##### 05:06:31 INFO - ##### 05:06:31 INFO - ##### Running read-buildbot-config step. 05:06:31 INFO - ##### 05:06:31 INFO - Running pre-action listener: _resource_record_pre_action 05:06:31 INFO - Running main action method: read_buildbot_config 05:06:31 INFO - Using buildbot properties: 05:06:31 INFO - { 05:06:31 INFO - "project": "", 05:06:31 INFO - "product": "firefox", 05:06:31 INFO - "script_repo_revision": "production", 05:06:31 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:06:31 INFO - "repository": "", 05:06:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:06:31 INFO - "buildid": "20160601022352", 05:06:31 INFO - "pgo_build": "False", 05:06:31 INFO - "basedir": "/builds/slave/test", 05:06:31 INFO - "buildnumber": 10, 05:06:31 INFO - "slavename": "t-yosemite-r7-0319", 05:06:31 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:06:31 INFO - "platform": "yosemite_r7", 05:06:31 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:06:31 INFO - "branch": "fx-team", 05:06:31 INFO - "repo_path": "integration/fx-team", 05:06:31 INFO - "revision": "ef722ca61b23d3e8a41bd157701914fab4940205", 05:06:31 INFO - "stage_platform": "macosx64", 05:06:31 INFO - "builduid": "ac933db4822840c1a7a5cc9f1a1f6cc0", 05:06:31 INFO - "slavebuilddir": "test" 05:06:31 INFO - } 05:06:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:06:31 INFO - Running post-action listener: _resource_record_post_action 05:06:31 INFO - ##### 05:06:31 INFO - ##### Finished read-buildbot-config step (success) 05:06:31 INFO - ##### 05:06:31 INFO - ##### 05:06:31 INFO - ##### Running download-and-extract step. 05:06:31 INFO - ##### 05:06:31 INFO - Running pre-action listener: _resource_record_pre_action 05:06:31 INFO - Running main action method: download_and_extract 05:06:31 INFO - mkdir: /builds/slave/test/build/tests 05:06: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')]} 05:06:31 INFO - https://queue.taskcluster.net/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:06:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:06:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:06:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/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 05:06:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/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 05:06:32 INFO - Downloaded 1199 bytes. 05:06:32 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:06:32 INFO - Using the following test package requirements: 05:06:32 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:06:32 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:06:32 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:06:32 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:06:32 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:06:32 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:06:32 INFO - u'jsshell-mac.zip'], 05:06:32 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:06:32 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:06:32 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:06:32 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:06:32 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:06:32 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:06:32 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:06:32 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:06:32 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:06:32 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:06:32 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:06:32 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:32 INFO - https://queue.taskcluster.net/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:06:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:06:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:06:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/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 05:06:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/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 05:06:33 INFO - Downloaded 18687126 bytes. 05:06:33 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'] 05:06:33 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 05:06:36 INFO - Return code: 0 05:06:36 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 05:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:36 INFO - https://queue.taskcluster.net/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:06:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:06:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:06:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/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 05:06:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/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 05:06:37 INFO - Downloaded 18687126 bytes. 05:06:37 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'] 05:06:37 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 05:06:41 INFO - Return code: 0 05:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:41 INFO - https://queue.taskcluster.net/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:06:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:06:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:06:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/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 05:06:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/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 05:06:42 INFO - Downloaded 10834055 bytes. 05:06:42 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'] 05:06:42 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 05:06:42 INFO - Return code: 0 05:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:42 INFO - https://queue.taskcluster.net/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:06:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:06:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:06:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:06:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bQ7PqEZzQN-NqynRSQVeTA/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 05:06:45 INFO - Downloaded 110989823 bytes. 05:06:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:06:45 INFO - mkdir: /builds/slave/test/properties 05:06:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:06:45 INFO - Writing to file /builds/slave/test/properties/build_url 05:06:45 INFO - Contents: 05:06:45 INFO - build_url:https://queue.taskcluster.net/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:06:49 INFO - Running post-action listener: _resource_record_post_action 05:06:49 INFO - Running post-action listener: set_extra_try_arguments 05:06:49 INFO - ##### 05:06:49 INFO - ##### Finished download-and-extract step (success) 05:06:49 INFO - ##### 05:06:49 INFO - ##### 05:06:49 INFO - ##### Running populate-webroot step. 05:06:49 INFO - ##### 05:06:49 INFO - Running pre-action listener: _resource_record_pre_action 05:06:49 INFO - Running main action method: populate_webroot 05:06:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:06:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:06:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:06:49 INFO - '--tppagecycles', 05:06:49 INFO - '7'], 05:06:49 INFO - 'tests': ['tsvgm']}}, 05:06:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:06:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:06:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:06:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:06:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:06:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:06:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:49 INFO - 'talos_options': ['--disable-e10s'], 05:06:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:49 INFO - 'talos_options': ['--disable-e10s'], 05:06:49 INFO - 'tests': ['damp', 'tps']}, 05:06:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:49 INFO - 'tests': ['damp', 'tps']}, 05:06:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:06:49 INFO - 'tests': ['dromaeo_dom']}, 05:06:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:06:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:06:49 INFO - 'tests': ['a11yr', 05:06:49 INFO - 'ts_paint', 05:06:49 INFO - 'tpaint', 05:06:49 INFO - 'sessionrestore', 05:06:49 INFO - 'sessionrestore_no_auto_restore', 05:06:49 INFO - 'tabpaint']}, 05:06:49 INFO - 'other-e10s': {'tests': ['a11yr', 05:06:49 INFO - 'ts_paint', 05:06:49 INFO - 'tpaint', 05:06:49 INFO - 'sessionrestore', 05:06:49 INFO - 'sessionrestore_no_auto_restore', 05:06:49 INFO - 'tabpaint']}, 05:06:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:06:49 INFO - 'ts_paint', 05:06:49 INFO - 'tpaint', 05:06:49 INFO - 'sessionrestore', 05:06:49 INFO - 'sessionrestore_no_auto_restore', 05:06:49 INFO - 'tabpaint']}, 05:06:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:06:49 INFO - 'ts_paint', 05:06:49 INFO - 'tpaint', 05:06:49 INFO - 'sessionrestore', 05:06:49 INFO - 'sessionrestore_no_auto_restore', 05:06:49 INFO - 'tabpaint']}, 05:06:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:06:49 INFO - 'tests': ['a11yr', 05:06:49 INFO - 'ts_paint', 05:06:49 INFO - 'tpaint', 05:06:49 INFO - 'sessionrestore', 05:06:49 INFO - 'sessionrestore_no_auto_restore', 05:06:49 INFO - 'tabpaint']}, 05:06:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:06:49 INFO - 'tests': ['a11yr', 05:06:49 INFO - 'ts_paint', 05:06:49 INFO - 'tpaint', 05:06:49 INFO - 'sessionrestore', 05:06:49 INFO - 'sessionrestore_no_auto_restore', 05:06:49 INFO - 'tabpaint']}, 05:06:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:06:49 INFO - 'tests': ['tsvgx', 05:06:49 INFO - 'tsvgr_opacity', 05:06:49 INFO - 'tart', 05:06:49 INFO - 'tscrollx', 05:06:49 INFO - 'cart']}, 05:06:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:06:49 INFO - 'tsvgr_opacity', 05:06:49 INFO - 'tart', 05:06:49 INFO - 'tscrollx', 05:06:49 INFO - 'cart']}, 05:06:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:49 INFO - 'talos_options': ['--disable-e10s'], 05:06:49 INFO - 'tests': ['tp5o']}, 05:06:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:49 INFO - 'tests': ['tp5o']}, 05:06:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:49 INFO - 'talos_options': ['--disable-e10s', 05:06:49 INFO - '--xperf_path', 05:06:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:49 INFO - 'tests': ['tp5n']}, 05:06:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:49 INFO - 'talos_options': ['--xperf_path', 05:06:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:49 INFO - 'tests': ['tp5n']}}, 05:06:49 INFO - 'talos.zip': {'path': '', 05:06:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:06:49 INFO - Running post-action listener: _resource_record_post_action 05:06:49 INFO - ##### 05:06:49 INFO - ##### Finished populate-webroot step (success) 05:06:49 INFO - ##### 05:06:49 INFO - ##### 05:06:49 INFO - ##### Running create-virtualenv step. 05:06:49 INFO - ##### 05:06:49 INFO - Running pre-action listener: _resource_record_pre_action 05:06:49 INFO - Running main action method: create_virtualenv 05:06:49 INFO - Creating virtualenv /builds/slave/test/build/venv 05:06:49 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 05:06:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:06:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:06:49 INFO - Using real prefix '/tools/python27' 05:06:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:06:50 INFO - Installing distribute.............................................................................................................................................................................................done. 05:06:53 INFO - Installing pip.................done. 05:06:53 INFO - Return code: 0 05:06:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:53 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 0x106ecba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c8ad96d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6fd30>, '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 0x106b93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d66b20>, '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.zrGhatqU2I/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.9e33vyaV4m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:06:53 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 05:06:53 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 05:06:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zrGhatqU2I/Render', 05:06:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:53 INFO - 'HOME': '/Users/cltbld', 05:06:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:53 INFO - 'LOGNAME': 'cltbld', 05:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:53 INFO - 'MOZ_NO_REMOTE': '1', 05:06:53 INFO - 'NO_EM_RESTART': '1', 05:06:53 INFO - 'PAGER': '/bin/cat', 05:06:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:53 INFO - 'PWD': '/builds/slave/test', 05:06:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:53 INFO - 'SHELL': '/bin/bash', 05:06:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9e33vyaV4m/Listeners', 05:06:53 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:06:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:53 INFO - 'USER': 'cltbld', 05:06:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:53 INFO - 'XPC_FLAGS': '0x0', 05:06:53 INFO - 'XPC_SERVICE_NAME': '0', 05:06:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:53 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 05:06:53 INFO - Cleaning up... 05:06:53 INFO - Return code: 0 05:06:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:53 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 0x106ecba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c8ad96d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6fd30>, '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 0x106b93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d66b20>, '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.zrGhatqU2I/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.9e33vyaV4m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:06:53 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 05:06:53 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 05:06:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zrGhatqU2I/Render', 05:06:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:53 INFO - 'HOME': '/Users/cltbld', 05:06:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:53 INFO - 'LOGNAME': 'cltbld', 05:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:53 INFO - 'MOZ_NO_REMOTE': '1', 05:06:53 INFO - 'NO_EM_RESTART': '1', 05:06:53 INFO - 'PAGER': '/bin/cat', 05:06:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:53 INFO - 'PWD': '/builds/slave/test', 05:06:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:53 INFO - 'SHELL': '/bin/bash', 05:06:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9e33vyaV4m/Listeners', 05:06:53 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:06:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:53 INFO - 'USER': 'cltbld', 05:06:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:53 INFO - 'XPC_FLAGS': '0x0', 05:06:53 INFO - 'XPC_SERVICE_NAME': '0', 05:06:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:54 INFO - Downloading/unpacking psutil>=3.1.1 05:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:06:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:57 INFO - Installing collected packages: psutil 05:06:57 INFO - Running setup.py install for psutil 05:06:57 INFO - building 'psutil._psutil_osx' extension 05:06:57 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 05:06:57 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 05:06:57 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 05:06:57 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 05:06:57 INFO - building 'psutil._psutil_posix' extension 05:06:57 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 05:06:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:06:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:06:57 INFO - ^ 05:06:57 INFO - 1 warning generated. 05:06:57 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 05:06:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:57 INFO - Successfully installed psutil 05:06:57 INFO - Cleaning up... 05:06:57 INFO - Return code: 0 05:06:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:06:57 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')]} 05:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:57 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')]} 05:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:57 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 0x106ecba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c8ad96d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6fd30>, '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 0x106b93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d66b20>, '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.zrGhatqU2I/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.9e33vyaV4m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:06:57 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 05:06:57 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 05:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zrGhatqU2I/Render', 05:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:57 INFO - 'HOME': '/Users/cltbld', 05:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:57 INFO - 'LOGNAME': 'cltbld', 05:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:57 INFO - 'MOZ_NO_REMOTE': '1', 05:06:57 INFO - 'NO_EM_RESTART': '1', 05:06:57 INFO - 'PAGER': '/bin/cat', 05:06:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:57 INFO - 'PWD': '/builds/slave/test', 05:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:57 INFO - 'SHELL': '/bin/bash', 05:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9e33vyaV4m/Listeners', 05:06:57 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:57 INFO - 'USER': 'cltbld', 05:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:57 INFO - 'XPC_FLAGS': '0x0', 05:06:57 INFO - 'XPC_SERVICE_NAME': '0', 05:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:06:58 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 05:06:58 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 05:06:58 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 05:06:58 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 05:06:58 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 05:06:58 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 05:07:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:07:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:07:00 INFO - Installing collected packages: mozsystemmonitor 05:07:00 INFO - Running setup.py install for mozsystemmonitor 05:07:00 INFO - Successfully installed mozsystemmonitor 05:07:00 INFO - Cleaning up... 05:07:00 INFO - Return code: 0 05:07:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:00 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 0x106ecba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c8ad96d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6fd30>, '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 0x106b93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d66b20>, '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.zrGhatqU2I/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.9e33vyaV4m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:07:00 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 05:07:00 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 05:07:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zrGhatqU2I/Render', 05:07:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:00 INFO - 'HOME': '/Users/cltbld', 05:07:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:00 INFO - 'LOGNAME': 'cltbld', 05:07:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:00 INFO - 'MOZ_NO_REMOTE': '1', 05:07:00 INFO - 'NO_EM_RESTART': '1', 05:07:00 INFO - 'PAGER': '/bin/cat', 05:07:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:00 INFO - 'PWD': '/builds/slave/test', 05:07:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:00 INFO - 'SHELL': '/bin/bash', 05:07:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9e33vyaV4m/Listeners', 05:07:00 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:07:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:00 INFO - 'USER': 'cltbld', 05:07:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:00 INFO - 'XPC_FLAGS': '0x0', 05:07:00 INFO - 'XPC_SERVICE_NAME': '0', 05:07:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:01 INFO - Downloading/unpacking blobuploader==1.2.4 05:07:01 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 05:07:01 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 05:07:01 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 05:07:01 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 05:07:01 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 05:07:01 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 05:07:03 INFO - Downloading blobuploader-1.2.4.tar.gz 05:07:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:07:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:07:03 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 05:07:03 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 05:07:03 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 05:07:03 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 05:07:03 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 05:07:03 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 05:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:07:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:07:04 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 05:07:04 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 05:07:04 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 05:07:04 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 05:07:04 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 05:07:04 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 05:07:04 INFO - Downloading docopt-0.6.1.tar.gz 05:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:07:04 INFO - Installing collected packages: blobuploader, requests, docopt 05:07:04 INFO - Running setup.py install for blobuploader 05:07:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:07:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:07:05 INFO - Running setup.py install for requests 05:07:05 INFO - Running setup.py install for docopt 05:07:05 INFO - Successfully installed blobuploader requests docopt 05:07:05 INFO - Cleaning up... 05:07:05 INFO - Return code: 0 05:07:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:05 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 0x106ecba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c8ad96d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6fd30>, '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 0x106b93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d66b20>, '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.zrGhatqU2I/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.9e33vyaV4m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:07:05 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 05:07:05 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 05:07:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zrGhatqU2I/Render', 05:07:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:05 INFO - 'HOME': '/Users/cltbld', 05:07:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:05 INFO - 'LOGNAME': 'cltbld', 05:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:05 INFO - 'MOZ_NO_REMOTE': '1', 05:07:05 INFO - 'NO_EM_RESTART': '1', 05:07:05 INFO - 'PAGER': '/bin/cat', 05:07:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:05 INFO - 'PWD': '/builds/slave/test', 05:07:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:05 INFO - 'SHELL': '/bin/bash', 05:07:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9e33vyaV4m/Listeners', 05:07:05 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:07:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:05 INFO - 'USER': 'cltbld', 05:07:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:05 INFO - 'XPC_FLAGS': '0x0', 05:07:05 INFO - 'XPC_SERVICE_NAME': '0', 05:07:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:07:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-opPGL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:07:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-T5EOrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:07:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-qRmLW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:07:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-x1BmD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:07:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-ZieMZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:07:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-bpGLXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:07:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-2Ke_hB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:07:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-yhWhlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:07:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-DwsAqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:07:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-5W1FXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:07:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-OuHY3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:07:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-GWnJ23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:07:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-JD5QT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:07:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-AHuZQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-x4Z9bY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:07:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-IdDS1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:07:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-WYxG65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:07:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:07:07 INFO - Running setup.py install for manifestparser 05:07:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:07:07 INFO - Running setup.py install for mozcrash 05:07:08 INFO - Running setup.py install for mozdebug 05:07:08 INFO - Running setup.py install for mozdevice 05:07:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:07:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:07:08 INFO - Running setup.py install for mozfile 05:07:08 INFO - Running setup.py install for mozhttpd 05:07:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:07:08 INFO - Running setup.py install for mozinfo 05:07:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:07:08 INFO - Running setup.py install for mozInstall 05:07:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:07:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:07:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:07:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:07:08 INFO - Running setup.py install for mozleak 05:07:09 INFO - Running setup.py install for mozlog 05:07:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:07:09 INFO - Running setup.py install for moznetwork 05:07:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:07:09 INFO - Running setup.py install for mozprocess 05:07:09 INFO - Running setup.py install for mozprofile 05:07:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:07:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:07:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:07:09 INFO - Running setup.py install for mozrunner 05:07:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:07:09 INFO - Running setup.py install for mozscreenshot 05:07:10 INFO - Running setup.py install for moztest 05:07:10 INFO - Running setup.py install for mozversion 05:07:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:07:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:07:10 INFO - Cleaning up... 05:07:10 INFO - Return code: 0 05:07:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:10 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 0x106ecba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c8ad96d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6fd30>, '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 0x106b93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d66b20>, '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.zrGhatqU2I/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.9e33vyaV4m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:07:10 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 05:07:10 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 05:07:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zrGhatqU2I/Render', 05:07:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:10 INFO - 'HOME': '/Users/cltbld', 05:07:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:10 INFO - 'LOGNAME': 'cltbld', 05:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:10 INFO - 'MOZ_NO_REMOTE': '1', 05:07:10 INFO - 'NO_EM_RESTART': '1', 05:07:10 INFO - 'PAGER': '/bin/cat', 05:07:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:10 INFO - 'PWD': '/builds/slave/test', 05:07:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:10 INFO - 'SHELL': '/bin/bash', 05:07:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9e33vyaV4m/Listeners', 05:07:10 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:07:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:10 INFO - 'USER': 'cltbld', 05:07:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:10 INFO - 'XPC_FLAGS': '0x0', 05:07:10 INFO - 'XPC_SERVICE_NAME': '0', 05:07:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:07:10 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-ZVHIXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:07:10 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)) 05:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:07:10 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-eLf13f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:07:10 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)) 05:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:07:10 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-eDJJZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:07:10 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)) 05:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:07:11 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-8va_q5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:07:11 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)) 05:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:07:11 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-wJCW4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:07:11 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)) 05:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:07:11 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-DvABJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:07:11 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)) 05:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:07:11 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-QjCQAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:07:11 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)) 05:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:07:11 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-2E4fgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:07:11 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)) 05:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:07:11 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-7wClZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:07:11 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)) 05:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:07:11 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-bJQDds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:07:11 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)) 05:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:07:11 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-QlErof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:07:11 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)) 05:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:07:11 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-H7KKyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:07:11 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)) 05:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:07:11 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-msLzDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:07:12 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)) 05:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:07:12 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-dbdMEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:07:12 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)) 05:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:12 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-0qdRU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:12 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)) 05:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:07:12 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-oOpZKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:07:12 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)) 05:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:07:12 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-c74iMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:07:12 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)) 05:07:12 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)) 05:07:12 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)) 05:07:12 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)) 05:07:12 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)) 05:07:12 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)) 05:07:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:14 INFO - Downloading blessings-1.6.tar.gz 05:07:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:07:15 INFO - Installing collected packages: blessings 05:07:15 INFO - Running setup.py install for blessings 05:07:15 INFO - Successfully installed blessings 05:07:15 INFO - Cleaning up... 05:07:15 INFO - Return code: 0 05:07:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:07:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:07:15 INFO - Reading from file tmpfile_stdout 05:07:15 INFO - Current package versions: 05:07:15 INFO - blessings == 1.6 05:07:15 INFO - blobuploader == 1.2.4 05:07:15 INFO - docopt == 0.6.1 05:07:15 INFO - manifestparser == 1.1 05:07:15 INFO - mozInstall == 1.12 05:07:15 INFO - mozcrash == 0.17 05:07:15 INFO - mozdebug == 0.1 05:07:15 INFO - mozdevice == 0.48 05:07:15 INFO - mozfile == 1.2 05:07:15 INFO - mozhttpd == 0.7 05:07:15 INFO - mozinfo == 0.9 05:07:15 INFO - mozleak == 0.1 05:07:15 INFO - mozlog == 3.2 05:07:15 INFO - moznetwork == 0.27 05:07:15 INFO - mozprocess == 0.22 05:07:15 INFO - mozprofile == 0.28 05:07:15 INFO - mozrunner == 6.11 05:07:15 INFO - mozscreenshot == 0.1 05:07:15 INFO - mozsystemmonitor == 0.3 05:07:15 INFO - moztest == 0.7 05:07:15 INFO - mozversion == 1.4 05:07:15 INFO - psutil == 3.1.1 05:07:15 INFO - requests == 1.2.3 05:07:15 INFO - wsgiref == 0.1.2 05:07:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:15 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 0x106ecba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c8ad96d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6fd30>, '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 0x106b93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d66b20>, '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.zrGhatqU2I/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.9e33vyaV4m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:07:15 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 05:07:15 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 05:07:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zrGhatqU2I/Render', 05:07:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:15 INFO - 'HOME': '/Users/cltbld', 05:07:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:15 INFO - 'LOGNAME': 'cltbld', 05:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:15 INFO - 'MOZ_NO_REMOTE': '1', 05:07:15 INFO - 'NO_EM_RESTART': '1', 05:07:15 INFO - 'PAGER': '/bin/cat', 05:07:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:15 INFO - 'PWD': '/builds/slave/test', 05:07:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:15 INFO - 'SHELL': '/bin/bash', 05:07:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9e33vyaV4m/Listeners', 05:07:15 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:07:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:15 INFO - 'USER': 'cltbld', 05:07:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:15 INFO - 'XPC_FLAGS': '0x0', 05:07:15 INFO - 'XPC_SERVICE_NAME': '0', 05:07:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:15 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)) 05:07:15 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)) 05:07:15 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)) 05:07:15 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)) 05:07:15 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)) 05:07:15 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)) 05:07:15 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)) 05:07:15 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)) 05:07:15 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)) 05:07:15 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)) 05:07:15 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)) 05:07:15 INFO - Cleaning up... 05:07:15 INFO - Return code: 0 05:07:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:15 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 0x106ecba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c8ad96d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6fd30>, '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 0x106b93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d66b20>, '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.zrGhatqU2I/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.9e33vyaV4m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:07:15 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 05:07:15 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 05:07:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zrGhatqU2I/Render', 05:07:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:15 INFO - 'HOME': '/Users/cltbld', 05:07:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:15 INFO - 'LOGNAME': 'cltbld', 05:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:15 INFO - 'MOZ_NO_REMOTE': '1', 05:07:15 INFO - 'NO_EM_RESTART': '1', 05:07:15 INFO - 'PAGER': '/bin/cat', 05:07:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:15 INFO - 'PWD': '/builds/slave/test', 05:07:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:15 INFO - 'SHELL': '/bin/bash', 05:07:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9e33vyaV4m/Listeners', 05:07:15 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:07:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:15 INFO - 'USER': 'cltbld', 05:07:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:15 INFO - 'XPC_FLAGS': '0x0', 05:07:15 INFO - 'XPC_SERVICE_NAME': '0', 05:07:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:16 INFO - Downloading/unpacking jsonschema 05:07:16 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 05:07:16 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 05:07:16 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 05:07:16 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 05:07:16 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 05:07:16 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 05:07:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:07:18 INFO - Downloading/unpacking functools32 (from jsonschema) 05:07:18 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 05:07:18 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 05:07:18 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 05:07:18 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 05:07:18 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 05:07:18 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 05:07:18 INFO - Downloading functools32-3.2.3-2.tar.gz 05:07:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:07:18 INFO - warning: no files found matching '*.txt' 05:07:18 INFO - no previously-included directories found matching 'build' 05:07:18 INFO - no previously-included directories found matching 'dist' 05:07:18 INFO - no previously-included directories found matching '.git*' 05:07:18 INFO - Installing collected packages: jsonschema, functools32 05:07:19 INFO - Running setup.py install for functools32 05:07:19 INFO - warning: no files found matching '*.txt' 05:07:19 INFO - no previously-included directories found matching 'build' 05:07:19 INFO - no previously-included directories found matching 'dist' 05:07:19 INFO - no previously-included directories found matching '.git*' 05:07:19 INFO - Successfully installed jsonschema functools32 05:07:19 INFO - Cleaning up... 05:07:19 INFO - Return code: 0 05:07:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:19 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 0x106ecba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ed1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c8ad96d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6fd30>, '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 0x106b93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d66b20>, '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.zrGhatqU2I/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.9e33vyaV4m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:07:19 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 05:07:19 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 05:07:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zrGhatqU2I/Render', 05:07:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:19 INFO - 'HOME': '/Users/cltbld', 05:07:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:19 INFO - 'LOGNAME': 'cltbld', 05:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:19 INFO - 'MOZ_NO_REMOTE': '1', 05:07:19 INFO - 'NO_EM_RESTART': '1', 05:07:19 INFO - 'PAGER': '/bin/cat', 05:07:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:19 INFO - 'PWD': '/builds/slave/test', 05:07:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:19 INFO - 'SHELL': '/bin/bash', 05:07:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9e33vyaV4m/Listeners', 05:07:19 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:07:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:19 INFO - 'USER': 'cltbld', 05:07:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:19 INFO - 'XPC_FLAGS': '0x0', 05:07:19 INFO - 'XPC_SERVICE_NAME': '0', 05:07:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:19 INFO - Downloading/unpacking flake8 05:07:19 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 05:07:19 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 05:07:19 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 05:07:19 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 05:07:19 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 05:07:19 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 05:07:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:07:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:07:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:07:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:07:22 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 05:07:22 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 05:07:22 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 05:07:22 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 05:07:22 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 05:07:22 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 05:07:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:07:22 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 05:07:22 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 05:07:22 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 05:07:22 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 05:07:22 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 05:07:22 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 05:07:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:07:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:07:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:07:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:07:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:07:23 INFO - no previously-included directories found matching 'docs/_build' 05:07:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:07:23 INFO - Running setup.py install for pep8 05:07:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:07:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:07:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:07:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:07:23 INFO - no previously-included directories found matching 'docs/_build' 05:07:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:07:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:07:23 INFO - Cleaning up... 05:07:23 INFO - Return code: 0 05:07:23 INFO - Running post-action listener: _resource_record_post_action 05:07:23 INFO - Running post-action listener: _start_resource_monitoring 05:07:23 INFO - Starting resource monitoring. 05:07:23 INFO - ##### 05:07:23 INFO - ##### Finished create-virtualenv step (success) 05:07:23 INFO - ##### 05:07:23 INFO - ##### 05:07:23 INFO - ##### Running install step. 05:07:23 INFO - ##### 05:07:23 INFO - Running pre-action listener: _resource_record_pre_action 05:07:23 INFO - Running main action method: install 05:07:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:07:23 INFO - Reading from file tmpfile_stdout 05:07:23 INFO - Detecting whether we're running mozinstall >=1.0... 05:07:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:07:23 INFO - Reading from file tmpfile_stdout 05:07:23 INFO - Output received: 05:07:23 INFO - Usage: mozinstall [options] installer 05:07:23 INFO - Options: 05:07:23 INFO - -h, --help show this help message and exit 05:07:23 INFO - -d DEST, --destination=DEST 05:07:23 INFO - Directory to install application into. [default: 05:07:23 INFO - "/builds/slave/test"] 05:07:23 INFO - --app=APP Application being installed. [default: firefox] 05:07:23 INFO - mkdir: /builds/slave/test/build/application 05:07:23 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'] 05:07:23 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 05:07:58 INFO - Reading from file tmpfile_stdout 05:07:58 INFO - Output received: 05:07:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:07:58 INFO - Running post-action listener: _resource_record_post_action 05:07:58 INFO - ##### 05:07:58 INFO - ##### Finished install step (success) 05:07:58 INFO - ##### 05:07:58 INFO - ##### 05:07:58 INFO - ##### Running run-tests step. 05:07:58 INFO - ##### 05:07:58 INFO - Running pre-action listener: _resource_record_pre_action 05:07:58 INFO - Running main action method: run_tests 05:07:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:07:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:07:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:07:58 INFO - 2016-06-01 05:07:58.922 screenresolution[2542:26043] starting screenresolution argv=screenresolution get 05:07:58 INFO - 2016-06-01 05:07:58.924 screenresolution[2542:26043] Display 0: 1600x1200x32@60 05:07:58 INFO - 2016-06-01 05:07:58.930 screenresolution[2543:26045] starting screenresolution argv=screenresolution list 05:07:58 INFO - Available Modes on Display 0 05:07:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:07:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:07:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:07:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:07:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:07:59 INFO - Intel Iris: 05:07:59 INFO - Chipset Model: Intel Iris 05:07:59 INFO - Type: GPU 05:07:59 INFO - Bus: Built-In 05:07:59 INFO - VRAM (Dynamic, Max): 1536 MB 05:07:59 INFO - Vendor: Intel (0x8086) 05:07:59 INFO - Device ID: 0x0a2e 05:07:59 INFO - Revision ID: 0x0009 05:07:59 INFO - Displays: 05:07:59 INFO - Display: 05:07:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:07:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:07:59 INFO - Display Serial Number: 1600x1200 60 05:07:59 INFO - Main Display: Yes 05:07:59 INFO - Mirror: Off 05:07:59 INFO - Online: Yes 05:07:59 INFO - Rotation: Supported 05:07:59 INFO - Return code: 0 05:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:07:59 INFO - Python 2.7.3 05:07:59 INFO - Return code: 0 05:07:59 INFO - grabbing minidump binary from tooltool 05:07:59 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')]} 05:07:59 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 0x106d6fd30>, '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 0x106b93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d66b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:07:59 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 05:07:59 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 05:07:59 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 05:07:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:07:59 INFO - Return code: 0 05:07:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:07:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:07:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:07:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:07:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:08:00 INFO - Return code: 0 05:08:00 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/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0319', '--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 05:08:00 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/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0319 --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 05:08:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zrGhatqU2I/Render', 05:08:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:00 INFO - 'HOME': '/Users/cltbld', 05:08:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:00 INFO - 'LOGNAME': 'cltbld', 05:08:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:08:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:00 INFO - 'MOZ_NO_REMOTE': '1', 05:08:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:08:00 INFO - 'NO_EM_RESTART': '1', 05:08:00 INFO - 'PAGER': '/bin/cat', 05:08:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:00 INFO - 'PWD': '/builds/slave/test', 05:08:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:08:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:00 INFO - 'SHELL': '/bin/bash', 05:08:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9e33vyaV4m/Listeners', 05:08:00 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:08:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:00 INFO - 'USER': 'cltbld', 05:08:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:00 INFO - 'XPC_FLAGS': '0x0', 05:08:00 INFO - 'XPC_SERVICE_NAME': '0', 05:08:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:00 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/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0319', '--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 05:08:00 INFO - mozversion application_buildid: 20160601022352 05:08:00 INFO - mozversion application_changeset: ef722ca61b23d3e8a41bd157701914fab4940205 05:08:00 INFO - mozversion application_display_name: Nightly 05:08:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:08:00 INFO - mozversion application_name: Firefox 05:08:00 INFO - mozversion application_remotingname: firefox 05:08:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:08:00 INFO - mozversion application_vendor: Mozilla 05:08:00 INFO - mozversion application_version: 49.0a1 05:08:00 INFO - mozversion platform_buildid: 20160601022352 05:08:00 INFO - mozversion platform_changeset: ef722ca61b23d3e8a41bd157701914fab4940205 05:08:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:08:00 INFO - mozversion platform_version: 49.0a1 05:08:00 INFO - using testdate: 1464782880 05:08:00 INFO - actual date: 1464782880 05:08:00 INFO - starting webserver on 'localhost:54881' 05:08:00 INFO - SUITE-START | Running 2 tests 05:08:00 INFO - TEST-START | dromaeo_css 05:08:00 INFO - operating with platform_type : mac_ 05:08:00 INFO - Initialising browser for dromaeo_css test... 05:08:00 INFO - TEST-INFO | started process 2561 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpdoRdKC/profile http://localhost:54881/getInfo.html) 05:08:03 INFO - PROCESS | 2561 | __metrics Screen width/height:1600/1200 05:08:03 INFO - PROCESS | 2561 | colorDepth:24 05:08:03 INFO - PROCESS | 2561 | Browser inner width/height: 1024/689 05:08:03 INFO - PROCESS | 2561 | __metrics 05:08:03 ERROR - PROCESS | 2561 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 05:08:04 INFO - TEST-INFO | 2561: exit 0 05:08:04 INFO - Browser initialized. 05:08:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:08:04 INFO - TEST-INFO | started process 2564 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpdoRdKC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:08:16 INFO - PROCESS | 2564 | RSS: Main: 252510208 05:08:16 INFO - PROCESS | 2564 | 05:09:06 INFO - PROCESS | 2564 | 1464782946061 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] 05:11:17 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:54881/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54881/tests/dromaeo/cssquery-ext.html) 05:11:18 INFO - PROCESS | 2564 | RSS: Main: 264835072 05:11:18 INFO - PROCESS | 2564 | 05:14:21 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:54881/tests/dromaeo/cssquery-ext.html (next: http://localhost:54881/tests/dromaeo/cssquery-jquery.html) 05:14:21 INFO - PROCESS | 2564 | RSS: Main: 276201472 05:14:21 INFO - PROCESS | 2564 | 05:17:23 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:54881/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54881/tests/dromaeo/cssquery-mootools.html) 05:17:24 INFO - PROCESS | 2564 | RSS: Main: 278384640 05:17:24 INFO - PROCESS | 2564 | 05:20:26 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:54881/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54881/tests/dromaeo/cssquery-prototype.html) 05:20:26 INFO - PROCESS | 2564 | RSS: Main: 279605248 05:20:26 INFO - PROCESS | 2564 | 05:23:29 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:54881/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54881/tests/dromaeo/cssquery-yui.html) 05:23:30 INFO - PROCESS | 2564 | RSS: Main: 283402240 05:23:30 INFO - PROCESS | 2564 | 05:26:32 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:54881/tests/dromaeo/cssquery-yui.html (next: http://localhost:54881/tests/dromaeo/cssquery-dojo.html) 05:26:33 INFO - PROCESS | 2564 | RSS: Main: 283480064 05:26:33 INFO - PROCESS | 2564 | 05:26:33 INFO - PROCESS | 2564 | __start_tp_report 05:26:33 INFO - PROCESS | 2564 | _x_x_mozilla_page_load 05:26:33 INFO - PROCESS | 2564 | _x_x_mozilla_page_load_details 05:26:33 INFO - PROCESS | 2564 | |i|pagename|runs| 05:26:33 INFO - PROCESS | 2564 | |0;dojo.html;2985.2686091907485;3451.292485040567;3481.129717570606;3483.512308276841;3503.649635036496;5634.577406694499;5714.657120572769;5734.598578099534;5838.182654428383;5857.619254748438;4791.568758811706;5644.350899646542;5659;5696.3733989261145;5843.620164689303;5128.435872054071;5164.819231326905;5239.187925871496;5259.342582177228;5295.814646487354;569.5662133738726;5133.255677926699;5221.269022336857;5229.973850130763;5232.502912223333;5190.948090519084;5211.661242019276;5240.266362709223;5256.500632439912;5275.472452754732;4394.12117576486;5243.134882744343;5284.4187139414635;5291.3385826771655;5351.438098999588;5137.17805151174;5162.819301324435;5222.1383471727495;5232.9738351308;5284.5243928046275;4721.858344249678;5370.4361042090795;5654.830355089354;5731.083026715747;5741.540676745851;6095.3778866513585;6160.322364539897;6177.474914632262;6214.844628884314;6219.40915613016;6103.216452666793;6115.088229002391;6162.722677479479;6221.844453888643;6221.844453888689;5911.940880591225;5917.940820591825;5924.437178468082;5929.318128415244;5931.73307201177;6129.572745043327;6188.628682279078;6289.842753931097;6329.113924050636;6399.744010239631;5177.404598471148;6103.30736498198;6155.6614386209185;6213.502919766408;6215.689215539295;6172.4136207060255;6181.536384771161;6223.439890409885;6254.937450625526;6256.311805701369;5077.39071311445;6069.302030266487;6102.8225305960505;6192.81421557354;6221.06683997404;6097.664628445478;6110.236220472441;6117.724702388403;6126.509879209652;6126.785562505291;4769.904601907942;4856.17445034345;4871.537203965618;4895.6083513318845;4922.606191504671;4347.478302603708;4421.867343979686;4424.778761061934;4486.5962063414445;4531.728096314232;5764.452377024176;5808.186853840466;5818.883622327529;5854.414558544111;5935.703214839241;6219.378062193744;6235.594686345373;6248.062790581449;6248.75004999804;6248.968755156286;4769.57073863354;5660.066089095336;5743.885122297531;5773.942260577424;5811.244538210006;4768.3085952536685;5393.4336894626285;5428.701421421781;5433.972830135824;5455.072637651609;5212.791488340424;6173.536984726074;6402.231732192166;6441.25925518562;6456.192975878015;6133.1510485532735;6145.033589251397;6164.229471316085;6191.628502289877;6210.658413787104;6439.484841212786;6463.385978332051;6475.805725828232;6488.480921526172;6492.480601551959;6104.186323682738;6105.511559075352;6176.536759743127;6188.721507532262;6204.007358822567;6128.380904898417;6138.754449821957;6225.128482012612;6238.812835614939;6239;6071.27144742616;6085;6137.9693101535995;6148.723307451086;6153.2000839890525;6041.637501749733;6106.847328816815;6132.294786099477;6137.447629713347;6143.754249829957;5861.765529378948;6067;6068.150458935665;6093.817185484442;6099.207103076749;6181.350958149509;6213.1922850029205;6227.221597300338;6237.095621135;6240.968795155995;5906.616069955439;6012.819615411545;6026.7287972040485;6032.728527216198;6106.053561697944 05:26:33 INFO - PROCESS | 2564 | |1;ext.html;478.57406907852;504.4249555506723;505.27997603415133;506.55676283252154;521.7964993652474;37314;39825;39903.401448978286;39996.60003399965;40047;40607;41378;41400.58599414005;41437.58562414375;41456.17087658245;9284.860727089099;9393.62425502979;9409.52952352383;9466.763330916749;9574.664886728966;9449;9536.761580960498;9538.427694338361;9556.904430955707;9559.61761529538;9469.95830458649;9470.052994700543;9506.619735210617;9554.713358599252;9566.330356875022;9398.38910470824;9420.105090016437;9448.858267126;9510.857337139949;9520.761980950463;8914.108589141098;9405.106514881076;9425;9426.952865235698;9470.763230919214;31325.530117048263;37986.81006594977;37999.240015199815;38077;38491.84524464243;2989.7907146499756;3115.454795410794;3273.918152046194;3376.1897144685345;3512.1746389598425;23214.65178022331;24023.51952960931;28422.85788571044;29395.70604293951;29623.851880740673;3143.7327827134723;3148.4962406015156;3153.3377990622;3160.952585711216;3240.5301231321564;446888;447227;451085;453300;453333.9999999967;28709.70806313721;33187.66812331894;33261.00216993446;33263;33351.83268585588;33806.66193338084;33852.49221261683;33904.83047584746;35051;35079.298414031575;16471.91764041172;16502;16619.6676066478;16650.916745416194;16739.581510462092;12525.185862919063;12649;12774.68063298425;12849.48602055926;12895.935520322339;15575.376984920704;18828.24687012504;18998.905005474884;19128.904355478135;19162.04189790505;7819.3744500439825;7994.400419968526;8006.039275287003;8014.83970320602;8070.717524886601;800.8358286551243;864.9654013839502;880.1462581296261;1132.7338075552238;1135.318808714781;29670.92195749991;29920.85039574832;30061.166046358405;30282.54576181359;30334.089977300275;1010.908219123345;1030.7526193713454;1089.199438412766;1160.7388337623968;1223.718544734716;13806.309684515734;15493.922530387501;15632.765508517381;15652.217389130497;15945.521634350987;559312;583421.0828945828;662760;664695;672450;56294.87410251772;57627;58100;58125.709371452875;58293.708531457916;1403.9368228429746;1414.4907833180048;1423.0465588056027;1423.4590855474853;1433.0756661952983;30097.773003511527;30156.84921575378;30193.222354339723;30228.09315720532;30287.646376409266;48259;49398.50601494083;49439;49744;49878.501214988835;596032;601311;606326;607384;607773;26019.349516262246;26456.206313810617;26833.731662683906;26877.059302924303;26925.73074269232;46963.060738785716;48250;48462.03075938532;48768.2684759736;49357;38383.80808095942;38962;39225.2154956905;39368.80315598404;40437.786866392875;31553.21116972094;38571.22857542778;38582.6141738579;38851.417228741026;39137.608623913395;31466;37991.81004094962;38422;38530.22939541138;38572.421413678254;33523.32953340871;33726.49410258897;34206.657933421346;34375.82812085924;34651;22361.66457503171;24881.507109573482;25440;25699.614505782054;25892.611610825476 05:26:33 INFO - PROCESS | 2564 | |2;jquery.html;340.35672578826023;369.8520591763296;377.3170561284076;387.1057856351826;396.00997506234415;19265.61468770624;19515.316963906247;20078.698819517682;20758.792412075876;20765.99999999998;11621.244619099753;11845.585404510844;11890.7621847563;12146.878531214685;12178.147529672926;5131.692098474095;5318.734063296839;5344.064788661988;5348.411674715778;5349;3781.8487260509683;5671.7731290748325;5989.490893274077;5989.88020239595;6017.669028203447;5565.6104072714925;5870.236869207019;5982.910256346158;6004.789832355868;6009.609375390585;5848.941510584882;5962.34414214436;5970.402959704038;5971.104334349839;5974.820755377345;4914.901701965977;5256.369235691704;5279.52484276417;5299.072662284085;5349.117395629716;13085.28030958297;14864.70270594582;20207.797922020887;25302.114425995187;25316;2135.562209746998;2223.8888055597317;2295.437617783637;2341.1805867946255;2417.7703118203744;9980.351277167034;10424.583016679324;14380.424783008668;14789.852101479062;15382.3077961492;1833.5097909224855;1844.4927644897625;1858.1173942377263;1883.6138591588692;1901.5341241395809;125400;130479;131300;132171;132342;9743.512824358753;10010.949945250373;11138.66584002465;11519.539218431337;11815.232009919328;14558.927205363905;14593;14619.976601637783;14629.487967921072;14658.780118298235;8611.311095112376;8685.261752751087;8702.129787021247;8733.82070949186;8801.163889430494;7151.427885769126;8687.435316704394;8702.956485217534;8776.210141087333;8889.066648001995;4077.7757223353;4088.4889388826396;4573.679842411066;4591.9081618367445;4638.768061596907;1409.3376113226766;1419.8012278281049;1632.2328505602347;1675.7318828987547;1687.1817168673429;852.1691350932791;917.5366439947784;937.3204426790522;948.6774496671165;1027.2655051638114;17274;19470.415887523395;20482.692759608617;21135.26026589062;21512.354629361143;807.4549678966674;929.7768535551419;935.6771913689767;946.6166202687922;1014.7818219082933;9194.540272986324;11049.944750276198;11255.549778008952;11329.830052549218;11406.714832129097;574189;581556;583804;584188;590157;61389;102649;130952.3452382751;134969.65030349768;135766;989.2975987049132;1008.3546530220665;1016.8068067067086;1027.0499787696351;1029.7477118106112;18734.90632546883;18747.625047499245;18818.52953676169;19053.71419428682;19201.61596768085;52672.20991685051;52995;54624.45375546194;54663.45336546584;55176.72411637916;77965.61017194878;78868.21131788609;79210.4157916827;79288;79309.29324541113;6863.210730766026;7336.816579585553;7422.480426370318;7784.571848548384;8387.874181887155;11965.803419657965;12933.224006559636;13145.60563183106;13146.934265328613;13439.193648381128;11109.732554504044;11319.717007074889;11363.943180284046;11375.976162145447;11408.201425900108;9174.53207486799;11005.504752286326;11299.43502824839;11311.434428278388;11342.149338799458;11402.828957565478;11679.65683946323;11717.76564468723;11805.586804461802;11850.111241657083;6902.137232845895;7382.224866388952;7422.257774222535;7599.810004749926;8350;9965.601375944882;11324.433778311217;11372.886271137513;11382.975532202141;11473.770524589294 05:26:33 INFO - PROCESS | 2564 | |3;mootools.html;1560.6332511859714;1594.9122798246096;1786.2051387132724;1802.5673838278817;1869.906504674766;7814.843703125941;7834.843303133934;8200.466969646968;8507.829843403128;8713.782155446117;8520.40357174998;8669.869951950726;8698.478091314526;8808.823823523542;8815.426997245175;2355.9057637694514;2403.879806009701;2408.4701365699607;2416.3113512648897;2416.5529377065213;2378.203301893868;2394.353524548371;2410.0600765701392;2420.9757902421015;2430.0765709030543;2319.7796209360085;2355.2698663414394;2357.8938947747392;2394;2411.602085655865;2377;2382.1662418153683;2401.8799060047013;2406.482606239667;2409.9759002410015;2026.6149431608094;2103.8737675739503;2349;2396.604560247557;2399.7600239976036;7681.308682218627;8660.740177794676;8709.520976346299;8731.781705457415;8877.422967507156;5315.627906046579;5405.648632838853;5491.395946445888;5498.725063746797;5502.5597952163735;3155.8954365971954;3598.9280214395826;3671.0271777978855;3741.38267185914;3767.0958969847316;942.9245660347156;1081.2431298091367;1082.1883587309517;1099.7470581766158;1105.745678493943;11355.829662555068;11430.599929002445;11556.306621602731;11567.42162891852;11647.068234541217;4678.906421871611;4737.881552961135;4860.392450943632;4861.246506791453;4864.100141473838;10145;10159.4920253987;10166.644167454104;10179.7455063624;10180;5386.165144402624;5394.676319420847;5398.73006349681;5404.486573775564;5429.67421954684;4501.437320334958;4771.093492236464;4789.425268967677;4854.368932038883;4948.851534453972;4100.752267719993;4105.794710264475;4165.729227600196;4189.581041895786;4192.4759405074365;1306.399056434032;1329.2224048931485;1330.0955350361653;1335.0654541820763;1339.71196192819;823.9670413183526;850.4769566716549;931.9254459643212;955.5890966884307;979.456401697065;5558.916616250759;6005.939940600538;6106.45041946227;6128.264608247039;6293.842653933688;919.7884486567993;944.4616568555995;949.4872768704822;953.2659851913987;980.7499087732582;3865.1496670732395;3935.842566297316;3939.8227079781477;3943.1719338939142;3988.3020471417967;8948.463092214488;10352.585896564053;10570.947145264225;10887.945560272148;10917.727056823644;10670.466476676134;10737.83893241602;10808.567657293777;10846;10862.565497380016;8127.187281271826;8182.263596276363;8345.915031045804;8351.749447516584;8371.83256334882;5880.588358815013;5989.5507836913275;6138.5396095292135;6151.538634602333;6153.538484613582;9701.417914925127;9888.159506441745;10172.593096276067;10205.285630006125;10234;9991.650292239738;10161.695149145537;10289.794204116026;10355.896441035795;10364.42995635217;6958.373746362851;7006.495957408777;7169.677364518713;7171.605561694157;7183.748568800067;7076.575405475688;7079.787606371817;8269.255766981001;8273.131321211427;8368.037675667376;7298.37963773064;7532.962335188289;7533.698652053857;7630.046244219473;7638.656260468181;7282.089738782653;7393.112826460859;7434.442416818652;7533.510321829063;7534.246575342422;6079.604825686316;7045.823854403681;7129.714811407693;7134.750283740252;7142.857142857218;4826.0830442215865;5008.348914641073;5103.209002604453;5119.232115182753;5197.974010129925;5195.68825870449;5205.479452054916;5210.505002024802;5332.5467335276935;5336.066188416934 05:26:33 INFO - PROCESS | 2564 | |4;prototype.html;269.8650674662668;284.1758899192342;300.91724775686686;309.7800561601263;320.50161998092955;4361.694681372305;5355.277037599923;5604.8038318658855;5622.100463925773;5660.264165658464;4408.781589439711;5258.6581872178285;5493.92868390664;5536.557075433966;5588.385277619459;3770.2271034438013;3864.3623802072625;4189.895252618679;4206;4214.346776249687;3846.0577158596184;4217;4219.662427005832;4222.725522841021;4226.535081141087;3496.965030349702;3927.8428862845594;4112.403701463286;4123.546409894909;4141.979290103545;3607.6651638893513;4081.999820088165;4130.8554200602985;4137.379393091031;4148.170365926811;3084.5835812165296;3564.8967109405316;3618.203995121069;3623.9852841204506;3638.909027274313;5036.345275114249;5153.711572106973;5399.487048730364;5436.374816896028;5452.454754524555;4364.083542456088;4634.582887540104;4761.357216775726;4780;4822.807087716487;5417.8103766367985;5475.561955043587;5610.24261724666;5734.770609175628;5903.49820265278;3641.198936234051;3918.2163567286657;3923.8234279457492;3946.3622596622654;3970.4316794865867;4973.676711013773;6106.236720409949;6203.596766210182;6207.7206525706;6293.930031894589;3948.9210215795524;3965.4250133731025;3977.3835055565855;3990.1421194443333;3992.261431635157;3627.546556680415;3741.30785804627;4020.4773379460485;4075.2053349597136;4084.632383085477;4720.221163507983;4763.356946812136;4786.377770889762;4802.6638135330195;4804.591609713144;5304.92042619361;5352.839414817562;5698.430156984268;5894.528437724972;5956.404359564096;1106.7841770854768;1392.276016471429;1392.317764295488;1394.76289030865;1397.6855207578215;719.3274288540232;815.4251252866715;820.0200760091633;838.4382463749303;850.974470765878;3893.9415908761393;4228.006418491652;4574.588287054181;4591.380163677927;4603.838865639754;4341.283688191413;4354.738715677006;4667.346571479996;4772.188727916263;4876.487968763229;4016.879493615196;4180.979095104505;4394.517097839775;4608.539146085364;4615.815367385267;2354.387859156597;2542.338991862129;2599.5320842248575;2721.7207912281197;2745.409736906575;4692.225782745877;5189.054104710734;5407.540359069444;5434.212039254365;5450.345958485007;4812.353205736831;4848.127337079289;5056.898862022739;5067.061104559347;5293.311869456946;4574.313852922021;4598.678092533491;4611.017262577057;4786.928196077061;5238.68567885928;5067.898642027212;5120.359955005624;5134.152864777419;5155.793768249378;5181.937702770998;3962.108525581721;4668.5798278155125;4718.64067966017;4755.2153894607;4941.678790878582;5189.160021584076;5237.685738855681;5335.1723586497665;5377.542908852792;5395.298611180521;3000.78994470385;3094.3656550407563;3494.1264683829045;3509.438489841613;3549.172176329244;4707.693999889996;5122.769475373542;5139;5150.403374953777;5768.653880767168;5040.49595040493;5495.340559132929;5775.873704627557;5910.054391297372;5944.8811023780145;5693.5445164387575;5843.620164689281;5848.5028772553105;5909.940900590939;5913.497352725067;5235.554977826928;5474.452554744494;5594.804181853616;5737.614807263734;5764.884702306014;5448.591355648263;5635.633683810539;5857.891159182776;5865.829643032105;5923.881522369442;5213.6350455467755;5730.885382292414;5731.8567035824435;5763.193152958505;5782.1615865698395 05:26:33 INFO - PROCESS | 2564 | |5;yui.html;706.8550947055854;750.0848964263598;791.9247671471209;828.8839562461253;850.9106543812895;14630.26848657567;15069.321880515376;15142.470013549528;15286.31211595478;15317.234138293083;14494.057886237413;15337;15349.846501534981;15549.144797036186;15619.984700994455;870.3820287595782;875.7241468937297;876.8772371867943;877.2937785083005;879.4767113567449;693.9097917270773;849.0914721248267;878.2490970220433;892.3218354050903;894.7449976756626;930.9581068851884;931.8741969834088;932.6129656192672;935.2097477631424;935.7005758157393;787.7951732549521;899.8785164002842;902.1835239108617;929.1730359979623;933.1601558597249;685.4893104637027;686.3342557719054;686.3959715450421;687.9484038697118;696.9163700355939;14459.710805783825;14465.20441375724;14510.782338264935;14830.925845370812;14879;10209.9489502552;13613.659658508517;13632.727345453135;13696.041277110606;13748.381322840496;9595.472249026301;11468.713282168013;11487.21948098046;11510.399651529948;12046.216995895238;3667.211549516867;3710.1466662667467;3769.11425814936;3770.962290377116;3894.1043559981363;86082;86779;86779.56610216972;87066;87708;10298.433586152683;10520.631777887736;10614.522346494427;10754.78490430187;11043.779124417466;17287.74068388975;20941.371758847257;20941.476463088544;20970.580588388453;20981.790182097982;7667.731629392944;7713.267239612228;7836.177201393885;7873.803154921173;7914.564698941498;4372.300431930876;4404.053128577307;4458.108378324348;4504.797284122223;4638.582527572467;2790.6651201855907;3482.6865582097735;3527.5766907971206;3585.4442561403025;3593.658602432765;731.7621772923821;733.7065173930471;739.2201227704822;742.3281929853491;750.6509473095037;632.8481164520482;639.587466084373;674.9527533072638;685.2633419074415;700.8423104801481;8843.46939183651;9133.315001374924;9269.67556135532;9672.22622190223;9722.95138524317;576.9740361683735;598.956274215232;642.0401499757892;692.6675195905993;696.251529605676;4459.732416054982;4589.380433641482;4593.311003349458;4608.331791890158;4642.117997580516;94218;96861;97162;97271;97408.51295743475;71398;81571.5921420401;84205.31589368178;86229.56885215659;86382.56808715916;75523.62238188773;75548.48903021909;76263;76491.47017059628;77005.61497192478;9001.459912405277;9049.59276832531;9058.094190580889;9091.768263869137;9130.543472826199;48378.51621483881;48432.27351589659;48447;48524;50582.73543161451;55886.72056639854;56197.59506012382;57583.408746605666;62053.68973155105;62430.687846560475;12562.37188140571;12850.935745321214;12895.613131606067;12901.875585497433;13016.414261358072;14105.923969906586;14191.858081419054;14362.353694084;14407.351669174703;14702.264886755833;13152.605421837361;13187.340632968122;13204.471821127432;13257.801132983206;13468.124571902796;10400.531976061247;13283.402246898719;13602.591922242349;13617.455301787817;13896.791548126988;12105.81841272364;12830.909372703425;12874.806877897026;12938.547150849676;13040.608781736562;10902.40036797952;11145.83281250798;11416.828747568627;11484.196106272815;11639.418029098342;10840.837387439036;11103.50034248477;11241.437928103398;11335;11351.262167959056 05:26:33 INFO - PROCESS | 2564 | __end_tp_report 05:26:33 INFO - PROCESS | 2564 | __start_cc_report 05:26:33 INFO - PROCESS | 2564 | _x_x_mozilla_cycle_collect,420 05:26:33 INFO - PROCESS | 2564 | __end_cc_report 05:26:33 INFO - PROCESS | 2564 | __startTimestamp1464783993031__endTimestamp 05:26:33 INFO - PROCESS | 2564 | 05:26:33 INFO - PROCESS | 2564 | ------- Summary: start ------- 05:26:33 INFO - PROCESS | 2564 | Number of tests: 6 05:26:33 INFO - PROCESS | 2564 | 05:26:33 INFO - PROCESS | 2564 | [#0] dojo.html Cycles:165 Average:5690.63 Median:6032.73 stddev:775.67 (12.9%) stddev-sans-first:748.45 05:26:33 INFO - PROCESS | 2564 | Values: 2985.3 3451.3 3481.1 3483.5 3503.6 5634.6 5714.7 5734.6 5838.2 5857.6 4791.6 5644.4 5659.0 5696.4 5843.6 5128.4 5164.8 5239.2 5259.3 5295.8 569.6 5133.3 5221.3 5230.0 5232.5 5190.9 5211.7 5240.3 5256.5 5275.5 4394.1 5243.1 5284.4 5291.3 5351.4 5137.2 5162.8 5222.1 5233.0 5284.5 4721.9 5370.4 5654.8 5731.1 5741.5 6095.4 6160.3 6177.5 6214.8 6219.4 6103.2 6115.1 6162.7 6221.8 6221.8 5911.9 5917.9 5924.4 5929.3 5931.7 6129.6 6188.6 6289.8 6329.1 6399.7 5177.4 6103.3 6155.7 6213.5 6215.7 6172.4 6181.5 6223.4 6254.9 6256.3 5077.4 6069.3 6102.8 6192.8 6221.1 6097.7 6110.2 6117.7 6126.5 6126.8 4769.9 4856.2 4871.5 4895.6 4922.6 4347.5 4421.9 4424.8 4486.6 4531.7 5764.5 5808.2 5818.9 5854.4 5935.7 6219.4 6235.6 6248.1 6248.8 6249.0 4769.6 5660.1 5743.9 5773.9 5811.2 4768.3 5393.4 5428.7 5434.0 5455.1 5212.8 6173.5 6402.2 6441.3 6456.2 6133.2 6145.0 6164.2 6191.6 6210.7 6439.5 6463.4 6475.8 6488.5 6492.5 6104.2 6105.5 6176.5 6188.7 6204.0 6128.4 6138.8 6225.1 6238.8 6239.0 6071.3 6085.0 6138.0 6148.7 6153.2 6041.6 6106.8 6132.3 6137.4 6143.8 5861.8 6067.0 6068.2 6093.8 6099.2 6181.4 6213.2 6227.2 6237.1 6241.0 5906.6 6012.8 6026.7 6032.7 6106.1 05:26:33 INFO - PROCESS | 2564 | 05:26:33 INFO - PROCESS | 2564 | [#1] ext.html Cycles:180 Average:67699.77 Median:38402.90 stddev:151854.88 (395.4%) stddev-sans-first:152197.00 05:26:33 INFO - PROCESS | 2564 | Values: 478.6 504.4 505.3 506.6 521.8 37314.0 39825.0 39903.4 39996.6 40047.0 40607.0 41378.0 41400.6 41437.6 41456.2 9284.9 9393.6 9409.5 9466.8 9574.7 9449.0 9536.8 9538.4 9556.9 9559.6 9470.0 9470.1 9506.6 9554.7 9566.3 9398.4 9420.1 9448.9 9510.9 9520.8 8914.1 9405.1 9425.0 9427.0 9470.8 31325.5 37986.8 37999.2 38077.0 38491.8 2989.8 3115.5 3273.9 3376.2 3512.2 23214.7 24023.5 28422.9 29395.7 29623.9 3143.7 3148.5 3153.3 3161.0 3240.5 446888.0 447227.0 451085.0 453300.0 453334.0 28709.7 33187.7 33261.0 33263.0 33351.8 33806.7 33852.5 33904.8 35051.0 35079.3 16471.9 16502.0 16619.7 16650.9 16739.6 12525.2 12649.0 12774.7 12849.5 12895.9 15575.4 18828.2 18998.9 19128.9 19162.0 7819.4 7994.4 8006.0 8014.8 8070.7 800.8 865.0 880.1 1132.7 1135.3 29670.9 29920.9 30061.2 30282.5 30334.1 1010.9 1030.8 1089.2 1160.7 1223.7 13806.3 15493.9 15632.8 15652.2 15945.5 559312.0 583421.1 662760.0 664695.0 672450.0 56294.9 57627.0 58100.0 58125.7 58293.7 1403.9 1414.5 1423.0 1423.5 1433.1 30097.8 30156.8 30193.2 30228.1 30287.6 48259.0 49398.5 49439.0 49744.0 49878.5 596032.0 601311.0 606326.0 607384.0 607773.0 26019.3 26456.2 26833.7 26877.1 26925.7 46963.1 48250.0 48462.0 48768.3 49357.0 38383.8 38962.0 39225.2 39368.8 40437.8 31553.2 38571.2 38582.6 38851.4 39137.6 31466.0 37991.8 38422.0 38530.2 38572.4 33523.3 33726.5 34206.7 34375.8 34651.0 22361.7 24881.5 25440.0 25699.6 25892.6 05:26:33 INFO - PROCESS | 2564 | 05:26:33 INFO - PROCESS | 2564 | [#2] jquery.html Cycles:180 Average:34179.68 Median:2318.31 stddev:97573.41 (4208.8%) stddev-sans-first:97814.05 05:26:33 INFO - PROCESS | 2564 | Values: 340.4 369.9 377.3 387.1 396.0 19265.6 19515.3 20078.7 20758.8 20766.0 11621.2 11845.6 11890.8 12146.9 12178.1 5131.7 5318.7 5344.1 5348.4 5349.0 3781.8 5671.8 5989.5 5989.9 6017.7 5565.6 5870.2 5982.9 6004.8 6009.6 5848.9 5962.3 5970.4 5971.1 5974.8 4914.9 5256.4 5279.5 5299.1 5349.1 13085.3 14864.7 20207.8 25302.1 25316.0 2135.6 2223.9 2295.4 2341.2 2417.8 9980.4 10424.6 14380.4 14789.9 15382.3 1833.5 1844.5 1858.1 1883.6 1901.5 125400.0 130479.0 131300.0 132171.0 132342.0 9743.5 10010.9 11138.7 11519.5 11815.2 14558.9 14593.0 14620.0 14629.5 14658.8 8611.3 8685.3 8702.1 8733.8 8801.2 7151.4 8687.4 8703.0 8776.2 8889.1 4077.8 4088.5 4573.7 4591.9 4638.8 1409.3 1419.8 1632.2 1675.7 1687.2 852.2 917.5 937.3 948.7 1027.3 17274.0 19470.4 20482.7 21135.3 21512.4 807.5 929.8 935.7 946.6 1014.8 9194.5 11049.9 11255.5 11329.8 11406.7 574189.0 581556.0 583804.0 584188.0 590157.0 61389.0 102649.0 130952.3 134969.7 135766.0 989.3 1008.4 1016.8 1027.0 1029.7 18734.9 18747.6 18818.5 19053.7 19201.6 52672.2 52995.0 54624.5 54663.5 55176.7 77965.6 78868.2 79210.4 79288.0 79309.3 6863.2 7336.8 7422.5 7784.6 8387.9 11965.8 12933.2 13145.6 13146.9 13439.2 11109.7 11319.7 11363.9 11376.0 11408.2 9174.5 11005.5 11299.4 11311.4 11342.1 11402.8 11679.7 11717.8 11805.6 11850.1 6902.1 7382.2 7422.3 7599.8 8350.0 9965.6 11324.4 11372.9 11383.0 11473.8 05:26:33 INFO - PROCESS | 2564 | 05:26:33 INFO - PROCESS | 2564 | [#3] mootools.html Cycles:180 Average:5718.12 Median:4978.60 stddev:3156.27 (63.4%) stddev-sans-first:3149.66 05:26:33 INFO - PROCESS | 2564 | Values: 1560.6 1594.9 1786.2 1802.6 1869.9 7814.8 7834.8 8200.5 8507.8 8713.8 8520.4 8669.9 8698.5 8808.8 8815.4 2355.9 2403.9 2408.5 2416.3 2416.6 2378.2 2394.4 2410.1 2421.0 2430.1 2319.8 2355.3 2357.9 2394.0 2411.6 2377.0 2382.2 2401.9 2406.5 2410.0 2026.6 2103.9 2349.0 2396.6 2399.8 7681.3 8660.7 8709.5 8731.8 8877.4 5315.6 5405.6 5491.4 5498.7 5502.6 3155.9 3598.9 3671.0 3741.4 3767.1 942.9 1081.2 1082.2 1099.7 1105.7 11355.8 11430.6 11556.3 11567.4 11647.1 4678.9 4737.9 4860.4 4861.2 4864.1 10145.0 10159.5 10166.6 10179.7 10180.0 5386.2 5394.7 5398.7 5404.5 5429.7 4501.4 4771.1 4789.4 4854.4 4948.9 4100.8 4105.8 4165.7 4189.6 4192.5 1306.4 1329.2 1330.1 1335.1 1339.7 824.0 850.5 931.9 955.6 979.5 5558.9 6005.9 6106.5 6128.3 6293.8 919.8 944.5 949.5 953.3 980.7 3865.1 3935.8 3939.8 3943.2 3988.3 8948.5 10352.6 10570.9 10887.9 10917.7 10670.5 10737.8 10808.6 10846.0 10862.6 8127.2 8182.3 8345.9 8351.7 8371.8 5880.6 5989.6 6138.5 6151.5 6153.5 9701.4 9888.2 10172.6 10205.3 10234.0 9991.7 10161.7 10289.8 10355.9 10364.4 6958.4 7006.5 7169.7 7171.6 7183.7 7076.6 7079.8 8269.3 8273.1 8368.0 7298.4 7533.0 7533.7 7630.0 7638.7 7282.1 7393.1 7434.4 7533.5 7534.2 6079.6 7045.8 7129.7 7134.8 7142.9 4826.1 5008.3 5103.2 5119.2 5198.0 5195.7 5205.5 5210.5 5332.5 5336.1 05:26:33 INFO - PROCESS | 2564 | 05:26:33 INFO - PROCESS | 2564 | [#4] prototype.html Cycles:180 Average:4426.97 Median:4783.19 stddev:1369.79 (28.6%) stddev-sans-first:1337.62 05:26:33 INFO - PROCESS | 2564 | Values: 269.9 284.2 300.9 309.8 320.5 4361.7 5355.3 5604.8 5622.1 5660.3 4408.8 5258.7 5493.9 5536.6 5588.4 3770.2 3864.4 4189.9 4206.0 4214.3 3846.1 4217.0 4219.7 4222.7 4226.5 3497.0 3927.8 4112.4 4123.5 4142.0 3607.7 4082.0 4130.9 4137.4 4148.2 3084.6 3564.9 3618.2 3624.0 3638.9 5036.3 5153.7 5399.5 5436.4 5452.5 4364.1 4634.6 4761.4 4780.0 4822.8 5417.8 5475.6 5610.2 5734.8 5903.5 3641.2 3918.2 3923.8 3946.4 3970.4 4973.7 6106.2 6203.6 6207.7 6293.9 3948.9 3965.4 3977.4 3990.1 3992.3 3627.5 3741.3 4020.5 4075.2 4084.6 4720.2 4763.4 4786.4 4802.7 4804.6 5304.9 5352.8 5698.4 5894.5 5956.4 1106.8 1392.3 1392.3 1394.8 1397.7 719.3 815.4 820.0 838.4 851.0 3893.9 4228.0 4574.6 4591.4 4603.8 4341.3 4354.7 4667.3 4772.2 4876.5 4016.9 4181.0 4394.5 4608.5 4615.8 2354.4 2542.3 2599.5 2721.7 2745.4 4692.2 5189.1 5407.5 5434.2 5450.3 4812.4 4848.1 5056.9 5067.1 5293.3 4574.3 4598.7 4611.0 4786.9 5238.7 5067.9 5120.4 5134.2 5155.8 5181.9 3962.1 4668.6 4718.6 4755.2 4941.7 5189.2 5237.7 5335.2 5377.5 5395.3 3000.8 3094.4 3494.1 3509.4 3549.2 4707.7 5122.8 5139.0 5150.4 5768.7 5040.5 5495.3 5775.9 5910.1 5944.9 5693.5 5843.6 5848.5 5909.9 5913.5 5235.6 5474.5 5594.8 5737.6 5764.9 5448.6 5635.6 5857.9 5865.8 5923.9 5213.6 5730.9 5731.9 5763.2 5782.2 05:26:33 INFO - PROCESS | 2564 | 05:26:33 INFO - PROCESS | 2564 | [#5] yui.html Cycles:180 Average:19096.96 Median:48439.64 stddev:26473.14 (54.7%) stddev-sans-first:26511.39 05:26:33 INFO - PROCESS | 2564 | Values: 706.9 750.1 791.9 828.9 850.9 14630.3 15069.3 15142.5 15286.3 15317.2 14494.1 15337.0 15349.8 15549.1 15620.0 870.4 875.7 876.9 877.3 879.5 693.9 849.1 878.2 892.3 894.7 931.0 931.9 932.6 935.2 935.7 787.8 899.9 902.2 929.2 933.2 685.5 686.3 686.4 687.9 696.9 14459.7 14465.2 14510.8 14830.9 14879.0 10209.9 13613.7 13632.7 13696.0 13748.4 9595.5 11468.7 11487.2 11510.4 12046.2 3667.2 3710.1 3769.1 3771.0 3894.1 86082.0 86779.0 86779.6 87066.0 87708.0 10298.4 10520.6 10614.5 10754.8 11043.8 17287.7 20941.4 20941.5 20970.6 20981.8 7667.7 7713.3 7836.2 7873.8 7914.6 4372.3 4404.1 4458.1 4504.8 4638.6 2790.7 3482.7 3527.6 3585.4 3593.7 731.8 733.7 739.2 742.3 750.7 632.8 639.6 675.0 685.3 700.8 8843.5 9133.3 9269.7 9672.2 9723.0 577.0 599.0 642.0 692.7 696.3 4459.7 4589.4 4593.3 4608.3 4642.1 94218.0 96861.0 97162.0 97271.0 97408.5 71398.0 81571.6 84205.3 86229.6 86382.6 75523.6 75548.5 76263.0 76491.5 77005.6 9001.5 9049.6 9058.1 9091.8 9130.5 48378.5 48432.3 48447.0 48524.0 50582.7 55886.7 56197.6 57583.4 62053.7 62430.7 12562.4 12850.9 12895.6 12901.9 13016.4 14105.9 14191.9 14362.4 14407.4 14702.3 13152.6 13187.3 13204.5 13257.8 13468.1 10400.5 13283.4 13602.6 13617.5 13896.8 12105.8 12830.9 12874.8 12938.5 13040.6 10902.4 11145.8 11416.8 11484.2 11639.4 10840.8 11103.5 11241.4 11335.0 11351.3 05:26:33 INFO - PROCESS | 2564 | -------- Summary: end -------- 05:26:33 INFO - PROCESS | 2564 | 05:26:33 INFO - TEST-INFO | 2564: exit 0 05:26:33 INFO - TEST-OK | dromaeo_css | took 1113077ms 05:26:33 INFO - TEST-START | kraken 05:26:33 INFO - operating with platform_type : mac_ 05:26:33 INFO - Initialising browser for kraken test... 05:26:33 INFO - TEST-INFO | started process 2568 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpyetzHO/profile http://localhost:54881/getInfo.html) 05:26:36 INFO - PROCESS | 2568 | __metrics Screen width/height:1600/1200 05:26:36 INFO - PROCESS | 2568 | colorDepth:24 05:26:36 INFO - PROCESS | 2568 | Browser inner width/height: 1024/689 05:26:36 INFO - PROCESS | 2568 | __metrics 05:26:36 ERROR - PROCESS | 2568 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 05:26:37 INFO - TEST-INFO | 2568: exit 0 05:26:37 INFO - Browser initialized. 05:26:37 INFO - Running cycle 1/1 for kraken test... 05:26:37 INFO - TEST-INFO | started process 2570 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpyetzHO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:49 INFO - PROCESS | 2570 | RSS: Main: 252674048 05:26:49 INFO - PROCESS | 2570 | 05:26:52 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54881/tests/kraken/driver.html?testName=audio-beat-detection) 05:26:53 INFO - PROCESS | 2570 | RSS: Main: 265543680 05:26:53 INFO - PROCESS | 2570 | 05:26:55 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54881/tests/kraken/driver.html?testName=audio-dft) 05:26:56 INFO - PROCESS | 2570 | RSS: Main: 269647872 05:26:56 INFO - PROCESS | 2570 | 05:26:59 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54881/tests/kraken/driver.html?testName=audio-fft) 05:27:00 INFO - PROCESS | 2570 | RSS: Main: 270196736 05:27:00 INFO - PROCESS | 2570 | 05:27:02 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54881/tests/kraken/driver.html?testName=audio-oscillator) 05:27:02 INFO - PROCESS | 2570 | RSS: Main: 271036416 05:27:02 INFO - PROCESS | 2570 | 05:27:04 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54881/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:27:05 INFO - PROCESS | 2570 | RSS: Main: 271519744 05:27:05 INFO - PROCESS | 2570 | 05:27:08 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54881/tests/kraken/driver.html?testName=imaging-darkroom) 05:27:09 INFO - PROCESS | 2570 | RSS: Main: 271994880 05:27:09 INFO - PROCESS | 2570 | 05:27:12 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54881/tests/kraken/driver.html?testName=imaging-desaturate) 05:27:12 INFO - PROCESS | 2570 | RSS: Main: 272109568 05:27:12 INFO - PROCESS | 2570 | 05:27:15 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54881/tests/kraken/driver.html?testName=json-parse-financial) 05:27:16 INFO - PROCESS | 2570 | RSS: Main: 272429056 05:27:16 INFO - PROCESS | 2570 | 05:27:18 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54881/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:27:18 INFO - PROCESS | 2570 | RSS: Main: 271896576 05:27:18 INFO - PROCESS | 2570 | 05:27:20 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54881/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:27:21 INFO - PROCESS | 2570 | RSS: Main: 271884288 05:27:21 INFO - PROCESS | 2570 | 05:27:23 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54881/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:27:24 INFO - PROCESS | 2570 | RSS: Main: 272117760 05:27:24 INFO - PROCESS | 2570 | 05:27:27 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54881/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:27:27 INFO - PROCESS | 2570 | RSS: Main: 272191488 05:27:27 INFO - PROCESS | 2570 | 05:27:30 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54881/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:27:31 INFO - PROCESS | 2570 | RSS: Main: 272134144 05:27:31 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | Cycle 1(1): loaded http://localhost:54881/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54881/tests/kraken/driver.html?testName=ai-astar) 05:27:33 INFO - PROCESS | 2570 | RSS: Main: 273231872 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | __start_tp_report 05:27:33 INFO - PROCESS | 2570 | _x_x_mozilla_page_load 05:27:33 INFO - PROCESS | 2570 | _x_x_mozilla_page_load_details 05:27:33 INFO - PROCESS | 2570 | |i|pagename|runs| 05:27:33 INFO - PROCESS | 2570 | |0;ai-astar;135;116;115;116;115;118;118;94;168;151 05:27:33 INFO - PROCESS | 2570 | |1;audio-beat-detection;166;150;170;162;121;141;140;119;168;115 05:27:33 INFO - PROCESS | 2570 | |2;audio-dft;197;199;207;238;200;194;199;201;195;200 05:27:33 INFO - PROCESS | 2570 | |3;audio-fft;72;80;74;145;60;59;61;63;134;69 05:27:33 INFO - PROCESS | 2570 | |4;audio-oscillator;69;73;121;73;74;94;116;70;73;112 05:27:33 INFO - PROCESS | 2570 | |5;imaging-gaussian-blur;81;129;125;90;93;129;127;91;104;90 05:27:33 INFO - PROCESS | 2570 | |6;imaging-darkroom;97;101;99;99;99;98;125;99;98;104 05:27:33 INFO - PROCESS | 2570 | |7;imaging-desaturate;90;90;93;137;131;92;99;92;145;143 05:27:33 INFO - PROCESS | 2570 | |8;json-parse-financial;56;58;60;58;60;61;85;81;52;55 05:27:33 INFO - PROCESS | 2570 | |9;json-stringify-tinderbox;64;72;67;77;62;100;66;78;61;92 05:27:33 INFO - PROCESS | 2570 | |10;stanford-crypto-aes;83;100;110;90;88;88;103;110;153;112 05:27:33 INFO - PROCESS | 2570 | |11;stanford-crypto-ccm;138;140;138;139;141;139;140;169;170;150 05:27:33 INFO - PROCESS | 2570 | |12;stanford-crypto-pbkdf2;170;171;168;169;184;224;200;170;169;167 05:27:33 INFO - PROCESS | 2570 | |13;stanford-crypto-sha256-iterative;69;66;78;66;60;70;87;62;76;82 05:27:33 INFO - PROCESS | 2570 | __end_tp_report 05:27:33 INFO - PROCESS | 2570 | __start_cc_report 05:27:33 INFO - PROCESS | 2570 | _x_x_mozilla_cycle_collect,1076 05:27:33 INFO - PROCESS | 2570 | __end_cc_report 05:27:33 INFO - PROCESS | 2570 | __startTimestamp1464784053517__endTimestamp 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | ------- Summary: start ------- 05:27:33 INFO - PROCESS | 2570 | Number of tests: 14 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#0] ai-astar Cycles:10 Average:124.60 Median:126.50 stddev:21.20 (16.8%) stddev-sans-first:22.15 05:27:33 INFO - PROCESS | 2570 | Values: 135.0 116.0 115.0 116.0 115.0 118.0 118.0 94.0 168.0 151.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:156.00 stddev:21.35 (13.7%) stddev-sans-first:21.27 05:27:33 INFO - PROCESS | 2570 | Values: 166.0 150.0 170.0 162.0 121.0 141.0 140.0 119.0 168.0 115.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#2] audio-dft Cycles:10 Average:203.00 Median:200.00 stddev:12.81 (6.4%) stddev-sans-first:13.40 05:27:33 INFO - PROCESS | 2570 | Values: 197.0 199.0 207.0 238.0 200.0 194.0 199.0 201.0 195.0 200.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#3] audio-fft Cycles:10 Average:81.70 Median:66.00 stddev:31.31 (47.4%) stddev-sans-first:33.01 05:27:33 INFO - PROCESS | 2570 | Values: 72.0 80.0 74.0 145.0 60.0 59.0 61.0 63.0 134.0 69.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#4] audio-oscillator Cycles:10 Average:87.50 Median:73.00 stddev:21.18 (29.0%) stddev-sans-first:21.38 05:27:33 INFO - PROCESS | 2570 | Values: 69.0 73.0 121.0 73.0 74.0 94.0 116.0 70.0 73.0 112.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#5] imaging-gaussian-blur Cycles:10 Average:105.90 Median:85.50 stddev:19.42 (22.7%) stddev-sans-first:18.39 05:27:33 INFO - PROCESS | 2570 | Values: 81.0 129.0 125.0 90.0 93.0 129.0 127.0 91.0 104.0 90.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:98.50 stddev:8.35 (8.5%) stddev-sans-first:8.66 05:27:33 INFO - PROCESS | 2570 | Values: 97.0 101.0 99.0 99.0 99.0 98.0 125.0 99.0 98.0 104.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#7] imaging-desaturate Cycles:10 Average:111.20 Median:91.00 stddev:24.33 (26.7%) stddev-sans-first:24.57 05:27:33 INFO - PROCESS | 2570 | Values: 90.0 90.0 93.0 137.0 131.0 92.0 99.0 92.0 145.0 143.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:60.00 stddev:11.12 (18.5%) stddev-sans-first:11.53 05:27:33 INFO - PROCESS | 2570 | Values: 56.0 58.0 60.0 58.0 60.0 61.0 85.0 81.0 52.0 55.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#9] json-stringify-tinderbox Cycles:10 Average:73.90 Median:69.50 stddev:13.14 (18.9%) stddev-sans-first:13.44 05:27:33 INFO - PROCESS | 2570 | Values: 64.0 72.0 67.0 77.0 62.0 100.0 66.0 78.0 61.0 92.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#10] stanford-crypto-aes Cycles:10 Average:103.70 Median:118.00 stddev:20.28 (17.2%) stddev-sans-first:20.08 05:27:33 INFO - PROCESS | 2570 | Values: 83.0 100.0 110.0 90.0 88.0 88.0 103.0 110.0 153.0 112.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:140.50 stddev:12.66 (9.0%) stddev-sans-first:13.06 05:27:33 INFO - PROCESS | 2570 | Values: 138.0 140.0 138.0 139.0 141.0 139.0 140.0 169.0 170.0 150.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:170.50 stddev:18.80 (11.0%) stddev-sans-first:19.65 05:27:33 INFO - PROCESS | 2570 | Values: 170.0 171.0 168.0 169.0 184.0 224.0 200.0 170.0 169.0 167.0 05:27:33 INFO - PROCESS | 2570 | 05:27:33 INFO - PROCESS | 2570 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:73.00 stddev:8.85 (12.1%) stddev-sans-first:9.33 05:27:33 INFO - PROCESS | 2570 | Values: 69.0 66.0 78.0 66.0 60.0 70.0 87.0 62.0 76.0 82.0 05:27:33 INFO - PROCESS | 2570 | -------- Summary: end -------- 05:27:33 INFO - PROCESS | 2570 | 05:27:34 INFO - TEST-INFO | 2570: exit 0 05:27:34 INFO - TEST-OK | kraken | took 60643ms 05:27:34 INFO - SUITE-END | took 1173s 05:27:34 INFO - Completed test suite (00:19:34) 05:27:34 INFO - javascript benchmark 05:27:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7164.213933563589, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2985.2686091907485, 3451.292485040567, 3481.129717570606, 3483.512308276841, 3503.649635036496, 5634.577406694499, 5714.657120572769, 5734.598578099534, 5838.182654428383, 5857.619254748438, 4791.568758811706, 5644.350899646542, 5659.0, 5696.3733989261145, 5843.620164689303, 5128.435872054071, 5164.819231326905, 5239.187925871496, 5259.342582177228, 5295.814646487354, 569.5662133738726, 5133.255677926699, 5221.269022336857, 5229.973850130763, 5232.502912223333, 5190.948090519084, 5211.661242019276, 5240.266362709223, 5256.500632439912, 5275.472452754732, 4394.12117576486, 5243.134882744343, 5284.4187139414635, 5291.3385826771655, 5351.438098999588, 5137.17805151174, 5162.819301324435, 5222.1383471727495, 5232.9738351308, 5284.5243928046275, 4721.858344249678, 5370.4361042090795, 5654.830355089354, 5731.083026715747, 5741.540676745851, 6095.3778866513585, 6160.322364539897, 6177.474914632262, 6214.844628884314, 6219.40915613016, 6103.216452666793, 6115.088229002391, 6162.722677479479, 6221.844453888643, 6221.844453888689, 5911.940880591225, 5917.940820591825, 5924.437178468082, 5929.318128415244, 5931.73307201177, 6129.572745043327, 6188.628682279078, 6289.842753931097, 6329.113924050636, 6399.744010239631, 5177.404598471148, 6103.30736498198, 6155.6614386209185, 6213.502919766408, 6215.689215539295, 6172.4136207060255, 6181.536384771161, 6223.439890409885, 6254.937450625526, 6256.311805701369, 5077.39071311445, 6069.302030266487, 6102.8225305960505, 6192.81421557354, 6221.06683997404, 6097.664628445478, 6110.236220472441, 6117.724702388403, 6126.509879209652, 6126.785562505291, 4769.904601907942, 4856.17445034345, 4871.537203965618, 4895.6083513318845, 4922.606191504671, 4347.478302603708, 4421.867343979686, 4424.778761061934, 4486.5962063414445, 4531.728096314232, 5764.452377024176, 5808.186853840466, 5818.883622327529, 5854.414558544111, 5935.703214839241, 6219.378062193744, 6235.594686345373, 6248.062790581449, 6248.75004999804, 6248.968755156286, 4769.57073863354, 5660.066089095336, 5743.885122297531, 5773.942260577424, 5811.244538210006, 4768.3085952536685, 5393.4336894626285, 5428.701421421781, 5433.972830135824, 5455.072637651609, 5212.791488340424, 6173.536984726074, 6402.231732192166, 6441.25925518562, 6456.192975878015, 6133.1510485532735, 6145.033589251397, 6164.229471316085, 6191.628502289877, 6210.658413787104, 6439.484841212786, 6463.385978332051, 6475.805725828232, 6488.480921526172, 6492.480601551959, 6104.186323682738, 6105.511559075352, 6176.536759743127, 6188.721507532262, 6204.007358822567, 6128.380904898417, 6138.754449821957, 6225.128482012612, 6238.812835614939, 6239.0, 6071.27144742616, 6085.0, 6137.9693101535995, 6148.723307451086, 6153.2000839890525, 6041.637501749733, 6106.847328816815, 6132.294786099477, 6137.447629713347, 6143.754249829957, 5861.765529378948, 6067.0, 6068.150458935665, 6093.817185484442, 6099.207103076749, 6181.350958149509, 6213.1922850029205, 6227.221597300338, 6237.095621135, 6240.968795155995, 5906.616069955439, 6012.819615411545, 6026.7287972040485, 6032.728527216198, 6106.053561697944], "value": 5643.767671196118, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.57406907852, 504.4249555506723, 505.27997603415133, 506.55676283252154, 521.7964993652474, 37314.0, 39825.0, 39903.401448978286, 39996.60003399965, 40047.0, 40607.0, 41378.0, 41400.58599414005, 41437.58562414375, 41456.17087658245, 9284.860727089099, 9393.62425502979, 9409.52952352383, 9466.763330916749, 9574.664886728966, 9449.0, 9536.761580960498, 9538.427694338361, 9556.904430955707, 9559.61761529538, 9469.95830458649, 9470.052994700543, 9506.619735210617, 9554.713358599252, 9566.330356875022, 9398.38910470824, 9420.105090016437, 9448.858267126, 9510.857337139949, 9520.761980950463, 8914.108589141098, 9405.106514881076, 9425.0, 9426.952865235698, 9470.763230919214, 31325.530117048263, 37986.81006594977, 37999.240015199815, 38077.0, 38491.84524464243, 2989.7907146499756, 3115.454795410794, 3273.918152046194, 3376.1897144685345, 3512.1746389598425, 23214.65178022331, 24023.51952960931, 28422.85788571044, 29395.70604293951, 29623.851880740673, 3143.7327827134723, 3148.4962406015156, 3153.3377990622, 3160.952585711216, 3240.5301231321564, 446888.0, 447227.0, 451085.0, 453300.0, 453333.9999999967, 28709.70806313721, 33187.66812331894, 33261.00216993446, 33263.0, 33351.83268585588, 33806.66193338084, 33852.49221261683, 33904.83047584746, 35051.0, 35079.298414031575, 16471.91764041172, 16502.0, 16619.6676066478, 16650.916745416194, 16739.581510462092, 12525.185862919063, 12649.0, 12774.68063298425, 12849.48602055926, 12895.935520322339, 15575.376984920704, 18828.24687012504, 18998.905005474884, 19128.904355478135, 19162.04189790505, 7819.3744500439825, 7994.400419968526, 8006.039275287003, 8014.83970320602, 8070.717524886601, 800.8358286551243, 864.9654013839502, 880.1462581296261, 1132.7338075552238, 1135.318808714781, 29670.92195749991, 29920.85039574832, 30061.166046358405, 30282.54576181359, 30334.089977300275, 1010.908219123345, 1030.7526193713454, 1089.199438412766, 1160.7388337623968, 1223.718544734716, 13806.309684515734, 15493.922530387501, 15632.765508517381, 15652.217389130497, 15945.521634350987, 559312.0, 583421.0828945828, 662760.0, 664695.0, 672450.0, 56294.87410251772, 57627.0, 58100.0, 58125.709371452875, 58293.708531457916, 1403.9368228429746, 1414.4907833180048, 1423.0465588056027, 1423.4590855474853, 1433.0756661952983, 30097.773003511527, 30156.84921575378, 30193.222354339723, 30228.09315720532, 30287.646376409266, 48259.0, 49398.50601494083, 49439.0, 49744.0, 49878.501214988835, 596032.0, 601311.0, 606326.0, 607384.0, 607773.0, 26019.349516262246, 26456.206313810617, 26833.731662683906, 26877.059302924303, 26925.73074269232, 46963.060738785716, 48250.0, 48462.03075938532, 48768.2684759736, 49357.0, 38383.80808095942, 38962.0, 39225.2154956905, 39368.80315598404, 40437.786866392875, 31553.21116972094, 38571.22857542778, 38582.6141738579, 38851.417228741026, 39137.608623913395, 31466.0, 37991.81004094962, 38422.0, 38530.22939541138, 38572.421413678254, 33523.32953340871, 33726.49410258897, 34206.657933421346, 34375.82812085924, 34651.0, 22361.66457503171, 24881.507109573482, 25440.0, 25699.614505782054, 25892.611610825476], "value": 19101.408128010957, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.35672578826023, 369.8520591763296, 377.3170561284076, 387.1057856351826, 396.00997506234415, 19265.61468770624, 19515.316963906247, 20078.698819517682, 20758.792412075876, 20765.99999999998, 11621.244619099753, 11845.585404510844, 11890.7621847563, 12146.878531214685, 12178.147529672926, 5131.692098474095, 5318.734063296839, 5344.064788661988, 5348.411674715778, 5349.0, 3781.8487260509683, 5671.7731290748325, 5989.490893274077, 5989.88020239595, 6017.669028203447, 5565.6104072714925, 5870.236869207019, 5982.910256346158, 6004.789832355868, 6009.609375390585, 5848.941510584882, 5962.34414214436, 5970.402959704038, 5971.104334349839, 5974.820755377345, 4914.901701965977, 5256.369235691704, 5279.52484276417, 5299.072662284085, 5349.117395629716, 13085.28030958297, 14864.70270594582, 20207.797922020887, 25302.114425995187, 25316.0, 2135.562209746998, 2223.8888055597317, 2295.437617783637, 2341.1805867946255, 2417.7703118203744, 9980.351277167034, 10424.583016679324, 14380.424783008668, 14789.852101479062, 15382.3077961492, 1833.5097909224855, 1844.4927644897625, 1858.1173942377263, 1883.6138591588692, 1901.5341241395809, 125400.0, 130479.0, 131300.0, 132171.0, 132342.0, 9743.512824358753, 10010.949945250373, 11138.66584002465, 11519.539218431337, 11815.232009919328, 14558.927205363905, 14593.0, 14619.976601637783, 14629.487967921072, 14658.780118298235, 8611.311095112376, 8685.261752751087, 8702.129787021247, 8733.82070949186, 8801.163889430494, 7151.427885769126, 8687.435316704394, 8702.956485217534, 8776.210141087333, 8889.066648001995, 4077.7757223353, 4088.4889388826396, 4573.679842411066, 4591.9081618367445, 4638.768061596907, 1409.3376113226766, 1419.8012278281049, 1632.2328505602347, 1675.7318828987547, 1687.1817168673429, 852.1691350932791, 917.5366439947784, 937.3204426790522, 948.6774496671165, 1027.2655051638114, 17274.0, 19470.415887523395, 20482.692759608617, 21135.26026589062, 21512.354629361143, 807.4549678966674, 929.7768535551419, 935.6771913689767, 946.6166202687922, 1014.7818219082933, 9194.540272986324, 11049.944750276198, 11255.549778008952, 11329.830052549218, 11406.714832129097, 574189.0, 581556.0, 583804.0, 584188.0, 590157.0, 61389.0, 102649.0, 130952.3452382751, 134969.65030349768, 135766.0, 989.2975987049132, 1008.3546530220665, 1016.8068067067086, 1027.0499787696351, 1029.7477118106112, 18734.90632546883, 18747.625047499245, 18818.52953676169, 19053.71419428682, 19201.61596768085, 52672.20991685051, 52995.0, 54624.45375546194, 54663.45336546584, 55176.72411637916, 77965.61017194878, 78868.21131788609, 79210.4157916827, 79288.0, 79309.29324541113, 6863.210730766026, 7336.816579585553, 7422.480426370318, 7784.571848548384, 8387.874181887155, 11965.803419657965, 12933.224006559636, 13145.60563183106, 13146.934265328613, 13439.193648381128, 11109.732554504044, 11319.717007074889, 11363.943180284046, 11375.976162145447, 11408.201425900108, 9174.53207486799, 11005.504752286326, 11299.43502824839, 11311.434428278388, 11342.149338799458, 11402.828957565478, 11679.65683946323, 11717.76564468723, 11805.586804461802, 11850.111241657083, 6902.137232845895, 7382.224866388952, 7422.257774222535, 7599.810004749926, 8350.0, 9965.601375944882, 11324.433778311217, 11372.886271137513, 11382.975532202141, 11473.770524589294], "value": 9268.436068152516, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1560.6332511859714, 1594.9122798246096, 1786.2051387132724, 1802.5673838278817, 1869.906504674766, 7814.843703125941, 7834.843303133934, 8200.466969646968, 8507.829843403128, 8713.782155446117, 8520.40357174998, 8669.869951950726, 8698.478091314526, 8808.823823523542, 8815.426997245175, 2355.9057637694514, 2403.879806009701, 2408.4701365699607, 2416.3113512648897, 2416.5529377065213, 2378.203301893868, 2394.353524548371, 2410.0600765701392, 2420.9757902421015, 2430.0765709030543, 2319.7796209360085, 2355.2698663414394, 2357.8938947747392, 2394.0, 2411.602085655865, 2377.0, 2382.1662418153683, 2401.8799060047013, 2406.482606239667, 2409.9759002410015, 2026.6149431608094, 2103.8737675739503, 2349.0, 2396.604560247557, 2399.7600239976036, 7681.308682218627, 8660.740177794676, 8709.520976346299, 8731.781705457415, 8877.422967507156, 5315.627906046579, 5405.648632838853, 5491.395946445888, 5498.725063746797, 5502.5597952163735, 3155.8954365971954, 3598.9280214395826, 3671.0271777978855, 3741.38267185914, 3767.0958969847316, 942.9245660347156, 1081.2431298091367, 1082.1883587309517, 1099.7470581766158, 1105.745678493943, 11355.829662555068, 11430.599929002445, 11556.306621602731, 11567.42162891852, 11647.068234541217, 4678.906421871611, 4737.881552961135, 4860.392450943632, 4861.246506791453, 4864.100141473838, 10145.0, 10159.4920253987, 10166.644167454104, 10179.7455063624, 10180.0, 5386.165144402624, 5394.676319420847, 5398.73006349681, 5404.486573775564, 5429.67421954684, 4501.437320334958, 4771.093492236464, 4789.425268967677, 4854.368932038883, 4948.851534453972, 4100.752267719993, 4105.794710264475, 4165.729227600196, 4189.581041895786, 4192.4759405074365, 1306.399056434032, 1329.2224048931485, 1330.0955350361653, 1335.0654541820763, 1339.71196192819, 823.9670413183526, 850.4769566716549, 931.9254459643212, 955.5890966884307, 979.456401697065, 5558.916616250759, 6005.939940600538, 6106.45041946227, 6128.264608247039, 6293.842653933688, 919.7884486567993, 944.4616568555995, 949.4872768704822, 953.2659851913987, 980.7499087732582, 3865.1496670732395, 3935.842566297316, 3939.8227079781477, 3943.1719338939142, 3988.3020471417967, 8948.463092214488, 10352.585896564053, 10570.947145264225, 10887.945560272148, 10917.727056823644, 10670.466476676134, 10737.83893241602, 10808.567657293777, 10846.0, 10862.565497380016, 8127.187281271826, 8182.263596276363, 8345.915031045804, 8351.749447516584, 8371.83256334882, 5880.588358815013, 5989.5507836913275, 6138.5396095292135, 6151.538634602333, 6153.538484613582, 9701.417914925127, 9888.159506441745, 10172.593096276067, 10205.285630006125, 10234.0, 9991.650292239738, 10161.695149145537, 10289.794204116026, 10355.896441035795, 10364.42995635217, 6958.373746362851, 7006.495957408777, 7169.677364518713, 7171.605561694157, 7183.748568800067, 7076.575405475688, 7079.787606371817, 8269.255766981001, 8273.131321211427, 8368.037675667376, 7298.37963773064, 7532.962335188289, 7533.698652053857, 7630.046244219473, 7638.656260468181, 7282.089738782653, 7393.112826460859, 7434.442416818652, 7533.510321829063, 7534.246575342422, 6079.604825686316, 7045.823854403681, 7129.714811407693, 7134.750283740252, 7142.857142857218, 4826.0830442215865, 5008.348914641073, 5103.209002604453, 5119.232115182753, 5197.974010129925, 5195.68825870449, 5205.479452054916, 5210.505002024802, 5332.5467335276935, 5336.066188416934], "value": 4622.378634221585, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.8650674662668, 284.1758899192342, 300.91724775686686, 309.7800561601263, 320.50161998092955, 4361.694681372305, 5355.277037599923, 5604.8038318658855, 5622.100463925773, 5660.264165658464, 4408.781589439711, 5258.6581872178285, 5493.92868390664, 5536.557075433966, 5588.385277619459, 3770.2271034438013, 3864.3623802072625, 4189.895252618679, 4206.0, 4214.346776249687, 3846.0577158596184, 4217.0, 4219.662427005832, 4222.725522841021, 4226.535081141087, 3496.965030349702, 3927.8428862845594, 4112.403701463286, 4123.546409894909, 4141.979290103545, 3607.6651638893513, 4081.999820088165, 4130.8554200602985, 4137.379393091031, 4148.170365926811, 3084.5835812165296, 3564.8967109405316, 3618.203995121069, 3623.9852841204506, 3638.909027274313, 5036.345275114249, 5153.711572106973, 5399.487048730364, 5436.374816896028, 5452.454754524555, 4364.083542456088, 4634.582887540104, 4761.357216775726, 4780.0, 4822.807087716487, 5417.8103766367985, 5475.561955043587, 5610.24261724666, 5734.770609175628, 5903.49820265278, 3641.198936234051, 3918.2163567286657, 3923.8234279457492, 3946.3622596622654, 3970.4316794865867, 4973.676711013773, 6106.236720409949, 6203.596766210182, 6207.7206525706, 6293.930031894589, 3948.9210215795524, 3965.4250133731025, 3977.3835055565855, 3990.1421194443333, 3992.261431635157, 3627.546556680415, 3741.30785804627, 4020.4773379460485, 4075.2053349597136, 4084.632383085477, 4720.221163507983, 4763.356946812136, 4786.377770889762, 4802.6638135330195, 4804.591609713144, 5304.92042619361, 5352.839414817562, 5698.430156984268, 5894.528437724972, 5956.404359564096, 1106.7841770854768, 1392.276016471429, 1392.317764295488, 1394.76289030865, 1397.6855207578215, 719.3274288540232, 815.4251252866715, 820.0200760091633, 838.4382463749303, 850.974470765878, 3893.9415908761393, 4228.006418491652, 4574.588287054181, 4591.380163677927, 4603.838865639754, 4341.283688191413, 4354.738715677006, 4667.346571479996, 4772.188727916263, 4876.487968763229, 4016.879493615196, 4180.979095104505, 4394.517097839775, 4608.539146085364, 4615.815367385267, 2354.387859156597, 2542.338991862129, 2599.5320842248575, 2721.7207912281197, 2745.409736906575, 4692.225782745877, 5189.054104710734, 5407.540359069444, 5434.212039254365, 5450.345958485007, 4812.353205736831, 4848.127337079289, 5056.898862022739, 5067.061104559347, 5293.311869456946, 4574.313852922021, 4598.678092533491, 4611.017262577057, 4786.928196077061, 5238.68567885928, 5067.898642027212, 5120.359955005624, 5134.152864777419, 5155.793768249378, 5181.937702770998, 3962.108525581721, 4668.5798278155125, 4718.64067966017, 4755.2153894607, 4941.678790878582, 5189.160021584076, 5237.685738855681, 5335.1723586497665, 5377.542908852792, 5395.298611180521, 3000.78994470385, 3094.3656550407563, 3494.1264683829045, 3509.438489841613, 3549.172176329244, 4707.693999889996, 5122.769475373542, 5139.0, 5150.403374953777, 5768.653880767168, 5040.49595040493, 5495.340559132929, 5775.873704627557, 5910.054391297372, 5944.8811023780145, 5693.5445164387575, 5843.620164689281, 5848.5028772553105, 5909.940900590939, 5913.497352725067, 5235.554977826928, 5474.452554744494, 5594.804181853616, 5737.614807263734, 5764.884702306014, 5448.591355648263, 5635.633683810539, 5857.891159182776, 5865.829643032105, 5923.881522369442, 5213.6350455467755, 5730.885382292414, 5731.8567035824435, 5763.193152958505, 5782.1615865698395], "value": 3986.221931945711, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.8550947055854, 750.0848964263598, 791.9247671471209, 828.8839562461253, 850.9106543812895, 14630.26848657567, 15069.321880515376, 15142.470013549528, 15286.31211595478, 15317.234138293083, 14494.057886237413, 15337.0, 15349.846501534981, 15549.144797036186, 15619.984700994455, 870.3820287595782, 875.7241468937297, 876.8772371867943, 877.2937785083005, 879.4767113567449, 693.9097917270773, 849.0914721248267, 878.2490970220433, 892.3218354050903, 894.7449976756626, 930.9581068851884, 931.8741969834088, 932.6129656192672, 935.2097477631424, 935.7005758157393, 787.7951732549521, 899.8785164002842, 902.1835239108617, 929.1730359979623, 933.1601558597249, 685.4893104637027, 686.3342557719054, 686.3959715450421, 687.9484038697118, 696.9163700355939, 14459.710805783825, 14465.20441375724, 14510.782338264935, 14830.925845370812, 14879.0, 10209.9489502552, 13613.659658508517, 13632.727345453135, 13696.041277110606, 13748.381322840496, 9595.472249026301, 11468.713282168013, 11487.21948098046, 11510.399651529948, 12046.216995895238, 3667.211549516867, 3710.1466662667467, 3769.11425814936, 3770.962290377116, 3894.1043559981363, 86082.0, 86779.0, 86779.56610216972, 87066.0, 87708.0, 10298.433586152683, 10520.631777887736, 10614.522346494427, 10754.78490430187, 11043.779124417466, 17287.74068388975, 20941.371758847257, 20941.476463088544, 20970.580588388453, 20981.790182097982, 7667.731629392944, 7713.267239612228, 7836.177201393885, 7873.803154921173, 7914.564698941498, 4372.300431930876, 4404.053128577307, 4458.108378324348, 4504.797284122223, 4638.582527572467, 2790.6651201855907, 3482.6865582097735, 3527.5766907971206, 3585.4442561403025, 3593.658602432765, 731.7621772923821, 733.7065173930471, 739.2201227704822, 742.3281929853491, 750.6509473095037, 632.8481164520482, 639.587466084373, 674.9527533072638, 685.2633419074415, 700.8423104801481, 8843.46939183651, 9133.315001374924, 9269.67556135532, 9672.22622190223, 9722.95138524317, 576.9740361683735, 598.956274215232, 642.0401499757892, 692.6675195905993, 696.251529605676, 4459.732416054982, 4589.380433641482, 4593.311003349458, 4608.331791890158, 4642.117997580516, 94218.0, 96861.0, 97162.0, 97271.0, 97408.51295743475, 71398.0, 81571.5921420401, 84205.31589368178, 86229.56885215659, 86382.56808715916, 75523.62238188773, 75548.48903021909, 76263.0, 76491.47017059628, 77005.61497192478, 9001.459912405277, 9049.59276832531, 9058.094190580889, 9091.768263869137, 9130.543472826199, 48378.51621483881, 48432.27351589659, 48447.0, 48524.0, 50582.73543161451, 55886.72056639854, 56197.59506012382, 57583.408746605666, 62053.68973155105, 62430.687846560475, 12562.37188140571, 12850.935745321214, 12895.613131606067, 12901.875585497433, 13016.414261358072, 14105.923969906586, 14191.858081419054, 14362.353694084, 14407.351669174703, 14702.264886755833, 13152.605421837361, 13187.340632968122, 13204.471821127432, 13257.801132983206, 13468.124571902796, 10400.531976061247, 13283.402246898719, 13602.591922242349, 13617.455301787817, 13896.791548126988, 12105.81841272364, 12830.909372703425, 12874.806877897026, 12938.547150849676, 13040.608781736562, 10902.40036797952, 11145.83281250798, 11416.828747568627, 11484.196106272815, 11639.418029098342, 10840.837387439036, 11103.50034248477, 11241.437928103398, 11335.0, 11351.262167959056], "value": 7343.421520213915, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1598.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 116.0, 115.0, 116.0, 115.0, 118.0, 118.0, 94.0, 168.0, 151.0], "value": 124.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 150.0, 170.0, 162.0, 121.0, 141.0, 140.0, 119.0, 168.0, 115.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 199.0, 207.0, 238.0, 200.0, 194.0, 199.0, 201.0, 195.0, 200.0], "value": 203.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 80.0, 74.0, 145.0, 60.0, 59.0, 61.0, 63.0, 134.0, 69.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 121.0, 73.0, 74.0, 94.0, 116.0, 70.0, 73.0, 112.0], "value": 87.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 129.0, 125.0, 90.0, 93.0, 129.0, 127.0, 91.0, 104.0, 90.0], "value": 105.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 101.0, 99.0, 99.0, 99.0, 98.0, 125.0, 99.0, 98.0, 104.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 93.0, 137.0, 131.0, 92.0, 99.0, 92.0, 145.0, 143.0], "value": 111.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 60.0, 58.0, 60.0, 61.0, 85.0, 81.0, 52.0, 55.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 67.0, 77.0, 62.0, 100.0, 66.0, 78.0, 61.0, 92.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 100.0, 110.0, 90.0, 88.0, 88.0, 103.0, 110.0, 153.0, 112.0], "value": 103.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 138.0, 139.0, 141.0, 139.0, 140.0, 169.0, 170.0, 150.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 168.0, 169.0, 184.0, 224.0, 200.0, 170.0, 169.0, 167.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 78.0, 66.0, 60.0, 70.0, 87.0, 62.0, 76.0, 82.0], "value": 71.6, "unit": "score"}]}]} 05:27:34 INFO - Return code: 0 05:27:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:27:34 ERROR - # TBPL SUCCESS # 05:27:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:27:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:27:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:27:34 INFO - 2016-06-01 05:27:34.835 screenresolution[2575:28801] starting screenresolution argv=screenresolution get 05:27:34 INFO - 2016-06-01 05:27:34.838 screenresolution[2575:28801] Display 0: 1600x1200x32@60 05:27:34 INFO - 2016-06-01 05:27:34.844 screenresolution[2576:28804] starting screenresolution argv=screenresolution list 05:27:34 INFO - Available Modes on Display 0 05:27:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:27:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:27:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:27:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:27:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:27:35 INFO - Intel Iris: 05:27:35 INFO - Chipset Model: Intel Iris 05:27:35 INFO - Type: GPU 05:27:35 INFO - Bus: Built-In 05:27:35 INFO - VRAM (Dynamic, Max): 1536 MB 05:27:35 INFO - Vendor: Intel (0x8086) 05:27:35 INFO - Device ID: 0x0a2e 05:27:35 INFO - Revision ID: 0x0009 05:27:35 INFO - Displays: 05:27:35 INFO - Display: 05:27:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:27:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:27:35 INFO - Display Serial Number: 1600x1200 60 05:27:35 INFO - Main Display: Yes 05:27:35 INFO - Mirror: Off 05:27:35 INFO - Online: Yes 05:27:35 INFO - Rotation: Supported 05:27:35 INFO - Return code: 0 05:27:35 INFO - Running post-action listener: _resource_record_post_action 05:27:35 INFO - ##### 05:27:35 INFO - ##### Finished run-tests step (success) 05:27:35 INFO - ##### 05:27:35 INFO - Running post-run listener: _resource_record_post_run 05:27:35 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 147456; Write bytes: 697730560; Read time: 1; Write time: 1972 05:27:35 INFO - TinderboxPrint: CPU usage
54.8% 05:27:35 INFO - TinderboxPrint: I/O read bytes / time
147,456 / 1 05:27:35 INFO - TinderboxPrint: I/O write bytes / time
697,730,560 / 1,972 05:27:35 INFO - TinderboxPrint: CPU idle
2,190.3 (45.2%) 05:27:35 INFO - TinderboxPrint: CPU system
977.7 (20.2%) 05:27:35 INFO - TinderboxPrint: CPU user
1,677.8 (34.6%) 05:27:35 INFO - TinderboxPrint: Swap in / out
398,888,960 / 0 05:27:35 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 397117952; Write bytes: 391390720; Read time: 30782; Write time: 801 05:27:35 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 147456; Write bytes: 295079936; Read time: 1; Write time: 1137 05:27:35 INFO - Running post-run listener: _upload_blobber_files 05:27:35 INFO - Blob upload gear active. 05:27:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:27:35 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 05:27:35 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'] 05:27:35 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 05:27:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:27:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:27:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:27:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:36 INFO - (blobuploader) - INFO - Done attempting. 05:27:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:27:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:27:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:37 INFO - (blobuploader) - INFO - Done attempting. 05:27:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:27:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:27:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:40 INFO - (blobuploader) - INFO - Done attempting. 05:27:40 INFO - (blobuploader) - INFO - Iteration through files over. 05:27:40 INFO - Return code: 0 05:27:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:27:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:27:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e5316dee060ce814121aedcbd7ea9c2fa811bfd28c12c791d5b78ac769176ab9e1a0c05262d4a819acf628fac7bfb12972356cea8a2507eb1503378430260a27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a7f6a1a6a6b81b186c48f7ace9897dbf11690fdaa225330b6f6e31f050dfaca22c81a1133f343882a803e0d26cc4ab0a2261bf1e510763e4a2b5d467142b72a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8886a40806cdfacd7107507e9499daef960c33575452646ea03347d283f33e6e46c2d845e2fedcb8e5eeadc4b451069bf91dc89e16094c2401d5d7f060a556e0"} 05:27:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:27:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:27:40 INFO - Contents: 05:27:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e5316dee060ce814121aedcbd7ea9c2fa811bfd28c12c791d5b78ac769176ab9e1a0c05262d4a819acf628fac7bfb12972356cea8a2507eb1503378430260a27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a7f6a1a6a6b81b186c48f7ace9897dbf11690fdaa225330b6f6e31f050dfaca22c81a1133f343882a803e0d26cc4ab0a2261bf1e510763e4a2b5d467142b72a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8886a40806cdfacd7107507e9499daef960c33575452646ea03347d283f33e6e46c2d845e2fedcb8e5eeadc4b451069bf91dc89e16094c2401d5d7f060a556e0"} 05:27:40 INFO - Running post-run listener: copy_logs_to_upload_dir 05:27:40 INFO - Copying logs to upload dir... 05:27:40 INFO - mkdir: /builds/slave/test/build/upload/logs 05:27:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.146622 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-06-01 05:27:40.409886) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 05:27:40.413049) ========= 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.zrGhatqU2I/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.9e33vyaV4m/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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/e5316dee060ce814121aedcbd7ea9c2fa811bfd28c12c791d5b78ac769176ab9e1a0c05262d4a819acf628fac7bfb12972356cea8a2507eb1503378430260a27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a7f6a1a6a6b81b186c48f7ace9897dbf11690fdaa225330b6f6e31f050dfaca22c81a1133f343882a803e0d26cc4ab0a2261bf1e510763e4a2b5d467142b72a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8886a40806cdfacd7107507e9499daef960c33575452646ea03347d283f33e6e46c2d845e2fedcb8e5eeadc4b451069bf91dc89e16094c2401d5d7f060a556e0"} build_url:https://queue.taskcluster.net/v1/task/bQ7PqEZzQN-NqynRSQVeTA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011377 build_url: 'https://queue.taskcluster.net/v1/task/bQ7PqEZzQN-NqynRSQVeTA/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/e5316dee060ce814121aedcbd7ea9c2fa811bfd28c12c791d5b78ac769176ab9e1a0c05262d4a819acf628fac7bfb12972356cea8a2507eb1503378430260a27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a7f6a1a6a6b81b186c48f7ace9897dbf11690fdaa225330b6f6e31f050dfaca22c81a1133f343882a803e0d26cc4ab0a2261bf1e510763e4a2b5d467142b72a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8886a40806cdfacd7107507e9499daef960c33575452646ea03347d283f33e6e46c2d845e2fedcb8e5eeadc4b451069bf91dc89e16094c2401d5d7f060a556e0"}' ========= master_lag: 0.19 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 05:27:40.610618) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 05:27:40.610942) ========= 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.zrGhatqU2I/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.9e33vyaV4m/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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.005327 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 05:27:40.634250) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 05:27:40.634551) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 05:27:40.634849) ========= ========= Total master_lag: 0.31 =========