builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0330 starttime: 1463591951.91 results: success (0) buildid: 20160518084142 builduid: aa8adf55ee544369b124fe5e39d58a86 revision: a8518344e759e4887b53d6e88d56eb3cfe00788a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:11.906288) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:11.906680) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:11.906958) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:11.925801) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:11.926483) ========= 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.d8JZs3aQ3o/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.eH7e1xczai/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-18 10:19:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 32.9M=0s 2016-05-18 10:19:11 (32.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082536 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:12.027750) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:12.028070) ========= 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.d8JZs3aQ3o/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.eH7e1xczai/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.020007 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:12.076929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-18 10:19:12.077295) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a8518344e759e4887b53d6e88d56eb3cfe00788a --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 a8518344e759e4887b53d6e88d56eb3cfe00788a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d8JZs3aQ3o/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.eH7e1xczai/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-18 10:19:12,130 truncating revision to first 12 chars 2016-05-18 10:19:12,130 Setting DEBUG logging. 2016-05-18 10:19:12,130 attempt 1/10 2016-05-18 10:19:12,130 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a8518344e759?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 10:19:14,413 unpacking tar archive at: fx-team-a8518344e759/testing/mozharness/ program finished with exit code 0 elapsedTime=2.534795 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-18 10:19:14.631151) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:14.631479) ========= script_repo_revision: a8518344e759e4887b53d6e88d56eb3cfe00788a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:14.631858) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:14.632136) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 10:19:14.646127) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-18 10:19:14.646648) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d8JZs3aQ3o/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.eH7e1xczai/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 10:19:14 INFO - MultiFileLogger online at 20160518 10:19:14 in /builds/slave/test 10:19:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 10:19:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:19:14 INFO - {'append_to_log': False, 10:19:14 INFO - 'base_work_dir': '/builds/slave/test', 10:19:14 INFO - 'blob_upload_branch': 'Fx-Team', 10:19:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:19:14 INFO - 'branch': 'Fx-Team', 10:19:14 INFO - 'buildbot_json_path': 'buildprops.json', 10:19:14 INFO - 'config_files': ('talos/mac_config.py',), 10:19:14 INFO - 'default_actions': ('clobber', 10:19:14 INFO - 'read-buildbot-config', 10:19:14 INFO - 'download-and-extract', 10:19:14 INFO - 'populate-webroot', 10:19:14 INFO - 'create-virtualenv', 10:19:14 INFO - 'install', 10:19:14 INFO - 'run-tests'), 10:19:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:19:14 INFO - 'download_minidump_stackwalk': True, 10:19:14 INFO - 'download_symbols': 'ondemand', 10:19:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:19:14 INFO - 'tooltool.py': '/tools/tooltool.py', 10:19:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:19:14 INFO - '/tools/misc-python/virtualenv.py')}, 10:19:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:19:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:19:14 INFO - 'installer_path': 'installer.exe', 10:19:14 INFO - 'log_level': 'info', 10:19:14 INFO - 'log_name': 'talos', 10:19:14 INFO - 'log_to_console': True, 10:19:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:19:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:19:14 INFO - 'opt_config_files': (), 10:19:14 INFO - 'pip_index': False, 10:19:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:19:14 INFO - 'cmd': ('bash', 10:19:14 INFO - '-c', 10:19:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:19:14 INFO - 'enabled': True, 10:19:14 INFO - 'halt_on_failure': False, 10:19:14 INFO - 'name': 'check_screen_resolution'},), 10:19:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:19:14 INFO - 'cmd': ('bash', 10:19:14 INFO - '-c', 10:19:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:19:14 INFO - 'enabled': True, 10:19:14 INFO - 'halt_on_failure': False, 10:19:14 INFO - 'name': 'check_screen_resolution'},), 10:19:14 INFO - 'run_cmd_checks_enabled': True, 10:19:14 INFO - 'sps_profile': False, 10:19:14 INFO - 'sps_profile_interval': 0, 10:19:14 INFO - 'suite': 'dromaeojs', 10:19:14 INFO - 'system_bits': '32', 10:19:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:19:14 INFO - 'title': 't-yosemite-r7-0330', 10:19:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:19:14 INFO - 'use_talos_json': True, 10:19:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:19:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:19:14 INFO - 'work_dir': 'build'} 10:19:14 INFO - ##### 10:19:14 INFO - ##### Running clobber step. 10:19:14 INFO - ##### 10:19:14 INFO - Running pre-action listener: _resource_record_pre_action 10:19:14 INFO - Running main action method: clobber 10:19:14 INFO - rmtree: /builds/slave/test/build 10:19:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:19:16 INFO - Running post-action listener: _resource_record_post_action 10:19:16 INFO - ##### 10:19:16 INFO - ##### Finished clobber step (success) 10:19:16 INFO - ##### 10:19:16 INFO - ##### 10:19:16 INFO - ##### Running read-buildbot-config step. 10:19:16 INFO - ##### 10:19:16 INFO - Running pre-action listener: _resource_record_pre_action 10:19:16 INFO - Running main action method: read_buildbot_config 10:19:16 INFO - Using buildbot properties: 10:19:16 INFO - { 10:19:16 INFO - "project": "", 10:19:16 INFO - "product": "firefox", 10:19:16 INFO - "script_repo_revision": "production", 10:19:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:19:16 INFO - "repository": "", 10:19:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:19:16 INFO - "buildid": "20160518084142", 10:19:16 INFO - "pgo_build": "False", 10:19:16 INFO - "basedir": "/builds/slave/test", 10:19:16 INFO - "buildnumber": 27, 10:19:16 INFO - "slavename": "t-yosemite-r7-0330", 10:19:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:19:16 INFO - "platform": "yosemite_r7", 10:19:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:19:16 INFO - "branch": "fx-team", 10:19:16 INFO - "repo_path": "integration/fx-team", 10:19:16 INFO - "revision": "a8518344e759e4887b53d6e88d56eb3cfe00788a", 10:19:16 INFO - "stage_platform": "macosx64", 10:19:16 INFO - "builduid": "aa8adf55ee544369b124fe5e39d58a86", 10:19:16 INFO - "slavebuilddir": "test" 10:19:16 INFO - } 10:19:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:19:16 INFO - Running post-action listener: _resource_record_post_action 10:19:16 INFO - ##### 10:19:16 INFO - ##### Finished read-buildbot-config step (success) 10:19:16 INFO - ##### 10:19:16 INFO - ##### 10:19:16 INFO - ##### Running download-and-extract step. 10:19:16 INFO - ##### 10:19:16 INFO - Running pre-action listener: _resource_record_pre_action 10:19:16 INFO - Running main action method: download_and_extract 10:19:16 INFO - mkdir: /builds/slave/test/build/tests 10:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:16 INFO - https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:19:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:19:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:19:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/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 10:19:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/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 10:19:16 INFO - Downloaded 1199 bytes. 10:19:16 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:19:16 INFO - Using the following test package requirements: 10:19:16 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:19:16 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:16 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:19:16 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:16 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:19:16 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:16 INFO - u'jsshell-mac.zip'], 10:19:16 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:16 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:19:16 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:19:16 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:16 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:19:16 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:16 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:19:16 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:16 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:19:16 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:16 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:19:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:16 INFO - https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:19:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:19:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:19:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/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 10:19:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/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 10:19:18 INFO - Downloaded 18306038 bytes. 10:19:18 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'] 10:19:18 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 10:19:21 INFO - Return code: 0 10:19:21 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 10:19:21 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')]} 10:19:21 INFO - https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:19:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:19:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:19:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/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 10:19:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/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 10:19:34 INFO - Downloaded 18306038 bytes. 10:19:34 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'] 10:19:34 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 10:19:37 INFO - Return code: 0 10:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:37 INFO - https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:19:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:19:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:19:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/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 10:19:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/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 10:19:38 INFO - Downloaded 10840194 bytes. 10:19:38 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'] 10:19:38 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 10:19:38 INFO - Return code: 0 10:19:38 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')]} 10:19:38 INFO - https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:19:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:19:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:19:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:19:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRhPmChrQ8ia89ffkqpTgg/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 10:19:42 INFO - Downloaded 110187251 bytes. 10:19:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:19:42 INFO - mkdir: /builds/slave/test/properties 10:19:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:19:42 INFO - Writing to file /builds/slave/test/properties/build_url 10:19:42 INFO - Contents: 10:19:42 INFO - build_url:https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:19:43 INFO - Running post-action listener: _resource_record_post_action 10:19:43 INFO - Running post-action listener: set_extra_try_arguments 10:19:43 INFO - ##### 10:19:43 INFO - ##### Finished download-and-extract step (success) 10:19:43 INFO - ##### 10:19:43 INFO - ##### 10:19:43 INFO - ##### Running populate-webroot step. 10:19:43 INFO - ##### 10:19:43 INFO - Running pre-action listener: _resource_record_pre_action 10:19:43 INFO - Running main action method: populate_webroot 10:19:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:19:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:19:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:19:43 INFO - '--tppagecycles', 10:19:43 INFO - '7'], 10:19:43 INFO - 'tests': ['tsvgm']}}, 10:19:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:19:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:19:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:19:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:19:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:19:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:19:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:43 INFO - 'talos_options': ['--disable-e10s'], 10:19:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:19:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:19:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:43 INFO - 'talos_options': ['--disable-e10s'], 10:19:43 INFO - 'tests': ['damp', 'tps']}, 10:19:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:43 INFO - 'tests': ['damp', 'tps']}, 10:19:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:19:43 INFO - 'tests': ['dromaeo_dom']}, 10:19:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:19:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:19:43 INFO - 'tests': ['a11yr', 10:19:43 INFO - 'ts_paint', 10:19:43 INFO - 'tpaint', 10:19:43 INFO - 'sessionrestore', 10:19:43 INFO - 'sessionrestore_no_auto_restore', 10:19:43 INFO - 'tabpaint']}, 10:19:43 INFO - 'other-e10s': {'tests': ['a11yr', 10:19:43 INFO - 'ts_paint', 10:19:43 INFO - 'tpaint', 10:19:43 INFO - 'sessionrestore', 10:19:43 INFO - 'sessionrestore_no_auto_restore', 10:19:43 INFO - 'tabpaint']}, 10:19:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:19:43 INFO - 'ts_paint', 10:19:43 INFO - 'tpaint', 10:19:43 INFO - 'sessionrestore', 10:19:43 INFO - 'sessionrestore_no_auto_restore', 10:19:43 INFO - 'tabpaint']}, 10:19:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:19:43 INFO - 'ts_paint', 10:19:43 INFO - 'tpaint', 10:19:43 INFO - 'sessionrestore', 10:19:43 INFO - 'sessionrestore_no_auto_restore', 10:19:43 INFO - 'tabpaint']}, 10:19:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:19:43 INFO - 'tests': ['a11yr', 10:19:43 INFO - 'ts_paint', 10:19:43 INFO - 'tpaint', 10:19:43 INFO - 'sessionrestore', 10:19:43 INFO - 'sessionrestore_no_auto_restore', 10:19:43 INFO - 'tabpaint']}, 10:19:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:19:43 INFO - 'tests': ['a11yr', 10:19:43 INFO - 'ts_paint', 10:19:43 INFO - 'tpaint', 10:19:43 INFO - 'sessionrestore', 10:19:43 INFO - 'sessionrestore_no_auto_restore', 10:19:43 INFO - 'tabpaint']}, 10:19:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:19:43 INFO - 'tests': ['tsvgx', 10:19:43 INFO - 'tsvgr_opacity', 10:19:43 INFO - 'tart', 10:19:43 INFO - 'tscrollx', 10:19:43 INFO - 'cart']}, 10:19:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:19:43 INFO - 'tsvgr_opacity', 10:19:43 INFO - 'tart', 10:19:43 INFO - 'tscrollx', 10:19:43 INFO - 'cart']}, 10:19:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:43 INFO - 'talos_options': ['--disable-e10s'], 10:19:43 INFO - 'tests': ['tp5o']}, 10:19:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:43 INFO - 'tests': ['tp5o']}, 10:19:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:43 INFO - 'talos_options': ['--disable-e10s', 10:19:43 INFO - '--xperf_path', 10:19:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:19:43 INFO - 'tests': ['tp5n']}, 10:19:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:43 INFO - 'talos_options': ['--xperf_path', 10:19:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:19:43 INFO - 'tests': ['tp5n']}}, 10:19:43 INFO - 'talos.zip': {'path': '', 10:19:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:19:43 INFO - Running post-action listener: _resource_record_post_action 10:19:43 INFO - ##### 10:19:43 INFO - ##### Finished populate-webroot step (success) 10:19:43 INFO - ##### 10:19:43 INFO - ##### 10:19:43 INFO - ##### Running create-virtualenv step. 10:19:43 INFO - ##### 10:19:43 INFO - Running pre-action listener: _resource_record_pre_action 10:19:43 INFO - Running main action method: create_virtualenv 10:19:43 INFO - Creating virtualenv /builds/slave/test/build/venv 10:19:43 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 10:19:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:19:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:19:43 INFO - Using real prefix '/tools/python27' 10:19:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:19:44 INFO - Installing distribute.............................................................................................................................................................................................done. 10:19:47 INFO - Installing pip.................done. 10:19:47 INFO - Return code: 0 10:19:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:47 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 0x107a22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff112c6f2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078c6d30>, '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 0x1076eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078bdb20>, '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.d8JZs3aQ3o/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.eH7e1xczai/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 10:19:47 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 10:19:47 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 10:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8JZs3aQ3o/Render', 10:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:47 INFO - 'HOME': '/Users/cltbld', 10:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:47 INFO - 'LOGNAME': 'cltbld', 10:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:47 INFO - 'MOZ_NO_REMOTE': '1', 10:19:47 INFO - 'NO_EM_RESTART': '1', 10:19:47 INFO - 'PAGER': '/bin/cat', 10:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:47 INFO - 'PWD': '/builds/slave/test', 10:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:47 INFO - 'SHELL': '/bin/bash', 10:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eH7e1xczai/Listeners', 10:19:47 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:47 INFO - 'USER': 'cltbld', 10:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:47 INFO - 'XPC_FLAGS': '0x0', 10:19:47 INFO - 'XPC_SERVICE_NAME': '0', 10:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:47 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 10:19:47 INFO - Cleaning up... 10:19:47 INFO - Return code: 0 10:19:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:47 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 0x107a22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff112c6f2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078c6d30>, '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 0x1076eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078bdb20>, '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.d8JZs3aQ3o/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.eH7e1xczai/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 10:19:47 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 10:19:47 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 10:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8JZs3aQ3o/Render', 10:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:47 INFO - 'HOME': '/Users/cltbld', 10:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:47 INFO - 'LOGNAME': 'cltbld', 10:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:47 INFO - 'MOZ_NO_REMOTE': '1', 10:19:47 INFO - 'NO_EM_RESTART': '1', 10:19:47 INFO - 'PAGER': '/bin/cat', 10:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:47 INFO - 'PWD': '/builds/slave/test', 10:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:47 INFO - 'SHELL': '/bin/bash', 10:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eH7e1xczai/Listeners', 10:19:47 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:47 INFO - 'USER': 'cltbld', 10:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:47 INFO - 'XPC_FLAGS': '0x0', 10:19:47 INFO - 'XPC_SERVICE_NAME': '0', 10:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:47 INFO - Downloading/unpacking psutil>=3.1.1 10:19:47 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 10:19:47 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 10:19:47 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 10:19:47 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 10:19:47 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 10:19:47 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 10:19:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:19:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:19:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:19:50 INFO - Installing collected packages: psutil 10:19:50 INFO - Running setup.py install for psutil 10:19:50 INFO - building 'psutil._psutil_osx' extension 10:19:50 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 10:19:50 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 10:19:50 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 10:19:50 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 10:19:50 INFO - building 'psutil._psutil_posix' extension 10:19:50 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 10:19:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:19:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:19:50 INFO - ^ 10:19:50 INFO - 1 warning generated. 10:19:50 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 10:19:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:19:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:19:51 INFO - Successfully installed psutil 10:19:51 INFO - Cleaning up... 10:19:51 INFO - Return code: 0 10:19:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff112c6f2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078c6d30>, '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 0x1076eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078bdb20>, '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.d8JZs3aQ3o/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.eH7e1xczai/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 10:19:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:19:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:19:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8JZs3aQ3o/Render', 10:19:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:51 INFO - 'HOME': '/Users/cltbld', 10:19:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:51 INFO - 'LOGNAME': 'cltbld', 10:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:51 INFO - 'MOZ_NO_REMOTE': '1', 10:19:51 INFO - 'NO_EM_RESTART': '1', 10:19:51 INFO - 'PAGER': '/bin/cat', 10:19:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:51 INFO - 'PWD': '/builds/slave/test', 10:19:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:51 INFO - 'SHELL': '/bin/bash', 10:19:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eH7e1xczai/Listeners', 10:19:51 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:19:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:51 INFO - 'USER': 'cltbld', 10:19:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:51 INFO - 'XPC_FLAGS': '0x0', 10:19:51 INFO - 'XPC_SERVICE_NAME': '0', 10:19:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:19:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:19:53 INFO - Installing collected packages: mozsystemmonitor 10:19:53 INFO - Running setup.py install for mozsystemmonitor 10:19:54 INFO - Successfully installed mozsystemmonitor 10:19:54 INFO - Cleaning up... 10:19:54 INFO - Return code: 0 10:19:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:54 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 0x107a22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff112c6f2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078c6d30>, '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 0x1076eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078bdb20>, '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.d8JZs3aQ3o/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.eH7e1xczai/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 10:19:54 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 10:19:54 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 10:19:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8JZs3aQ3o/Render', 10:19:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:54 INFO - 'HOME': '/Users/cltbld', 10:19:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:54 INFO - 'LOGNAME': 'cltbld', 10:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:54 INFO - 'MOZ_NO_REMOTE': '1', 10:19:54 INFO - 'NO_EM_RESTART': '1', 10:19:54 INFO - 'PAGER': '/bin/cat', 10:19:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:54 INFO - 'PWD': '/builds/slave/test', 10:19:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:54 INFO - 'SHELL': '/bin/bash', 10:19:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eH7e1xczai/Listeners', 10:19:54 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:19:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:54 INFO - 'USER': 'cltbld', 10:19:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:54 INFO - 'XPC_FLAGS': '0x0', 10:19:54 INFO - 'XPC_SERVICE_NAME': '0', 10:19:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:54 INFO - Downloading/unpacking blobuploader==1.2.4 10:19: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 10:19: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 10:19: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 10:19: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 10:19: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 10:19: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 10:19:56 INFO - Downloading blobuploader-1.2.4.tar.gz 10:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:19:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:19:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:58 INFO - Downloading docopt-0.6.1.tar.gz 10:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:19:58 INFO - Installing collected packages: blobuploader, requests, docopt 10:19:58 INFO - Running setup.py install for blobuploader 10:19:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:19:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:19:58 INFO - Running setup.py install for requests 10:19:58 INFO - Running setup.py install for docopt 10:19:58 INFO - Successfully installed blobuploader requests docopt 10:19:58 INFO - Cleaning up... 10:19:59 INFO - Return code: 0 10:19:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19: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')]} 10:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19: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')]} 10:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:59 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 0x107a22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff112c6f2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078c6d30>, '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 0x1076eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078bdb20>, '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.d8JZs3aQ3o/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.eH7e1xczai/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 10:19:59 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 10:19:59 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 10:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8JZs3aQ3o/Render', 10:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:59 INFO - 'HOME': '/Users/cltbld', 10:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:59 INFO - 'LOGNAME': 'cltbld', 10:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:59 INFO - 'MOZ_NO_REMOTE': '1', 10:19:59 INFO - 'NO_EM_RESTART': '1', 10:19:59 INFO - 'PAGER': '/bin/cat', 10:19:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:59 INFO - 'PWD': '/builds/slave/test', 10:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:59 INFO - 'SHELL': '/bin/bash', 10:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eH7e1xczai/Listeners', 10:19:59 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:59 INFO - 'USER': 'cltbld', 10:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:59 INFO - 'XPC_FLAGS': '0x0', 10:19:59 INFO - 'XPC_SERVICE_NAME': '0', 10:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Ic7TPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-CuyF8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-5J_HPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-yN87em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-5VYi5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Jvf9NY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-52xm4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-82NndN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-8phrTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-TGFtQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-X_nJ04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-rcMaqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-CquBAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Pg8W4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-0tGBdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-w6umsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-nbi07t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:20:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:20:01 INFO - Running setup.py install for manifestparser 10:20:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:20:01 INFO - Running setup.py install for mozcrash 10:20:01 INFO - Running setup.py install for mozdebug 10:20:01 INFO - Running setup.py install for mozdevice 10:20:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:20:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:20:01 INFO - Running setup.py install for mozfile 10:20:01 INFO - Running setup.py install for mozhttpd 10:20:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:20:01 INFO - Running setup.py install for mozinfo 10:20:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:20:02 INFO - Running setup.py install for mozInstall 10:20:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:20:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:20:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:20:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:20:02 INFO - Running setup.py install for mozleak 10:20:02 INFO - Running setup.py install for mozlog 10:20:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:20:02 INFO - Running setup.py install for moznetwork 10:20:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:20:02 INFO - Running setup.py install for mozprocess 10:20:02 INFO - Running setup.py install for mozprofile 10:20:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:20:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:20:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:20:02 INFO - Running setup.py install for mozrunner 10:20:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:20:03 INFO - Running setup.py install for mozscreenshot 10:20:03 INFO - Running setup.py install for moztest 10:20:03 INFO - Running setup.py install for mozversion 10:20:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:20:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:20:03 INFO - Cleaning up... 10:20:03 INFO - Return code: 0 10:20:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:03 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 0x107a22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff112c6f2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078c6d30>, '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 0x1076eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078bdb20>, '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.d8JZs3aQ3o/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.eH7e1xczai/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 10:20:03 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 10:20:03 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 10:20:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8JZs3aQ3o/Render', 10:20:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:03 INFO - 'HOME': '/Users/cltbld', 10:20:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:03 INFO - 'LOGNAME': 'cltbld', 10:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:03 INFO - 'MOZ_NO_REMOTE': '1', 10:20:03 INFO - 'NO_EM_RESTART': '1', 10:20:03 INFO - 'PAGER': '/bin/cat', 10:20:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:03 INFO - 'PWD': '/builds/slave/test', 10:20:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:03 INFO - 'SHELL': '/bin/bash', 10:20:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eH7e1xczai/Listeners', 10:20:03 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:20:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:03 INFO - 'USER': 'cltbld', 10:20:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:03 INFO - 'XPC_FLAGS': '0x0', 10:20:03 INFO - 'XPC_SERVICE_NAME': '0', 10:20:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:20:03 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-B5IUoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:20:04 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)) 10:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-mmL9Y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:20:04 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)) 10:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-9TnzTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:20:04 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)) 10:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Aaef5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:20:04 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)) 10:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-7ojMke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:20:04 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)) 10:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-nKHWTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:20:04 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)) 10:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-eyoQUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:20:04 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)) 10:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-4l5jEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:20:04 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)) 10:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-zl5Jyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:20:04 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)) 10:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-R3QmXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:20:04 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)) 10:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-pHMbHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:20:05 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)) 10:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-tXXX4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:20:05 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)) 10:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-d9RHvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:20:05 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)) 10:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ucVqSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:20:05 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)) 10:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-SxH1gU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:05 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)) 10:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Ph9EHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:20:05 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)) 10:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-nn0WOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:20:05 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)) 10:20:05 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)) 10:20:05 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)) 10:20:05 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)) 10:20:05 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)) 10:20:05 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)) 10:20:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:20:05 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 10:20:05 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 10:20:05 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 10:20:05 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 10:20:05 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 10:20:05 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 10:20:08 INFO - Downloading blessings-1.6.tar.gz 10:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:20:08 INFO - Installing collected packages: blessings 10:20:08 INFO - Running setup.py install for blessings 10:20:08 INFO - Successfully installed blessings 10:20:08 INFO - Cleaning up... 10:20:08 INFO - Return code: 0 10:20:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:20:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:20:08 INFO - Reading from file tmpfile_stdout 10:20:08 INFO - Current package versions: 10:20:08 INFO - blessings == 1.6 10:20:08 INFO - blobuploader == 1.2.4 10:20:08 INFO - docopt == 0.6.1 10:20:08 INFO - manifestparser == 1.1 10:20:08 INFO - mozInstall == 1.12 10:20:08 INFO - mozcrash == 0.17 10:20:08 INFO - mozdebug == 0.1 10:20:08 INFO - mozdevice == 0.48 10:20:08 INFO - mozfile == 1.2 10:20:08 INFO - mozhttpd == 0.7 10:20:08 INFO - mozinfo == 0.9 10:20:08 INFO - mozleak == 0.1 10:20:08 INFO - mozlog == 3.2 10:20:08 INFO - moznetwork == 0.27 10:20:08 INFO - mozprocess == 0.22 10:20:08 INFO - mozprofile == 0.28 10:20:08 INFO - mozrunner == 6.11 10:20:08 INFO - mozscreenshot == 0.1 10:20:08 INFO - mozsystemmonitor == 0.3 10:20:08 INFO - moztest == 0.7 10:20:08 INFO - mozversion == 1.4 10:20:08 INFO - psutil == 3.1.1 10:20:08 INFO - requests == 1.2.3 10:20:08 INFO - wsgiref == 0.1.2 10:20:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:08 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')]} 10:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:08 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')]} 10:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:08 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 0x107a22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff112c6f2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078c6d30>, '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 0x1076eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078bdb20>, '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.d8JZs3aQ3o/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.eH7e1xczai/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 10:20:08 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 10:20:08 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 10:20:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8JZs3aQ3o/Render', 10:20:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:08 INFO - 'HOME': '/Users/cltbld', 10:20:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:08 INFO - 'LOGNAME': 'cltbld', 10:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:08 INFO - 'MOZ_NO_REMOTE': '1', 10:20:08 INFO - 'NO_EM_RESTART': '1', 10:20:08 INFO - 'PAGER': '/bin/cat', 10:20:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:08 INFO - 'PWD': '/builds/slave/test', 10:20:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:08 INFO - 'SHELL': '/bin/bash', 10:20:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eH7e1xczai/Listeners', 10:20:08 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:20:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:08 INFO - 'USER': 'cltbld', 10:20:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:08 INFO - 'XPC_FLAGS': '0x0', 10:20:08 INFO - 'XPC_SERVICE_NAME': '0', 10:20:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:08 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)) 10:20:08 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)) 10:20:08 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)) 10:20:08 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)) 10:20:08 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)) 10:20:08 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)) 10:20:08 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)) 10:20:08 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)) 10:20:08 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)) 10:20:08 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)) 10:20:08 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)) 10:20:08 INFO - Cleaning up... 10:20:08 INFO - Return code: 0 10:20:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:20:08 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')]} 10:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:08 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')]} 10:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:08 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 0x107a22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff112c6f2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078c6d30>, '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 0x1076eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078bdb20>, '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.d8JZs3aQ3o/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.eH7e1xczai/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 10:20:08 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 10:20:08 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 10:20:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8JZs3aQ3o/Render', 10:20:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:08 INFO - 'HOME': '/Users/cltbld', 10:20:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:08 INFO - 'LOGNAME': 'cltbld', 10:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:08 INFO - 'MOZ_NO_REMOTE': '1', 10:20:08 INFO - 'NO_EM_RESTART': '1', 10:20:08 INFO - 'PAGER': '/bin/cat', 10:20:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:08 INFO - 'PWD': '/builds/slave/test', 10:20:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:08 INFO - 'SHELL': '/bin/bash', 10:20:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eH7e1xczai/Listeners', 10:20:08 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:20:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:08 INFO - 'USER': 'cltbld', 10:20:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:08 INFO - 'XPC_FLAGS': '0x0', 10:20:08 INFO - 'XPC_SERVICE_NAME': '0', 10:20:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:09 INFO - Downloading/unpacking jsonschema 10:20:09 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 10:20:09 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 10:20:09 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 10:20:09 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 10:20:09 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 10:20:09 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 10:20:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:20:11 INFO - Downloading/unpacking functools32 (from jsonschema) 10:20:11 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 10:20:11 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 10:20:11 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 10:20:11 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 10:20:11 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 10:20:11 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 10:20:12 INFO - Downloading functools32-3.2.3-2.tar.gz 10:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:20:12 INFO - warning: no files found matching '*.txt' 10:20:12 INFO - no previously-included directories found matching 'build' 10:20:12 INFO - no previously-included directories found matching 'dist' 10:20:12 INFO - no previously-included directories found matching '.git*' 10:20:12 INFO - Installing collected packages: jsonschema, functools32 10:20:12 INFO - Running setup.py install for functools32 10:20:12 INFO - warning: no files found matching '*.txt' 10:20:12 INFO - no previously-included directories found matching 'build' 10:20:12 INFO - no previously-included directories found matching 'dist' 10:20:12 INFO - no previously-included directories found matching '.git*' 10:20:12 INFO - Successfully installed jsonschema functools32 10:20:12 INFO - Cleaning up... 10:20:12 INFO - Return code: 0 10:20:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:20:12 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')]} 10:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:12 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')]} 10:20:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:12 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 0x107a22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff112c6f2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078c6d30>, '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 0x1076eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078bdb20>, '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.d8JZs3aQ3o/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.eH7e1xczai/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 10:20:12 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 10:20:12 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 10:20:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8JZs3aQ3o/Render', 10:20:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:12 INFO - 'HOME': '/Users/cltbld', 10:20:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:12 INFO - 'LOGNAME': 'cltbld', 10:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:12 INFO - 'MOZ_NO_REMOTE': '1', 10:20:12 INFO - 'NO_EM_RESTART': '1', 10:20:12 INFO - 'PAGER': '/bin/cat', 10:20:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:12 INFO - 'PWD': '/builds/slave/test', 10:20:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:12 INFO - 'SHELL': '/bin/bash', 10:20:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eH7e1xczai/Listeners', 10:20:12 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:20:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:12 INFO - 'USER': 'cltbld', 10:20:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:12 INFO - 'XPC_FLAGS': '0x0', 10:20:12 INFO - 'XPC_SERVICE_NAME': '0', 10:20:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:13 INFO - Downloading/unpacking flake8 10:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:20:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:20:15 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 10:20:15 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 10:20:15 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 10:20:15 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 10:20:15 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 10:20:15 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 10:20:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:20:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:20:15 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 10:20:15 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 10:20:15 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 10:20:15 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 10:20:15 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 10:20:15 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 10:20:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:20: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 10:20: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 10:20: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 10:20: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 10:20: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 10:20: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 10:20:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:20:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:20:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:20:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:20:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:20:16 INFO - no previously-included directories found matching 'docs/_build' 10:20:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:20:16 INFO - Running setup.py install for pep8 10:20:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:20:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:20:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:20:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:20:16 INFO - no previously-included directories found matching 'docs/_build' 10:20:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:20:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:20:17 INFO - Cleaning up... 10:20:17 INFO - Return code: 0 10:20:17 INFO - Running post-action listener: _resource_record_post_action 10:20:17 INFO - Running post-action listener: _start_resource_monitoring 10:20:17 INFO - Starting resource monitoring. 10:20:17 INFO - ##### 10:20:17 INFO - ##### Finished create-virtualenv step (success) 10:20:17 INFO - ##### 10:20:17 INFO - ##### 10:20:17 INFO - ##### Running install step. 10:20:17 INFO - ##### 10:20:17 INFO - Running pre-action listener: _resource_record_pre_action 10:20:17 INFO - Running main action method: install 10:20:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:20:17 INFO - Reading from file tmpfile_stdout 10:20:17 INFO - Detecting whether we're running mozinstall >=1.0... 10:20:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:20:17 INFO - Reading from file tmpfile_stdout 10:20:17 INFO - Output received: 10:20:17 INFO - Usage: mozinstall [options] installer 10:20:17 INFO - Options: 10:20:17 INFO - -h, --help show this help message and exit 10:20:17 INFO - -d DEST, --destination=DEST 10:20:17 INFO - Directory to install application into. [default: 10:20:17 INFO - "/builds/slave/test"] 10:20:17 INFO - --app=APP Application being installed. [default: firefox] 10:20:17 INFO - mkdir: /builds/slave/test/build/application 10:20:17 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'] 10:20:17 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 10:20:51 INFO - Reading from file tmpfile_stdout 10:20:51 INFO - Output received: 10:20:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:20:51 INFO - Running post-action listener: _resource_record_post_action 10:20:51 INFO - ##### 10:20:51 INFO - ##### Finished install step (success) 10:20:51 INFO - ##### 10:20:51 INFO - ##### 10:20:51 INFO - ##### Running run-tests step. 10:20:51 INFO - ##### 10:20:51 INFO - Running pre-action listener: _resource_record_pre_action 10:20:51 INFO - Running main action method: run_tests 10:20:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:20:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:20:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:20:51 INFO - 2016-05-18 10:20:51.019 screenresolution[2215:29331] starting screenresolution argv=screenresolution get 10:20:51 INFO - 2016-05-18 10:20:51.021 screenresolution[2215:29331] Display 0: 1600x1200x32@60 10:20:51 INFO - 2016-05-18 10:20:51.027 screenresolution[2216:29338] starting screenresolution argv=screenresolution list 10:20:51 INFO - Available Modes on Display 0 10:20:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:20:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:20:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:20:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:20:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:20:51 INFO - Intel Iris: 10:20:51 INFO - Chipset Model: Intel Iris 10:20:51 INFO - Type: GPU 10:20:51 INFO - Bus: Built-In 10:20:51 INFO - VRAM (Dynamic, Max): 1536 MB 10:20:51 INFO - Vendor: Intel (0x8086) 10:20:51 INFO - Device ID: 0x0a2e 10:20:51 INFO - Revision ID: 0x0009 10:20:51 INFO - Displays: 10:20:51 INFO - Display: 10:20:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:20:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:20:51 INFO - Display Serial Number: 1600x1200 60 10:20:51 INFO - Main Display: Yes 10:20:51 INFO - Mirror: Off 10:20:51 INFO - Online: Yes 10:20:51 INFO - Rotation: Supported 10:20:51 INFO - Return code: 0 10:20:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:20:51 INFO - Python 2.7.3 10:20:51 INFO - Return code: 0 10:20:51 INFO - grabbing minidump binary from tooltool 10:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:51 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 0x1078c6d30>, '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 0x1076eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078bdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:20:51 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 10:20:51 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 10:20:51 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 10:20:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:20:51 INFO - Return code: 0 10:20:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:20:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:20:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:20:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:20:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:20:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:20:52 INFO - Return code: 0 10:20:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0330 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:20:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8JZs3aQ3o/Render', 10:20:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:52 INFO - 'HOME': '/Users/cltbld', 10:20:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:52 INFO - 'LOGNAME': 'cltbld', 10:20:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:20:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:52 INFO - 'MOZ_NO_REMOTE': '1', 10:20:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:20:52 INFO - 'NO_EM_RESTART': '1', 10:20:52 INFO - 'PAGER': '/bin/cat', 10:20:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:52 INFO - 'PWD': '/builds/slave/test', 10:20:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:20:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:52 INFO - 'SHELL': '/bin/bash', 10:20:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eH7e1xczai/Listeners', 10:20:52 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 10:20:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:52 INFO - 'USER': 'cltbld', 10:20:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:52 INFO - 'XPC_FLAGS': '0x0', 10:20:52 INFO - 'XPC_SERVICE_NAME': '0', 10:20:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:20:52 INFO - mozversion application_buildid: 20160518084142 10:20:52 INFO - mozversion application_changeset: a8518344e759e4887b53d6e88d56eb3cfe00788a 10:20:52 INFO - mozversion application_display_name: Nightly 10:20:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:20:52 INFO - mozversion application_name: Firefox 10:20:52 INFO - mozversion application_remotingname: firefox 10:20:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:20:52 INFO - mozversion application_vendor: Mozilla 10:20:52 INFO - mozversion application_version: 49.0a1 10:20:52 INFO - mozversion platform_buildid: 20160518084142 10:20:52 INFO - mozversion platform_changeset: a8518344e759e4887b53d6e88d56eb3cfe00788a 10:20:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:20:52 INFO - mozversion platform_version: 49.0a1 10:20:52 INFO - using testdate: 1463592052 10:20:52 INFO - actual date: 1463592052 10:20:52 INFO - starting webserver on 'localhost:58469' 10:20:52 INFO - SUITE-START | Running 2 tests 10:20:52 INFO - TEST-START | dromaeo_css 10:20:52 INFO - operating with platform_type : mac_ 10:20:52 INFO - Initialising browser for dromaeo_css test... 10:20:52 INFO - TEST-INFO | started process 2234 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpcIPPS_/profile http://localhost:58469/getInfo.html) 10:20:54 INFO - PROCESS | 2234 | __metrics Screen width/height:1600/1200 10:20:54 INFO - PROCESS | 2234 | colorDepth:24 10:20:54 INFO - PROCESS | 2234 | Browser inner width/height: 1024/689 10:20:54 INFO - PROCESS | 2234 | __metrics 10:20:55 INFO - PROCESS | 2234 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:20:55 ERROR - PROCESS | 2234 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:20:55 INFO - TEST-INFO | 2234: exit 0 10:20:55 INFO - Browser initialized. 10:20:55 INFO - Running cycle 1/1 for dromaeo_css test... 10:20:55 INFO - TEST-INFO | started process 2236 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpcIPPS_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:21:07 INFO - PROCESS | 2236 | RSS: Main: 238788608 10:21:07 INFO - PROCESS | 2236 | 10:21:59 INFO - PROCESS | 2236 | 1463592119154 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] 10:24:09 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:58469/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58469/tests/dromaeo/cssquery-ext.html) 10:24:10 INFO - PROCESS | 2236 | RSS: Main: 375353344 10:24:10 INFO - PROCESS | 2236 | 10:27:13 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:58469/tests/dromaeo/cssquery-ext.html (next: http://localhost:58469/tests/dromaeo/cssquery-jquery.html) 10:27:14 INFO - PROCESS | 2236 | RSS: Main: 377286656 10:27:14 INFO - PROCESS | 2236 | 10:30:16 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:58469/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58469/tests/dromaeo/cssquery-mootools.html) 10:30:17 INFO - PROCESS | 2236 | RSS: Main: 367525888 10:30:17 INFO - PROCESS | 2236 | 10:33:19 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:58469/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58469/tests/dromaeo/cssquery-prototype.html) 10:33:20 INFO - PROCESS | 2236 | RSS: Main: 368197632 10:33:20 INFO - PROCESS | 2236 | 10:36:23 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:58469/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58469/tests/dromaeo/cssquery-yui.html) 10:36:24 INFO - PROCESS | 2236 | RSS: Main: 334323712 10:36:24 INFO - PROCESS | 2236 | 10:39:26 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:58469/tests/dromaeo/cssquery-yui.html (next: http://localhost:58469/tests/dromaeo/cssquery-dojo.html) 10:39:27 INFO - PROCESS | 2236 | RSS: Main: 388026368 10:39:27 INFO - PROCESS | 2236 | 10:39:27 INFO - PROCESS | 2236 | __start_tp_report 10:39:27 INFO - PROCESS | 2236 | _x_x_mozilla_page_load 10:39:27 INFO - PROCESS | 2236 | _x_x_mozilla_page_load_details 10:39:27 INFO - PROCESS | 2236 | |i|pagename|runs| 10:39:27 INFO - PROCESS | 2236 | |0;dojo.html;3433.7424693148014;3435.759496835221;3458.4293591557393;3463.913402164947;3500.334936362091;5651.2370829937945;5654.575906806996;5714.771409143635;5733.369329373771;5798.24622799036;5723;5780.421957804228;5819.67991760453;5835.299764028312;5857.6485410875375;5102.923456148152;5135.409427915782;5187.948120518803;5207.453217412174;5248.107821670306;5241.292425522563;5271.894562108756;5286.497782710637;5298.417174110845;5318.521333080022;5122.462141475129;5141.408737995141;5172.715500647463;5175.197844334134;5194.428612852583;5166.276721259027;5169.043726910496;5180.300659411007;5187.974060129713;5218.582513398919;5200.5059519345605;5225.634205605609;5239.711815850127;5249.790008399659;5251.947480525184;5580.19087232349;5671.7447714852515;5725.370209276976;5751.166080918285;5791.797287094973;6266.467350275232;6297.779577714758;6306.716197771066;6309.558330916838;6314.968425157845;6239.968800156015;6296.779612713533;6313.337099604521;6319.052142178664;6322.336154703736;5739.741711622945;5788.392218817048;5827.096799995923;5876.794312199053;5954.255718035246;6295.559310848288;6300.180976473029;6305;6310.085037669604;6317.273513545909;5148.9742551287;6062.878742425127;6231.470325022333;6242.063690446469;6255.43701066897;6111.7792642805525;6131.315499379051;6206.689665516707;6244.094606282064;6329.208848893888;6246.187995560548;6267.467265282411;6293.842653933597;6301.495880329563;6305.4009869062365;6293.527985401113;6314.116023756677;6315.115883776275;6333.873322533524;6341.8731625368155;4848.8302909398;4856;4886.364772579542;4937.8271760488215;4984.22744474607;4448.488423831236;4462.776861156929;4484.977575112103;4495.100979803987;4497.437820272466;5780.450857168562;5789.131630255495;5795.362510123883;5795.623284486495;5804.071348584291;6183.628982261079;6188.071789231652;6253.405926437073;6267.811965641038;6280.623162610258;5864.530837532987;5886.470217680429;5887.4406931341455;5904.675242861684;5927.229460170151;5420.24116623673;5432.891342173134;5442.863928401743;5443.755031023535;5451.972740136274;6221.626702397871;6254.843628909313;6283.2460104787715;6309.211348581427;6355.650439225887;5621.971890140523;5955.225820643289;6279.623222606659;6287.119803227643;6292.874142517216;6283.685815709287;6366.586171898812;6367.044943258548;6384.297727250092;6504;5111.565516930954;6112.786052488142;6209.285932117864;6277.686115694106;6337.556371054166;6297.842553936188;6308.747650093945;6313;6331.9366806331345;6333.113364128933;6031.24609423822;6204.410580994799;6241.843953901007;6243.313235544178;6244.375562443719;5516.310461192351;5627.831165065055;6133.202683651097;6184.381561843779;6205.286392064971;5144.66238777913;5824.650520968756;5958.463738263577;5989.610675306091;6073.665948372882;6033.12519684652;6124.173236613;6233.407826256498;6238.407351301619;6245.375462453719;6023.427774361428;6075.422834830684;6083.543734219863;6098.79532419082;6140.078988151813 10:39:27 INFO - PROCESS | 2236 | |1;ext.html;485.7134290768446;504.65179026471753;521.8017153481676;525.2357819372812;541.4909984614461;31771;34361.484577731324;42727.35908961368;43901.56098439015;44009;37169.628303716956;45008.42470513533;45451.545484545146;45475;45677.314840277424;8602.526861022641;9716.222702183826;9895.208383329353;9934.65228716995;9943.254255930799;8622.741317760478;11713.297202167898;11894.405279735978;11922.940385298061;12053.758924821543;11934.582289619864;11987.40062996851;12004.93997530011;12019.098567607422;12022.879771202264;8659.134086591353;11643.18497705161;11845.7630847383;11916.642500724993;11995.460204290785;11742.47158877844;11793.764124717458;11806.173567850168;11806.232594881305;11810.350430726307;41368.79315603433;41756.79121604403;41768;41788.37317440241;41883.7905810469;3240.54632351471;3290.8190049547266;3336.2660214752714;3819.4461803038403;3914.982104652811;29077.854610726812;29290.560641590393;29460;29491.55762663562;29634.85182574095;2663.7735792457665;2711.5797051457057;2720.170348043846;2731.2489065506948;2753.697093319733;436672;517236;518533;519508;519936.40031799977;63673.68163159247;63851.72296554136;63917.80410979433;64005.35994639994;64103;37213.25573488569;37987.81006095007;38004.04989875275;38996.610033899866;67931.66034169897;15487.690246195012;17135.05757183367;17231.827681723273;17244.965302081917;17342.566435839206;11877.643670689893;12112.273263604036;12885.162464439969;13092.410841512155;13096.738065238642;17961.1019449027;19093.613298337707;20231.786092834478;20432.365410767103;20451.386458406272;7969.521828690297;8049.3962952778775;8081.353491720648;8116.472429292077;8136.430449868571;901.1889299630385;997.835357166074;1047.9109275711498;1067.7170549804307;1176.325097078981;29958.40083198324;29962.700372996427;29980.850095749818;30833.537496937563;30845.383092338026;1096.9067629251444;1118.4351902289454;1187.5487314820314;1209.5464200924653;1229.1825935752624;15434.6141346465;15468.84531154674;15570.221488925736;15817;15879.920600396925;720484;721654;722918;723596.3482556276;724035;60557;62144.68927655333;62320.75358492805;62368.68815655893;62381;1284.479785686817;1302.5245785288303;1451.8112645356036;1492.410497853342;1507.6683129711446;27785.305367365978;28436.720347583996;29410.55884161782;31205.063848084596;31361.215969600944;51407.74296128495;52108.17621383233;52811;52834.73582632062;52918.94162116659;565335;568565;568935;570274;570792;24506.754932450447;26084.478310434064;27227.72772272252;27328.453430930873;27363;46759;46840;47288;47777;48179.277310839665;36639.63360366362;37888.81055594704;38255.852324430314;38872;38910.221795564496;37347.43978840374;37512.249755005294;38107.809460952514;38329.6167038326;38579.80710096432;30079.398412032075;37176.44235336526;37193;38775.41836872393;38874;28078;33098.338033239685;33156.50265245975;33402.164945876546;33501.04172391584;24769.752302477464;24827;24966.50066998686;25237.11670091538;25417 10:39:27 INFO - PROCESS | 2236 | |2;jquery.html;388.9591592882747;390.2974645637851;396.65491022810124;398.75675837738993;417.228127963268;13790;15110.546683599483;17542.386016489425;21668.34994950154;21716.022778974944;16078.75881861773;16522.504324870242;18062.4581262562;19623.5094122647;20712.585748285026;5659.8585035374235;5989.311229208632;5994.310654274749;5997.910031349533;5998.130271110686;5783.479486846183;5841.3282472515775;5843.065109582468;5857.062869940811;5909.9704501477645;5437.157240627689;5474.069408200596;5723.656580605156;5774.191613174159;5811.767529298823;5757.136429535562;5783.71081445926;5795.739191736382;5807.332156801937;5854.18041474194;4916.975415122937;5046.167382381903;5076.086304465195;5087.364079490064;5106.259592359124;25886.22341329763;26504.60243096355;26632.33419164517;27093.45813083747;27249;1904.4001139640968;2117.587070521249;2172.6306527890297;2423.3578101803037;2693.824901381404;12059.166095967441;15098.24508774568;15207.391704331814;15317.387304507807;15502.30239639219;1714.6742118997352;1783.8840475369188;1793.3633560086112;1804.8917064976013;1877.662020836249;73945;111605.44197278962;116724;117464;118142;10878.456077196108;11092;11312.20814542974;11367.943160284145;11447.370394628375;14087.22520261375;14197.716045679028;14225.07537010091;14227.715445691028;14246.501372451914;7764.417668674759;8139.837203255902;8152.2662960333855;8264.545450000187;8532.488050716976;7390.260973902567;7941.880871786928;8122.83754324922;8669.352822963616;8837.690680826141;6632.336766323329;6824.078749368871;6848.363380098698;6854.588724676535;6854.931450685529;1686.8228835972045;1809.9185536650882;1871.1579789094963;1880.8213219744102;1908.9236430542624;799.984000320002;889.3063410539789;927.536231884058;960.313375936195;1034.3276870034388;20433.386998390073;21500.764778214143;21586.892065539887;21591.2443064492;21697.132114715234;953.2613665732391;997.5959736306808;1018.414411713253;1019.4189312092185;1077.7413420778958;11001.449927503592;11294.717632059264;11615.535378584931;11646.534138634359;11773.88226117745;559084;603340;605748;627060;627827;104879;127071;128149;131957.34021330025;132996;917.156216281023;1027.5170669785189;1050.1598721023151;1052.1214785654065;1067.5943141606294;19185.040747962827;19607.31374401889;19775.40673779789;19778.60442791165;19815.702764458256;49774.75563110951;52303;52954.94090118099;53817.192742109684;54816.629584260714;74095;74425.6278718603;75211.62394187994;76668.61665691636;77099.61450192714;8117.350611951148;8210.425270231026;8248.876266856121;8295.626696798538;8571.22858942698;11391.829122563002;11926.701832454259;12694.238345699288;12801.679958001125;13412.865871341162;10159.593616255563;11256.043236325004;11468.31190128595;11685.364049032973;11726.765464690829;5380.542653874464;10071.798564028531;10623;11154.13633388443;11372;7892.763217103496;11527.250728702607;11733.647990560297;11865.525378985089;11960.401979900797;7943.563104029334;7978.680852765824;8204.671813127408;8232.753017409488;8394.958025210079;9940.950295248478;11401.771964560496;11508.194426390073;11712.531498739954;11785.941070294593 10:39:27 INFO - PROCESS | 2236 | |3;mootools.html;1437.5902867682712;1605.6547842213922;1630.0138416258164;1861.4694811978588;1970.9408717738463;8076.3538916886655;8565.143485651424;8568.186022327876;8592.828143437144;8801.691940782073;8778.736637900858;8784.868226976585;8986.86519702205;8994.190522852941;8995.730128096151;2452.472718365551;2478.120267305108;2479.727230004698;2481.5110933439883;2481.838680485772;2418.3107814272935;2485.440775825434;2487.987560062197;2488.9377765555914;2491.389609545664;2448.326710154704;2456.6806315179;2482.4290413204885;2483.5405449991717;2486.5648511510503;2421.9757802422014;2453.7055553333535;2459.434330104077;2462.581361168606;2467.494163696438;2421;2447.131268399728;2456.877156142186;2459.495803360307;2462.3597864555168;8870.645174193025;8928.598213080362;8935.151160639729;8946.686865959726;8948.955255223747;5328.147496400557;5408.4050754416985;5574.331080270353;5591.9720401397735;5599.580031497654;3420.350133474632;3426.6573342665783;3528.576570811493;3549.804760738158;3556.8399422026073;992.2062350119875;996.880374355082;1006.2704539209088;1019.306871327497;1027.3476342522551;12008.939955300255;12059.336736479578;12129.636110916686;12138.575149869801;12140.817887731691;4608.884777880513;4783.402074740658;4784.114938736345;4884.4871288514905;5133.948660513422;9417.058294170529;9483.525823708787;9551.713448596553;9558.235341172689;9559.952200238955;5483.287172667528;5485.149801780731;5489.066858634042;5490.615656904059;5491.972540137273;4561.680682352204;4591.0387799868895;4932.679375840559;5092.694438333712;5128.743562821844;3886.7279290449965;3900.4983081513255;3956.3076461619103;4009.8997525062105;4015.759054456742;1198.6763573835017;1210.9455074521668;1211.4730092409654;1225.6935766058486;1226.877312268766;661.7121552124842;703.4829400390709;839.5088873009358;855.5166331022953;978.7895602445508;5768.134779783066;5924.4667979882015;6156.507479401637;6206.06908963659;6269.905951410732;860.9655613775504;939.2110627073291;999.7800483893532;1002.8545860850281;1002.9849552256723;3221.838908054588;3966.960330396659;4076.775777332216;4106.527749308868;4112.465379500646;10530.789384212272;11270.943645281885;11272.380019098864;11292.43537823122;11297.887021129683;10287.537060832281;10551.050405463546;11118.833217501744;11290.830637540443;11323.207375483802;7168.354848063699;8428.704995325133;8429.494230346076;8432.409731318749;8436.746897592959;5864.296284445723;5901.763929442946;5935.258092738408;6071.605345652519;6150.507959363151;9717.271204659537;10004.549795259085;10301.690949271235;10304.793904121725;10325.9483702581;10366.222533309881;10512.842307365243;10515.789684206426;10525.157987361146;10531.262811603114;8256.13310602378;8344.916550834414;8386.287165590993;8475;8499.83000340002;8383.119772424052;8399.28606068491;8497.490150590984;8499.277561407349;8507.021692505441;5806.738696758572;7244.818879528054;7380.77857664271;7618.847623047619;7883.2905038546805;7335.523190992569;7491.513051651626;7538.472306938681;7583.05211848519;7600.011998440167;7164.247753985756;7174.641267936687;7246.818829529304;7261.636918154177;7280.199178090512;4811.975940120277;4921.4832442593015;5007.47421520735;5026.246063090565;5186.507281808223;4980.302757614009;5030.698158110525;5047.167217409086;5102.642815002914;5321.36143662763 10:39:27 INFO - PROCESS | 2236 | |4;prototype.html;306.5172353543169;311.7568296728552;323.96436391996883;325.6287831871665;327.93605246976836;5099.337086178796;5363.973180134104;5374.462553744624;5379.98250656004;5411.563657077754;4234.470691163605;4564.406627138467;5004.924926126102;5211.3746350437905;5283.656562323446;3976.7017473689443;3976.965988842893;4002.2995975704134;4037.6769858411403;4063.288924438219;3552.325058238939;3824.3116239076953;3882.165334453092;4058.6955978301744;4065.7560546367163;3224.2423030587793;3549.32562813066;3792.260509200707;3857.614238576148;3960.4950118954757;3493.3188028334484;3600.6039335673054;3826.789526576037;4055.776932268724;4056.006278461781;2961.8074825136296;3165.271987442878;3544.2379888324135;3558.8220588970707;3558.91102722434;4866.975665121652;5013.824516141954;5015.699058056528;5020.493851844432;5101.336826212606;4437.9570800861775;4645.605123564501;4687.617078032017;4708.646851486153;4729.456112547033;4692.36653051837;4846.5638092571835;5058.785891386041;5599.524040456566;5616.7142221800295;3489.232368878868;3506.1760486285702;3822.6750726188307;3926.293267211901;3957.6240257175523;4514.661400394951;5183.352080989876;5921.756431149463;5938.851528711773;5950.107483877409;3479.4258947273925;3706.4810926470313;3857.9228415431535;3886.436466712311;3905.589913059088;3389.81356025416;3456.498807672873;3723.6090210528046;3959.2279505496153;3979.184267225212;3898.6101389860787;4090.8977275567754;4270.615644592001;4272.401863739079;4646.652470783502;5096.534356637522;5237.947620523746;5603.747831347518;5648.531381840751;5659.43405659439;1116.815725405299;1233.5805826019018;1350.628577141294;1355.3494322725146;1366.8906487480979;755.199488542147;779.3881802784787;872.4111224923151;878.6221924572368;922.262190247799;5222.843314700565;5231.09674130861;5273.789048438096;5287.41838397776;5310.9734451327495;5693.572982026365;5703.572232082611;5774.88450230993;5796.739146738407;5813.854653633693;4102.712810103264;4707.8587642370785;4779.832705855279;5167.276581278624;5346.4118946915805;2378.429176997508;2511.296836885675;2983.2392739851634;3027.2734543709353;3057.770667199969;5004.499550044967;5076.796928122908;5203.375594928632;5844.977129002407;6095.049584133035;5276.709780962084;5458.372287186945;5504.6421982571;5901.645901245939;5981.940180598225;5997.940020599913;6003.849903752529;6019.434946913834;6038.5772995889865;6057.909131363033;4970.975145124251;5405.567554595702;5840.532757379315;5846.6784326862435;5879.441453061952;5660.433956604306;5684.687342196054;5728.3985181557;5748.511376532874;5789.855253618524;5823.679697616672;6038.849028774316;6054.364291749479;6084.60450070744;6094.963856144377;3895.4156876468755;3908.3160446921106;3913.8630147944687;3917.6082391760597;3927.253821773968;5172.922406163835;5523.696196709059;5728.885422291614;5818.359980402237;5852.707364631837;4514.503404625554;5645.943540564706;5704.629199102045;5711.514521265739;5715.5713321500225;4884.438289596742;5247.475252474875;5438.320209973754;5616.522595579421;5632.577556683347;4869.4400143982475;5267.657602255841;5421.457854214547;5522.806701765579;5527.751251193626;5548.557375082427;5578.721063946868;5598;5639.251831932114;5666.17840413146;5226.2230841514065;5463.322971743737;5469.453054694658;5470.288862447856;5491.560675146058 10:39:27 INFO - PROCESS | 2236 | |5;yui.html;631.9526035547332;644.5101722690754;651.8533330000749;753.9886901696475;787.3111027850631;14986.775198372034;15397.615059623515;15407.845921540782;15429.691406171884;15454.922725386372;13733.313334333294;14261.215633140173;14286.214258215794;14318;14320.928395358009;891.9598618062171;895;911.1344222988155;911.5806728904679;912.1562554636968;896.9686060987867;898.2634239923266;899.9910000900005;901.0358915959926;901.5762591581979;950.9762255943588;954.203240294355;955.1356022799378;955.5461155950902;957.1481381570374;945.2626950978248;946.4557879219408;947.3652652722658;947.9905200947971;950.0357137505438;708.5075872268775;709.7799682098566;709.9112610923635;710.0378986473306;710.0627172132787;13148.276844773534;13168.53910113151;13239.735205295936;13265.66835829112;13327.400266987912;11094;11144.442777861155;11280.435978201058;11322.660320190407;11785.351805650685;7546;8349.373796965252;9219.400738952014;9317.534123293808;9351.438913665133;2843.5734639803986;2985.7909946303766;3408.062782734743;3472.895813125585;3592.5329707138167;81852.18147818506;82363.5881820593;82382;82631.58684206601;82745.17254827495;8695.869561956575;9076.81846363069;9348.953255233817;9493.477858717837;9585.760355991017;20891.16435342569;20913;20992.370228893156;21008.054637541347;21270.468238293855;6750.5274630775375;7185.425165986709;7353.816154596071;7359.116905971211;7630.809229769189;3308.9338213236083;4030.314846476106;4048.2105989332385;4073.837046518106;4091.4067460218102;3000.204945689394;3490.1798077451776;3568.5717713873983;3574.6425357464045;3583.874564390234;639.3350915048297;641.1568787045114;645.335304636216;646.1341801985268;658.3745441830281;541.3666010767412;543.739005277469;559.4098226371217;566.1903478026379;585.7803323753592;7725.343345815556;7871.291583757489;7888.802779930433;7946.6424010919645;8201.466904651297;538.9595780316414;539.2692901118927;568.0740393159207;579.1168468086119;580.1819434597197;3592.820358982093;3757.28611563802;3757.5866654667966;3788.2234142000825;3800.790956497364;81024.37951240942;83929.02674245936;83930;92297.53851230702;95835;79691;82207.58896205481;82495.3500929978;83266;86438.56780716057;74608;75580;75669;76651;76837;6314.810555683337;8214.3428525719;8277.089520152658;8418.915810842058;8461.830763384822;39308.213835722556;41822.58177418187;45089;46480.767596163154;46791.59625211249;64904;65563;66036.67926641344;66583.00125498218;67158;10836.295640783324;10919.945400272947;11227.38249396291;11395.886041139482;11978.397156890134;11809.468573914022;11920.880791192323;11986.041116710468;12058.51765929353;12235;9589.904100958902;11298.983091521803;11394.715132121763;11434.885651143382;11549.595764148215;11197.385361988581;11315.660530184108;11317.207795454238;11469.910358515928;11772.587959421378;11176.497057632589;11268.60559880433;11271.830922536004;11342.262752921033;11714.76570468603;9358.344915855825;9495.620175192915;9526.52367381642;9593.42439453635;9619.615215391306;7424.665890034853;9168.862467062865;9246.537673116452;9580.28147888925;9602.807943841224 10:39:27 INFO - PROCESS | 2236 | __end_tp_report 10:39:27 INFO - PROCESS | 2236 | __start_cc_report 10:39:27 INFO - PROCESS | 2236 | _x_x_mozilla_cycle_collect,1278 10:39:27 INFO - PROCESS | 2236 | __end_cc_report 10:39:27 INFO - PROCESS | 2236 | __startTimestamp1463593167134__endTimestamp 10:39:27 INFO - PROCESS | 2236 | 10:39:27 INFO - PROCESS | 2236 | ------- Summary: start ------- 10:39:27 INFO - PROCESS | 2236 | Number of tests: 6 10:39:27 INFO - PROCESS | 2236 | 10:39:27 INFO - PROCESS | 2236 | [#0] dojo.html Cycles:165 Average:5765.30 Median:5955.23 stddev:644.91 (10.8%) stddev-sans-first:620.41 10:39:27 INFO - PROCESS | 2236 | Values: 3433.7 3435.8 3458.4 3463.9 3500.3 5651.2 5654.6 5714.8 5733.4 5798.2 5723.0 5780.4 5819.7 5835.3 5857.6 5102.9 5135.4 5187.9 5207.5 5248.1 5241.3 5271.9 5286.5 5298.4 5318.5 5122.5 5141.4 5172.7 5175.2 5194.4 5166.3 5169.0 5180.3 5188.0 5218.6 5200.5 5225.6 5239.7 5249.8 5251.9 5580.2 5671.7 5725.4 5751.2 5791.8 6266.5 6297.8 6306.7 6309.6 6315.0 6240.0 6296.8 6313.3 6319.1 6322.3 5739.7 5788.4 5827.1 5876.8 5954.3 6295.6 6300.2 6305.0 6310.1 6317.3 5149.0 6062.9 6231.5 6242.1 6255.4 6111.8 6131.3 6206.7 6244.1 6329.2 6246.2 6267.5 6293.8 6301.5 6305.4 6293.5 6314.1 6315.1 6333.9 6341.9 4848.8 4856.0 4886.4 4937.8 4984.2 4448.5 4462.8 4485.0 4495.1 4497.4 5780.5 5789.1 5795.4 5795.6 5804.1 6183.6 6188.1 6253.4 6267.8 6280.6 5864.5 5886.5 5887.4 5904.7 5927.2 5420.2 5432.9 5442.9 5443.8 5452.0 6221.6 6254.8 6283.2 6309.2 6355.7 5622.0 5955.2 6279.6 6287.1 6292.9 6283.7 6366.6 6367.0 6384.3 6504.0 5111.6 6112.8 6209.3 6277.7 6337.6 6297.8 6308.7 6313.0 6331.9 6333.1 6031.2 6204.4 6241.8 6243.3 6244.4 5516.3 5627.8 6133.2 6184.4 6205.3 5144.7 5824.7 5958.5 5989.6 6073.7 6033.1 6124.2 6233.4 6238.4 6245.4 6023.4 6075.4 6083.5 6098.8 6140.1 10:39:27 INFO - PROCESS | 2236 | 10:39:27 INFO - PROCESS | 2236 | [#1] ext.html Cycles:180 Average:72580.74 Median:33931.26 stddev:162100.34 (477.7%) stddev-sans-first:162464.69 10:39:27 INFO - PROCESS | 2236 | Values: 485.7 504.7 521.8 525.2 541.5 31771.0 34361.5 42727.4 43901.6 44009.0 37169.6 45008.4 45451.5 45475.0 45677.3 8602.5 9716.2 9895.2 9934.7 9943.3 8622.7 11713.3 11894.4 11922.9 12053.8 11934.6 11987.4 12004.9 12019.1 12022.9 8659.1 11643.2 11845.8 11916.6 11995.5 11742.5 11793.8 11806.2 11806.2 11810.4 41368.8 41756.8 41768.0 41788.4 41883.8 3240.5 3290.8 3336.3 3819.4 3915.0 29077.9 29290.6 29460.0 29491.6 29634.9 2663.8 2711.6 2720.2 2731.2 2753.7 436672.0 517236.0 518533.0 519508.0 519936.4 63673.7 63851.7 63917.8 64005.4 64103.0 37213.3 37987.8 38004.0 38996.6 67931.7 15487.7 17135.1 17231.8 17245.0 17342.6 11877.6 12112.3 12885.2 13092.4 13096.7 17961.1 19093.6 20231.8 20432.4 20451.4 7969.5 8049.4 8081.4 8116.5 8136.4 901.2 997.8 1047.9 1067.7 1176.3 29958.4 29962.7 29980.9 30833.5 30845.4 1096.9 1118.4 1187.5 1209.5 1229.2 15434.6 15468.8 15570.2 15817.0 15879.9 720484.0 721654.0 722918.0 723596.3 724035.0 60557.0 62144.7 62320.8 62368.7 62381.0 1284.5 1302.5 1451.8 1492.4 1507.7 27785.3 28436.7 29410.6 31205.1 31361.2 51407.7 52108.2 52811.0 52834.7 52918.9 565335.0 568565.0 568935.0 570274.0 570792.0 24506.8 26084.5 27227.7 27328.5 27363.0 46759.0 46840.0 47288.0 47777.0 48179.3 36639.6 37888.8 38255.9 38872.0 38910.2 37347.4 37512.2 38107.8 38329.6 38579.8 30079.4 37176.4 37193.0 38775.4 38874.0 28078.0 33098.3 33156.5 33402.2 33501.0 24769.8 24827.0 24966.5 25237.1 25417.0 10:39:27 INFO - PROCESS | 2236 | 10:39:27 INFO - PROCESS | 2236 | [#2] jquery.html Cycles:180 Average:34821.99 Median:11944.33 stddev:100644.11 (842.6%) stddev-sans-first:100893.23 10:39:27 INFO - PROCESS | 2236 | Values: 389.0 390.3 396.7 398.8 417.2 13790.0 15110.5 17542.4 21668.3 21716.0 16078.8 16522.5 18062.5 19623.5 20712.6 5659.9 5989.3 5994.3 5997.9 5998.1 5783.5 5841.3 5843.1 5857.1 5910.0 5437.2 5474.1 5723.7 5774.2 5811.8 5757.1 5783.7 5795.7 5807.3 5854.2 4917.0 5046.2 5076.1 5087.4 5106.3 25886.2 26504.6 26632.3 27093.5 27249.0 1904.4 2117.6 2172.6 2423.4 2693.8 12059.2 15098.2 15207.4 15317.4 15502.3 1714.7 1783.9 1793.4 1804.9 1877.7 73945.0 111605.4 116724.0 117464.0 118142.0 10878.5 11092.0 11312.2 11367.9 11447.4 14087.2 14197.7 14225.1 14227.7 14246.5 7764.4 8139.8 8152.3 8264.5 8532.5 7390.3 7941.9 8122.8 8669.4 8837.7 6632.3 6824.1 6848.4 6854.6 6854.9 1686.8 1809.9 1871.2 1880.8 1908.9 800.0 889.3 927.5 960.3 1034.3 20433.4 21500.8 21586.9 21591.2 21697.1 953.3 997.6 1018.4 1019.4 1077.7 11001.4 11294.7 11615.5 11646.5 11773.9 559084.0 603340.0 605748.0 627060.0 627827.0 104879.0 127071.0 128149.0 131957.3 132996.0 917.2 1027.5 1050.2 1052.1 1067.6 19185.0 19607.3 19775.4 19778.6 19815.7 49774.8 52303.0 52954.9 53817.2 54816.6 74095.0 74425.6 75211.6 76668.6 77099.6 8117.4 8210.4 8248.9 8295.6 8571.2 11391.8 11926.7 12694.2 12801.7 13412.9 10159.6 11256.0 11468.3 11685.4 11726.8 5380.5 10071.8 10623.0 11154.1 11372.0 7892.8 11527.3 11733.6 11865.5 11960.4 7943.6 7978.7 8204.7 8232.8 8395.0 9941.0 11401.8 11508.2 11712.5 11785.9 10:39:27 INFO - PROCESS | 2236 | 10:39:27 INFO - PROCESS | 2236 | [#3] mootools.html Cycles:180 Average:5827.03 Median:5028.47 stddev:3274.21 (65.1%) stddev-sans-first:3266.78 10:39:27 INFO - PROCESS | 2236 | Values: 1437.6 1605.7 1630.0 1861.5 1970.9 8076.4 8565.1 8568.2 8592.8 8801.7 8778.7 8784.9 8986.9 8994.2 8995.7 2452.5 2478.1 2479.7 2481.5 2481.8 2418.3 2485.4 2488.0 2488.9 2491.4 2448.3 2456.7 2482.4 2483.5 2486.6 2422.0 2453.7 2459.4 2462.6 2467.5 2421.0 2447.1 2456.9 2459.5 2462.4 8870.6 8928.6 8935.2 8946.7 8949.0 5328.1 5408.4 5574.3 5592.0 5599.6 3420.4 3426.7 3528.6 3549.8 3556.8 992.2 996.9 1006.3 1019.3 1027.3 12008.9 12059.3 12129.6 12138.6 12140.8 4608.9 4783.4 4784.1 4884.5 5133.9 9417.1 9483.5 9551.7 9558.2 9560.0 5483.3 5485.1 5489.1 5490.6 5492.0 4561.7 4591.0 4932.7 5092.7 5128.7 3886.7 3900.5 3956.3 4009.9 4015.8 1198.7 1210.9 1211.5 1225.7 1226.9 661.7 703.5 839.5 855.5 978.8 5768.1 5924.5 6156.5 6206.1 6269.9 861.0 939.2 999.8 1002.9 1003.0 3221.8 3967.0 4076.8 4106.5 4112.5 10530.8 11270.9 11272.4 11292.4 11297.9 10287.5 10551.1 11118.8 11290.8 11323.2 7168.4 8428.7 8429.5 8432.4 8436.7 5864.3 5901.8 5935.3 6071.6 6150.5 9717.3 10004.5 10301.7 10304.8 10325.9 10366.2 10512.8 10515.8 10525.2 10531.3 8256.1 8344.9 8386.3 8475.0 8499.8 8383.1 8399.3 8497.5 8499.3 8507.0 5806.7 7244.8 7380.8 7618.8 7883.3 7335.5 7491.5 7538.5 7583.1 7600.0 7164.2 7174.6 7246.8 7261.6 7280.2 4812.0 4921.5 5007.5 5026.2 5186.5 4980.3 5030.7 5047.2 5102.6 5321.4 10:39:27 INFO - PROCESS | 2236 | 10:39:27 INFO - PROCESS | 2236 | [#4] prototype.html Cycles:180 Average:4509.12 Median:5097.94 stddev:1422.72 (27.9%) stddev-sans-first:1391.30 10:39:27 INFO - PROCESS | 2236 | Values: 306.5 311.8 324.0 325.6 327.9 5099.3 5364.0 5374.5 5380.0 5411.6 4234.5 4564.4 5004.9 5211.4 5283.7 3976.7 3977.0 4002.3 4037.7 4063.3 3552.3 3824.3 3882.2 4058.7 4065.8 3224.2 3549.3 3792.3 3857.6 3960.5 3493.3 3600.6 3826.8 4055.8 4056.0 2961.8 3165.3 3544.2 3558.8 3558.9 4867.0 5013.8 5015.7 5020.5 5101.3 4438.0 4645.6 4687.6 4708.6 4729.5 4692.4 4846.6 5058.8 5599.5 5616.7 3489.2 3506.2 3822.7 3926.3 3957.6 4514.7 5183.4 5921.8 5938.9 5950.1 3479.4 3706.5 3857.9 3886.4 3905.6 3389.8 3456.5 3723.6 3959.2 3979.2 3898.6 4090.9 4270.6 4272.4 4646.7 5096.5 5237.9 5603.7 5648.5 5659.4 1116.8 1233.6 1350.6 1355.3 1366.9 755.2 779.4 872.4 878.6 922.3 5222.8 5231.1 5273.8 5287.4 5311.0 5693.6 5703.6 5774.9 5796.7 5813.9 4102.7 4707.9 4779.8 5167.3 5346.4 2378.4 2511.3 2983.2 3027.3 3057.8 5004.5 5076.8 5203.4 5845.0 6095.0 5276.7 5458.4 5504.6 5901.6 5981.9 5997.9 6003.8 6019.4 6038.6 6057.9 4971.0 5405.6 5840.5 5846.7 5879.4 5660.4 5684.7 5728.4 5748.5 5789.9 5823.7 6038.8 6054.4 6084.6 6095.0 3895.4 3908.3 3913.9 3917.6 3927.3 5172.9 5523.7 5728.9 5818.4 5852.7 4514.5 5645.9 5704.6 5711.5 5715.6 4884.4 5247.5 5438.3 5616.5 5632.6 4869.4 5267.7 5421.5 5522.8 5527.8 5548.6 5578.7 5598.0 5639.3 5666.2 5226.2 5463.3 5469.5 5470.3 5491.6 10:39:27 INFO - PROCESS | 2236 | 10:39:27 INFO - PROCESS | 2236 | [#5] yui.html Cycles:180 Average:18096.96 Median:32775.07 stddev:25889.70 (79.0%) stddev-sans-first:25929.11 10:39:27 INFO - PROCESS | 2236 | Values: 632.0 644.5 651.9 754.0 787.3 14986.8 15397.6 15407.8 15429.7 15454.9 13733.3 14261.2 14286.2 14318.0 14320.9 892.0 895.0 911.1 911.6 912.2 897.0 898.3 900.0 901.0 901.6 951.0 954.2 955.1 955.5 957.1 945.3 946.5 947.4 948.0 950.0 708.5 709.8 709.9 710.0 710.1 13148.3 13168.5 13239.7 13265.7 13327.4 11094.0 11144.4 11280.4 11322.7 11785.4 7546.0 8349.4 9219.4 9317.5 9351.4 2843.6 2985.8 3408.1 3472.9 3592.5 81852.2 82363.6 82382.0 82631.6 82745.2 8695.9 9076.8 9349.0 9493.5 9585.8 20891.2 20913.0 20992.4 21008.1 21270.5 6750.5 7185.4 7353.8 7359.1 7630.8 3308.9 4030.3 4048.2 4073.8 4091.4 3000.2 3490.2 3568.6 3574.6 3583.9 639.3 641.2 645.3 646.1 658.4 541.4 543.7 559.4 566.2 585.8 7725.3 7871.3 7888.8 7946.6 8201.5 539.0 539.3 568.1 579.1 580.2 3592.8 3757.3 3757.6 3788.2 3800.8 81024.4 83929.0 83930.0 92297.5 95835.0 79691.0 82207.6 82495.4 83266.0 86438.6 74608.0 75580.0 75669.0 76651.0 76837.0 6314.8 8214.3 8277.1 8418.9 8461.8 39308.2 41822.6 45089.0 46480.8 46791.6 64904.0 65563.0 66036.7 66583.0 67158.0 10836.3 10919.9 11227.4 11395.9 11978.4 11809.5 11920.9 11986.0 12058.5 12235.0 9589.9 11299.0 11394.7 11434.9 11549.6 11197.4 11315.7 11317.2 11469.9 11772.6 11176.5 11268.6 11271.8 11342.3 11714.8 9358.3 9495.6 9526.5 9593.4 9619.6 7424.7 9168.9 9246.5 9580.3 9602.8 10:39:27 INFO - PROCESS | 2236 | -------- Summary: end -------- 10:39:27 INFO - PROCESS | 2236 | 10:39:27 INFO - TEST-INFO | 2236: exit 0 10:39:27 INFO - TEST-OK | dromaeo_css | took 1115028ms 10:39:27 INFO - TEST-START | kraken 10:39:27 INFO - operating with platform_type : mac_ 10:39:27 INFO - Initialising browser for kraken test... 10:39:27 INFO - TEST-INFO | started process 2240 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpwg8pAE/profile http://localhost:58469/getInfo.html) 10:39:30 INFO - PROCESS | 2240 | __metrics Screen width/height:1600/1200 10:39:30 INFO - PROCESS | 2240 | colorDepth:24 10:39:30 INFO - PROCESS | 2240 | Browser inner width/height: 1024/689 10:39:30 INFO - PROCESS | 2240 | __metrics 10:39:30 INFO - PROCESS | 2240 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:39:30 ERROR - PROCESS | 2240 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:39:30 INFO - TEST-INFO | 2240: exit 0 10:39:30 INFO - Browser initialized. 10:39:30 INFO - Running cycle 1/1 for kraken test... 10:39:30 INFO - TEST-INFO | started process 2242 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpwg8pAE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:39:42 INFO - PROCESS | 2242 | RSS: Main: 238718976 10:39:42 INFO - PROCESS | 2242 | 10:39:45 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58469/tests/kraken/driver.html?testName=audio-beat-detection) 10:39:46 INFO - PROCESS | 2242 | RSS: Main: 286830592 10:39:46 INFO - PROCESS | 2242 | 10:39:49 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58469/tests/kraken/driver.html?testName=audio-dft) 10:39:49 INFO - PROCESS | 2242 | RSS: Main: 297287680 10:39:49 INFO - PROCESS | 2242 | 10:39:53 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58469/tests/kraken/driver.html?testName=audio-fft) 10:39:53 INFO - PROCESS | 2242 | RSS: Main: 389668864 10:39:53 INFO - PROCESS | 2242 | 10:39:56 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58469/tests/kraken/driver.html?testName=audio-oscillator) 10:39:56 INFO - PROCESS | 2242 | RSS: Main: 287100928 10:39:56 INFO - PROCESS | 2242 | 10:39:58 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58469/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:39:59 INFO - PROCESS | 2242 | RSS: Main: 290865152 10:39:59 INFO - PROCESS | 2242 | 10:40:02 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58469/tests/kraken/driver.html?testName=imaging-darkroom) 10:40:03 INFO - PROCESS | 2242 | RSS: Main: 327311360 10:40:03 INFO - PROCESS | 2242 | 10:40:06 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58469/tests/kraken/driver.html?testName=imaging-desaturate) 10:40:06 INFO - PROCESS | 2242 | RSS: Main: 329920512 10:40:06 INFO - PROCESS | 2242 | 10:40:09 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58469/tests/kraken/driver.html?testName=json-parse-financial) 10:40:10 INFO - PROCESS | 2242 | RSS: Main: 328749056 10:40:10 INFO - PROCESS | 2242 | 10:40:12 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58469/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:40:12 INFO - PROCESS | 2242 | RSS: Main: 316473344 10:40:12 INFO - PROCESS | 2242 | 10:40:14 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58469/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:40:15 INFO - PROCESS | 2242 | RSS: Main: 323366912 10:40:15 INFO - PROCESS | 2242 | 10:40:17 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58469/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:40:18 INFO - PROCESS | 2242 | RSS: Main: 325959680 10:40:18 INFO - PROCESS | 2242 | 10:40:21 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58469/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:40:21 INFO - PROCESS | 2242 | RSS: Main: 330645504 10:40:21 INFO - PROCESS | 2242 | 10:40:24 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58469/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:40:25 INFO - PROCESS | 2242 | RSS: Main: 328507392 10:40:25 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | Cycle 1(1): loaded http://localhost:58469/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58469/tests/kraken/driver.html?testName=ai-astar) 10:40:27 INFO - PROCESS | 2242 | RSS: Main: 374837248 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | __start_tp_report 10:40:27 INFO - PROCESS | 2242 | _x_x_mozilla_page_load 10:40:27 INFO - PROCESS | 2242 | _x_x_mozilla_page_load_details 10:40:27 INFO - PROCESS | 2242 | |i|pagename|runs| 10:40:27 INFO - PROCESS | 2242 | |0;ai-astar;110;106;112;112;111;112;109;112;112;113 10:40:27 INFO - PROCESS | 2242 | |1;audio-beat-detection;154;115;141;196;114;138;138;116;174;116 10:40:27 INFO - PROCESS | 2242 | |2;audio-dft;186;185;184;182;186;181;181;181;184;185 10:40:27 INFO - PROCESS | 2242 | |3;audio-fft;77;73;56;125;57;62;72;82;133;74 10:40:27 INFO - PROCESS | 2242 | |4;audio-oscillator;91;102;132;81;79;77;102;93;120;102 10:40:27 INFO - PROCESS | 2242 | |5;imaging-gaussian-blur;86;84;86;84;85;83;85;83;86;85 10:40:27 INFO - PROCESS | 2242 | |6;imaging-darkroom;93;93;93;94;91;93;93;93;93;92 10:40:27 INFO - PROCESS | 2242 | |7;imaging-desaturate;86;86;83;86;83;86;84;85;87;86 10:40:27 INFO - PROCESS | 2242 | |8;json-parse-financial;57;57;58;57;56;57;56;119;55;56 10:40:27 INFO - PROCESS | 2242 | |9;json-stringify-tinderbox;62;80;73;73;71;68;69;68;71;70 10:40:27 INFO - PROCESS | 2242 | |10;stanford-crypto-aes;84;83;85;85;84;82;84;82;85;85 10:40:27 INFO - PROCESS | 2242 | |11;stanford-crypto-ccm;138;143;141;136;125;142;133;137;215;142 10:40:27 INFO - PROCESS | 2242 | |12;stanford-crypto-pbkdf2;163;164;163;163;166;214;216;204;161;165 10:40:27 INFO - PROCESS | 2242 | |13;stanford-crypto-sha256-iterative;66;68;66;66;67;65;65;66;71;80 10:40:27 INFO - PROCESS | 2242 | __end_tp_report 10:40:27 INFO - PROCESS | 2242 | __start_cc_report 10:40:27 INFO - PROCESS | 2242 | _x_x_mozilla_cycle_collect,2301 10:40:27 INFO - PROCESS | 2242 | __end_cc_report 10:40:27 INFO - PROCESS | 2242 | __startTimestamp1463593227538__endTimestamp 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | ------- Summary: start ------- 10:40:27 INFO - PROCESS | 2242 | Number of tests: 14 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:2.08 (1.9%) stddev-sans-first:2.18 10:40:27 INFO - PROCESS | 2242 | Values: 110.0 106.0 112.0 112.0 111.0 112.0 109.0 112.0 112.0 113.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:139.50 stddev:27.79 (19.9%) stddev-sans-first:29.02 10:40:27 INFO - PROCESS | 2242 | Values: 154.0 115.0 141.0 196.0 114.0 138.0 138.0 116.0 174.0 116.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.50 stddev:2.07 (1.1%) stddev-sans-first:1.99 10:40:27 INFO - PROCESS | 2242 | Values: 186.0 185.0 184.0 182.0 186.0 181.0 181.0 181.0 184.0 185.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#3] audio-fft Cycles:10 Average:81.10 Median:72.50 stddev:26.69 (36.8%) stddev-sans-first:28.27 10:40:27 INFO - PROCESS | 2242 | Values: 77.0 73.0 56.0 125.0 57.0 62.0 72.0 82.0 133.0 74.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:78.00 stddev:17.80 (22.8%) stddev-sans-first:18.71 10:40:27 INFO - PROCESS | 2242 | Values: 91.0 102.0 132.0 81.0 79.0 77.0 102.0 93.0 120.0 102.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#5] imaging-gaussian-blur Cycles:10 Average:84.70 Median:85.00 stddev:1.16 (1.4%) stddev-sans-first:1.13 10:40:27 INFO - PROCESS | 2242 | Values: 86.0 84.0 86.0 84.0 85.0 83.0 85.0 83.0 86.0 85.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 10:40:27 INFO - PROCESS | 2242 | Values: 93.0 93.0 93.0 94.0 91.0 93.0 93.0 93.0 93.0 92.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:86.00 stddev:1.40 (1.6%) stddev-sans-first:1.45 10:40:27 INFO - PROCESS | 2242 | Values: 86.0 86.0 83.0 86.0 83.0 86.0 84.0 85.0 87.0 86.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:57.00 stddev:19.76 (34.7%) stddev-sans-first:20.85 10:40:27 INFO - PROCESS | 2242 | Values: 57.0 57.0 58.0 57.0 56.0 57.0 56.0 119.0 55.0 56.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.00 stddev:4.60 (6.5%) stddev-sans-first:3.71 10:40:27 INFO - PROCESS | 2242 | Values: 62.0 80.0 73.0 73.0 71.0 68.0 69.0 68.0 71.0 70.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.50 stddev:1.20 (1.4%) stddev-sans-first:1.27 10:40:27 INFO - PROCESS | 2242 | Values: 84.0 83.0 85.0 85.0 84.0 82.0 84.0 82.0 85.0 85.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:141.50 stddev:25.11 (17.7%) stddev-sans-first:26.50 10:40:27 INFO - PROCESS | 2242 | Values: 138.0 143.0 141.0 136.0 125.0 142.0 133.0 137.0 215.0 142.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.90 Median:165.50 stddev:23.31 (14.1%) stddev-sans-first:24.09 10:40:27 INFO - PROCESS | 2242 | Values: 163.0 164.0 163.0 163.0 166.0 214.0 216.0 204.0 161.0 165.0 10:40:27 INFO - PROCESS | 2242 | 10:40:27 INFO - PROCESS | 2242 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:66.50 stddev:4.57 (6.9%) stddev-sans-first:4.79 10:40:27 INFO - PROCESS | 2242 | Values: 66.0 68.0 66.0 66.0 67.0 65.0 65.0 66.0 71.0 80.0 10:40:27 INFO - PROCESS | 2242 | -------- Summary: end -------- 10:40:27 INFO - PROCESS | 2242 | 10:40:28 INFO - TEST-INFO | 2242: exit 0 10:40:28 INFO - TEST-OK | kraken | took 60712ms 10:40:28 INFO - SUITE-END | took 1175s 10:40:28 INFO - Completed test suite (00:19:35) 10:40:28 INFO - javascript benchmark 10:40:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3433.7424693148014, 3435.759496835221, 3458.4293591557393, 3463.913402164947, 3500.334936362091, 5651.2370829937945, 5654.575906806996, 5714.771409143635, 5733.369329373771, 5798.24622799036, 5723.0, 5780.421957804228, 5819.67991760453, 5835.299764028312, 5857.6485410875375, 5102.923456148152, 5135.409427915782, 5187.948120518803, 5207.453217412174, 5248.107821670306, 5241.292425522563, 5271.894562108756, 5286.497782710637, 5298.417174110845, 5318.521333080022, 5122.462141475129, 5141.408737995141, 5172.715500647463, 5175.197844334134, 5194.428612852583, 5166.276721259027, 5169.043726910496, 5180.300659411007, 5187.974060129713, 5218.582513398919, 5200.5059519345605, 5225.634205605609, 5239.711815850127, 5249.790008399659, 5251.947480525184, 5580.19087232349, 5671.7447714852515, 5725.370209276976, 5751.166080918285, 5791.797287094973, 6266.467350275232, 6297.779577714758, 6306.716197771066, 6309.558330916838, 6314.968425157845, 6239.968800156015, 6296.779612713533, 6313.337099604521, 6319.052142178664, 6322.336154703736, 5739.741711622945, 5788.392218817048, 5827.096799995923, 5876.794312199053, 5954.255718035246, 6295.559310848288, 6300.180976473029, 6305.0, 6310.085037669604, 6317.273513545909, 5148.9742551287, 6062.878742425127, 6231.470325022333, 6242.063690446469, 6255.43701066897, 6111.7792642805525, 6131.315499379051, 6206.689665516707, 6244.094606282064, 6329.208848893888, 6246.187995560548, 6267.467265282411, 6293.842653933597, 6301.495880329563, 6305.4009869062365, 6293.527985401113, 6314.116023756677, 6315.115883776275, 6333.873322533524, 6341.8731625368155, 4848.8302909398, 4856.0, 4886.364772579542, 4937.8271760488215, 4984.22744474607, 4448.488423831236, 4462.776861156929, 4484.977575112103, 4495.100979803987, 4497.437820272466, 5780.450857168562, 5789.131630255495, 5795.362510123883, 5795.623284486495, 5804.071348584291, 6183.628982261079, 6188.071789231652, 6253.405926437073, 6267.811965641038, 6280.623162610258, 5864.530837532987, 5886.470217680429, 5887.4406931341455, 5904.675242861684, 5927.229460170151, 5420.24116623673, 5432.891342173134, 5442.863928401743, 5443.755031023535, 5451.972740136274, 6221.626702397871, 6254.843628909313, 6283.2460104787715, 6309.211348581427, 6355.650439225887, 5621.971890140523, 5955.225820643289, 6279.623222606659, 6287.119803227643, 6292.874142517216, 6283.685815709287, 6366.586171898812, 6367.044943258548, 6384.297727250092, 6504.0, 5111.565516930954, 6112.786052488142, 6209.285932117864, 6277.686115694106, 6337.556371054166, 6297.842553936188, 6308.747650093945, 6313.0, 6331.9366806331345, 6333.113364128933, 6031.24609423822, 6204.410580994799, 6241.843953901007, 6243.313235544178, 6244.375562443719, 5516.310461192351, 5627.831165065055, 6133.202683651097, 6184.381561843779, 6205.286392064971, 5144.66238777913, 5824.650520968756, 5958.463738263577, 5989.610675306091, 6073.665948372882, 6033.12519684652, 6124.173236613, 6233.407826256498, 6238.407351301619, 6245.375462453719, 6023.427774361428, 6075.422834830684, 6083.543734219863, 6098.79532419082, 6140.078988151813], "value": 5725.292063657205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.7134290768446, 504.65179026471753, 521.8017153481676, 525.2357819372812, 541.4909984614461, 31771.0, 34361.484577731324, 42727.35908961368, 43901.56098439015, 44009.0, 37169.628303716956, 45008.42470513533, 45451.545484545146, 45475.0, 45677.314840277424, 8602.526861022641, 9716.222702183826, 9895.208383329353, 9934.65228716995, 9943.254255930799, 8622.741317760478, 11713.297202167898, 11894.405279735978, 11922.940385298061, 12053.758924821543, 11934.582289619864, 11987.40062996851, 12004.93997530011, 12019.098567607422, 12022.879771202264, 8659.134086591353, 11643.18497705161, 11845.7630847383, 11916.642500724993, 11995.460204290785, 11742.47158877844, 11793.764124717458, 11806.173567850168, 11806.232594881305, 11810.350430726307, 41368.79315603433, 41756.79121604403, 41768.0, 41788.37317440241, 41883.7905810469, 3240.54632351471, 3290.8190049547266, 3336.2660214752714, 3819.4461803038403, 3914.982104652811, 29077.854610726812, 29290.560641590393, 29460.0, 29491.55762663562, 29634.85182574095, 2663.7735792457665, 2711.5797051457057, 2720.170348043846, 2731.2489065506948, 2753.697093319733, 436672.0, 517236.0, 518533.0, 519508.0, 519936.40031799977, 63673.68163159247, 63851.72296554136, 63917.80410979433, 64005.35994639994, 64103.0, 37213.25573488569, 37987.81006095007, 38004.04989875275, 38996.610033899866, 67931.66034169897, 15487.690246195012, 17135.05757183367, 17231.827681723273, 17244.965302081917, 17342.566435839206, 11877.643670689893, 12112.273263604036, 12885.162464439969, 13092.410841512155, 13096.738065238642, 17961.1019449027, 19093.613298337707, 20231.786092834478, 20432.365410767103, 20451.386458406272, 7969.521828690297, 8049.3962952778775, 8081.353491720648, 8116.472429292077, 8136.430449868571, 901.1889299630385, 997.835357166074, 1047.9109275711498, 1067.7170549804307, 1176.325097078981, 29958.40083198324, 29962.700372996427, 29980.850095749818, 30833.537496937563, 30845.383092338026, 1096.9067629251444, 1118.4351902289454, 1187.5487314820314, 1209.5464200924653, 1229.1825935752624, 15434.6141346465, 15468.84531154674, 15570.221488925736, 15817.0, 15879.920600396925, 720484.0, 721654.0, 722918.0, 723596.3482556276, 724035.0, 60557.0, 62144.68927655333, 62320.75358492805, 62368.68815655893, 62381.0, 1284.479785686817, 1302.5245785288303, 1451.8112645356036, 1492.410497853342, 1507.6683129711446, 27785.305367365978, 28436.720347583996, 29410.55884161782, 31205.063848084596, 31361.215969600944, 51407.74296128495, 52108.17621383233, 52811.0, 52834.73582632062, 52918.94162116659, 565335.0, 568565.0, 568935.0, 570274.0, 570792.0, 24506.754932450447, 26084.478310434064, 27227.72772272252, 27328.453430930873, 27363.0, 46759.0, 46840.0, 47288.0, 47777.0, 48179.277310839665, 36639.63360366362, 37888.81055594704, 38255.852324430314, 38872.0, 38910.221795564496, 37347.43978840374, 37512.249755005294, 38107.809460952514, 38329.6167038326, 38579.80710096432, 30079.398412032075, 37176.44235336526, 37193.0, 38775.41836872393, 38874.0, 28078.0, 33098.338033239685, 33156.50265245975, 33402.164945876546, 33501.04172391584, 24769.752302477464, 24827.0, 24966.50066998686, 25237.11670091538, 25417.0], "value": 20424.603366202376, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.9591592882747, 390.2974645637851, 396.65491022810124, 398.75675837738993, 417.228127963268, 13790.0, 15110.546683599483, 17542.386016489425, 21668.34994950154, 21716.022778974944, 16078.75881861773, 16522.504324870242, 18062.4581262562, 19623.5094122647, 20712.585748285026, 5659.8585035374235, 5989.311229208632, 5994.310654274749, 5997.910031349533, 5998.130271110686, 5783.479486846183, 5841.3282472515775, 5843.065109582468, 5857.062869940811, 5909.9704501477645, 5437.157240627689, 5474.069408200596, 5723.656580605156, 5774.191613174159, 5811.767529298823, 5757.136429535562, 5783.71081445926, 5795.739191736382, 5807.332156801937, 5854.18041474194, 4916.975415122937, 5046.167382381903, 5076.086304465195, 5087.364079490064, 5106.259592359124, 25886.22341329763, 26504.60243096355, 26632.33419164517, 27093.45813083747, 27249.0, 1904.4001139640968, 2117.587070521249, 2172.6306527890297, 2423.3578101803037, 2693.824901381404, 12059.166095967441, 15098.24508774568, 15207.391704331814, 15317.387304507807, 15502.30239639219, 1714.6742118997352, 1783.8840475369188, 1793.3633560086112, 1804.8917064976013, 1877.662020836249, 73945.0, 111605.44197278962, 116724.0, 117464.0, 118142.0, 10878.456077196108, 11092.0, 11312.20814542974, 11367.943160284145, 11447.370394628375, 14087.22520261375, 14197.716045679028, 14225.07537010091, 14227.715445691028, 14246.501372451914, 7764.417668674759, 8139.837203255902, 8152.2662960333855, 8264.545450000187, 8532.488050716976, 7390.260973902567, 7941.880871786928, 8122.83754324922, 8669.352822963616, 8837.690680826141, 6632.336766323329, 6824.078749368871, 6848.363380098698, 6854.588724676535, 6854.931450685529, 1686.8228835972045, 1809.9185536650882, 1871.1579789094963, 1880.8213219744102, 1908.9236430542624, 799.984000320002, 889.3063410539789, 927.536231884058, 960.313375936195, 1034.3276870034388, 20433.386998390073, 21500.764778214143, 21586.892065539887, 21591.2443064492, 21697.132114715234, 953.2613665732391, 997.5959736306808, 1018.414411713253, 1019.4189312092185, 1077.7413420778958, 11001.449927503592, 11294.717632059264, 11615.535378584931, 11646.534138634359, 11773.88226117745, 559084.0, 603340.0, 605748.0, 627060.0, 627827.0, 104879.0, 127071.0, 128149.0, 131957.34021330025, 132996.0, 917.156216281023, 1027.5170669785189, 1050.1598721023151, 1052.1214785654065, 1067.5943141606294, 19185.040747962827, 19607.31374401889, 19775.40673779789, 19778.60442791165, 19815.702764458256, 49774.75563110951, 52303.0, 52954.94090118099, 53817.192742109684, 54816.629584260714, 74095.0, 74425.6278718603, 75211.62394187994, 76668.61665691636, 77099.61450192714, 8117.350611951148, 8210.425270231026, 8248.876266856121, 8295.626696798538, 8571.22858942698, 11391.829122563002, 11926.701832454259, 12694.238345699288, 12801.679958001125, 13412.865871341162, 10159.593616255563, 11256.043236325004, 11468.31190128595, 11685.364049032973, 11726.765464690829, 5380.542653874464, 10071.798564028531, 10623.0, 11154.13633388443, 11372.0, 7892.763217103496, 11527.250728702607, 11733.647990560297, 11865.525378985089, 11960.401979900797, 7943.563104029334, 7978.680852765824, 8204.671813127408, 8232.753017409488, 8394.958025210079, 9940.950295248478, 11401.771964560496, 11508.194426390073, 11712.531498739954, 11785.941070294593], "value": 9640.992095506703, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.5902867682712, 1605.6547842213922, 1630.0138416258164, 1861.4694811978588, 1970.9408717738463, 8076.3538916886655, 8565.143485651424, 8568.186022327876, 8592.828143437144, 8801.691940782073, 8778.736637900858, 8784.868226976585, 8986.86519702205, 8994.190522852941, 8995.730128096151, 2452.472718365551, 2478.120267305108, 2479.727230004698, 2481.5110933439883, 2481.838680485772, 2418.3107814272935, 2485.440775825434, 2487.987560062197, 2488.9377765555914, 2491.389609545664, 2448.326710154704, 2456.6806315179, 2482.4290413204885, 2483.5405449991717, 2486.5648511510503, 2421.9757802422014, 2453.7055553333535, 2459.434330104077, 2462.581361168606, 2467.494163696438, 2421.0, 2447.131268399728, 2456.877156142186, 2459.495803360307, 2462.3597864555168, 8870.645174193025, 8928.598213080362, 8935.151160639729, 8946.686865959726, 8948.955255223747, 5328.147496400557, 5408.4050754416985, 5574.331080270353, 5591.9720401397735, 5599.580031497654, 3420.350133474632, 3426.6573342665783, 3528.576570811493, 3549.804760738158, 3556.8399422026073, 992.2062350119875, 996.880374355082, 1006.2704539209088, 1019.306871327497, 1027.3476342522551, 12008.939955300255, 12059.336736479578, 12129.636110916686, 12138.575149869801, 12140.817887731691, 4608.884777880513, 4783.402074740658, 4784.114938736345, 4884.4871288514905, 5133.948660513422, 9417.058294170529, 9483.525823708787, 9551.713448596553, 9558.235341172689, 9559.952200238955, 5483.287172667528, 5485.149801780731, 5489.066858634042, 5490.615656904059, 5491.972540137273, 4561.680682352204, 4591.0387799868895, 4932.679375840559, 5092.694438333712, 5128.743562821844, 3886.7279290449965, 3900.4983081513255, 3956.3076461619103, 4009.8997525062105, 4015.759054456742, 1198.6763573835017, 1210.9455074521668, 1211.4730092409654, 1225.6935766058486, 1226.877312268766, 661.7121552124842, 703.4829400390709, 839.5088873009358, 855.5166331022953, 978.7895602445508, 5768.134779783066, 5924.4667979882015, 6156.507479401637, 6206.06908963659, 6269.905951410732, 860.9655613775504, 939.2110627073291, 999.7800483893532, 1002.8545860850281, 1002.9849552256723, 3221.838908054588, 3966.960330396659, 4076.775777332216, 4106.527749308868, 4112.465379500646, 10530.789384212272, 11270.943645281885, 11272.380019098864, 11292.43537823122, 11297.887021129683, 10287.537060832281, 10551.050405463546, 11118.833217501744, 11290.830637540443, 11323.207375483802, 7168.354848063699, 8428.704995325133, 8429.494230346076, 8432.409731318749, 8436.746897592959, 5864.296284445723, 5901.763929442946, 5935.258092738408, 6071.605345652519, 6150.507959363151, 9717.271204659537, 10004.549795259085, 10301.690949271235, 10304.793904121725, 10325.9483702581, 10366.222533309881, 10512.842307365243, 10515.789684206426, 10525.157987361146, 10531.262811603114, 8256.13310602378, 8344.916550834414, 8386.287165590993, 8475.0, 8499.83000340002, 8383.119772424052, 8399.28606068491, 8497.490150590984, 8499.277561407349, 8507.021692505441, 5806.738696758572, 7244.818879528054, 7380.77857664271, 7618.847623047619, 7883.2905038546805, 7335.523190992569, 7491.513051651626, 7538.472306938681, 7583.05211848519, 7600.011998440167, 7164.247753985756, 7174.641267936687, 7246.818829529304, 7261.636918154177, 7280.199178090512, 4811.975940120277, 4921.4832442593015, 5007.47421520735, 5026.246063090565, 5186.507281808223, 4980.302757614009, 5030.698158110525, 5047.167217409086, 5102.642815002914, 5321.36143662763], "value": 4663.839167752891, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.5172353543169, 311.7568296728552, 323.96436391996883, 325.6287831871665, 327.93605246976836, 5099.337086178796, 5363.973180134104, 5374.462553744624, 5379.98250656004, 5411.563657077754, 4234.470691163605, 4564.406627138467, 5004.924926126102, 5211.3746350437905, 5283.656562323446, 3976.7017473689443, 3976.965988842893, 4002.2995975704134, 4037.6769858411403, 4063.288924438219, 3552.325058238939, 3824.3116239076953, 3882.165334453092, 4058.6955978301744, 4065.7560546367163, 3224.2423030587793, 3549.32562813066, 3792.260509200707, 3857.614238576148, 3960.4950118954757, 3493.3188028334484, 3600.6039335673054, 3826.789526576037, 4055.776932268724, 4056.006278461781, 2961.8074825136296, 3165.271987442878, 3544.2379888324135, 3558.8220588970707, 3558.91102722434, 4866.975665121652, 5013.824516141954, 5015.699058056528, 5020.493851844432, 5101.336826212606, 4437.9570800861775, 4645.605123564501, 4687.617078032017, 4708.646851486153, 4729.456112547033, 4692.36653051837, 4846.5638092571835, 5058.785891386041, 5599.524040456566, 5616.7142221800295, 3489.232368878868, 3506.1760486285702, 3822.6750726188307, 3926.293267211901, 3957.6240257175523, 4514.661400394951, 5183.352080989876, 5921.756431149463, 5938.851528711773, 5950.107483877409, 3479.4258947273925, 3706.4810926470313, 3857.9228415431535, 3886.436466712311, 3905.589913059088, 3389.81356025416, 3456.498807672873, 3723.6090210528046, 3959.2279505496153, 3979.184267225212, 3898.6101389860787, 4090.8977275567754, 4270.615644592001, 4272.401863739079, 4646.652470783502, 5096.534356637522, 5237.947620523746, 5603.747831347518, 5648.531381840751, 5659.43405659439, 1116.815725405299, 1233.5805826019018, 1350.628577141294, 1355.3494322725146, 1366.8906487480979, 755.199488542147, 779.3881802784787, 872.4111224923151, 878.6221924572368, 922.262190247799, 5222.843314700565, 5231.09674130861, 5273.789048438096, 5287.41838397776, 5310.9734451327495, 5693.572982026365, 5703.572232082611, 5774.88450230993, 5796.739146738407, 5813.854653633693, 4102.712810103264, 4707.8587642370785, 4779.832705855279, 5167.276581278624, 5346.4118946915805, 2378.429176997508, 2511.296836885675, 2983.2392739851634, 3027.2734543709353, 3057.770667199969, 5004.499550044967, 5076.796928122908, 5203.375594928632, 5844.977129002407, 6095.049584133035, 5276.709780962084, 5458.372287186945, 5504.6421982571, 5901.645901245939, 5981.940180598225, 5997.940020599913, 6003.849903752529, 6019.434946913834, 6038.5772995889865, 6057.909131363033, 4970.975145124251, 5405.567554595702, 5840.532757379315, 5846.6784326862435, 5879.441453061952, 5660.433956604306, 5684.687342196054, 5728.3985181557, 5748.511376532874, 5789.855253618524, 5823.679697616672, 6038.849028774316, 6054.364291749479, 6084.60450070744, 6094.963856144377, 3895.4156876468755, 3908.3160446921106, 3913.8630147944687, 3917.6082391760597, 3927.253821773968, 5172.922406163835, 5523.696196709059, 5728.885422291614, 5818.359980402237, 5852.707364631837, 4514.503404625554, 5645.943540564706, 5704.629199102045, 5711.514521265739, 5715.5713321500225, 4884.438289596742, 5247.475252474875, 5438.320209973754, 5616.522595579421, 5632.577556683347, 4869.4400143982475, 5267.657602255841, 5421.457854214547, 5522.806701765579, 5527.751251193626, 5548.557375082427, 5578.721063946868, 5598.0, 5639.251831932114, 5666.17840413146, 5226.2230841514065, 5463.322971743737, 5469.453054694658, 5470.288862447856, 5491.560675146058], "value": 4056.8390602325644, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.9526035547332, 644.5101722690754, 651.8533330000749, 753.9886901696475, 787.3111027850631, 14986.775198372034, 15397.615059623515, 15407.845921540782, 15429.691406171884, 15454.922725386372, 13733.313334333294, 14261.215633140173, 14286.214258215794, 14318.0, 14320.928395358009, 891.9598618062171, 895.0, 911.1344222988155, 911.5806728904679, 912.1562554636968, 896.9686060987867, 898.2634239923266, 899.9910000900005, 901.0358915959926, 901.5762591581979, 950.9762255943588, 954.203240294355, 955.1356022799378, 955.5461155950902, 957.1481381570374, 945.2626950978248, 946.4557879219408, 947.3652652722658, 947.9905200947971, 950.0357137505438, 708.5075872268775, 709.7799682098566, 709.9112610923635, 710.0378986473306, 710.0627172132787, 13148.276844773534, 13168.53910113151, 13239.735205295936, 13265.66835829112, 13327.400266987912, 11094.0, 11144.442777861155, 11280.435978201058, 11322.660320190407, 11785.351805650685, 7546.0, 8349.373796965252, 9219.400738952014, 9317.534123293808, 9351.438913665133, 2843.5734639803986, 2985.7909946303766, 3408.062782734743, 3472.895813125585, 3592.5329707138167, 81852.18147818506, 82363.5881820593, 82382.0, 82631.58684206601, 82745.17254827495, 8695.869561956575, 9076.81846363069, 9348.953255233817, 9493.477858717837, 9585.760355991017, 20891.16435342569, 20913.0, 20992.370228893156, 21008.054637541347, 21270.468238293855, 6750.5274630775375, 7185.425165986709, 7353.816154596071, 7359.116905971211, 7630.809229769189, 3308.9338213236083, 4030.314846476106, 4048.2105989332385, 4073.837046518106, 4091.4067460218102, 3000.204945689394, 3490.1798077451776, 3568.5717713873983, 3574.6425357464045, 3583.874564390234, 639.3350915048297, 641.1568787045114, 645.335304636216, 646.1341801985268, 658.3745441830281, 541.3666010767412, 543.739005277469, 559.4098226371217, 566.1903478026379, 585.7803323753592, 7725.343345815556, 7871.291583757489, 7888.802779930433, 7946.6424010919645, 8201.466904651297, 538.9595780316414, 539.2692901118927, 568.0740393159207, 579.1168468086119, 580.1819434597197, 3592.820358982093, 3757.28611563802, 3757.5866654667966, 3788.2234142000825, 3800.790956497364, 81024.37951240942, 83929.02674245936, 83930.0, 92297.53851230702, 95835.0, 79691.0, 82207.58896205481, 82495.3500929978, 83266.0, 86438.56780716057, 74608.0, 75580.0, 75669.0, 76651.0, 76837.0, 6314.810555683337, 8214.3428525719, 8277.089520152658, 8418.915810842058, 8461.830763384822, 39308.213835722556, 41822.58177418187, 45089.0, 46480.767596163154, 46791.59625211249, 64904.0, 65563.0, 66036.67926641344, 66583.00125498218, 67158.0, 10836.295640783324, 10919.945400272947, 11227.38249396291, 11395.886041139482, 11978.397156890134, 11809.468573914022, 11920.880791192323, 11986.041116710468, 12058.51765929353, 12235.0, 9589.904100958902, 11298.983091521803, 11394.715132121763, 11434.885651143382, 11549.595764148215, 11197.385361988581, 11315.660530184108, 11317.207795454238, 11469.910358515928, 11772.587959421378, 11176.497057632589, 11268.60559880433, 11271.830922536004, 11342.262752921033, 11714.76570468603, 9358.344915855825, 9495.620175192915, 9526.52367381642, 9593.42439453635, 9619.615215391306, 7424.665890034853, 9168.862467062865, 9246.537673116452, 9580.28147888925, 9602.807943841224], "value": 6738.004900518314, "unit": "score"}], "name": "dromaeo_css", "value": 7237.641543714588, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 112.0, 112.0, 111.0, 112.0, 109.0, 112.0, 112.0, 113.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 115.0, 141.0, 196.0, 114.0, 138.0, 138.0, 116.0, 174.0, 116.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 182.0, 186.0, 181.0, 181.0, 181.0, 184.0, 185.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 56.0, 125.0, 57.0, 62.0, 72.0, 82.0, 133.0, 74.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 102.0, 132.0, 81.0, 79.0, 77.0, 102.0, 93.0, 120.0, 102.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 85.0, 83.0, 85.0, 83.0, 86.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 91.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 83.0, 86.0, 83.0, 86.0, 84.0, 85.0, 87.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 58.0, 57.0, 56.0, 57.0, 56.0, 119.0, 55.0, 56.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 80.0, 73.0, 73.0, 71.0, 68.0, 69.0, 68.0, 71.0, 70.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 85.0, 84.0, 82.0, 84.0, 82.0, 85.0, 85.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 141.0, 136.0, 125.0, 142.0, 133.0, 137.0, 215.0, 142.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 163.0, 166.0, 214.0, 216.0, 204.0, 161.0, 165.0], "value": 177.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 66.0, 67.0, 65.0, 65.0, 66.0, 71.0, 80.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1484.6000000000001, "alertThreshold": 2.0}]} 10:40:28 INFO - Return code: 0 10:40:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:40:28 ERROR - # TBPL SUCCESS # 10:40:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:40:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:40:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:40:28 INFO - 2016-05-18 10:40:28.842 screenresolution[2246:31149] starting screenresolution argv=screenresolution get 10:40:28 INFO - 2016-05-18 10:40:28.845 screenresolution[2246:31149] Display 0: 1600x1200x32@60 10:40:28 INFO - 2016-05-18 10:40:28.852 screenresolution[2247:31152] starting screenresolution argv=screenresolution list 10:40:28 INFO - Available Modes on Display 0 10:40:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:40:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:40:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:40:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:40:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:40:29 INFO - Intel Iris: 10:40:29 INFO - Chipset Model: Intel Iris 10:40:29 INFO - Type: GPU 10:40:29 INFO - Bus: Built-In 10:40:29 INFO - VRAM (Dynamic, Max): 1536 MB 10:40:29 INFO - Vendor: Intel (0x8086) 10:40:29 INFO - Device ID: 0x0a2e 10:40:29 INFO - Revision ID: 0x0009 10:40:29 INFO - Displays: 10:40:29 INFO - Display: 10:40:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:40:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:40:29 INFO - Display Serial Number: 1600x1200 60 10:40:29 INFO - Main Display: Yes 10:40:29 INFO - Mirror: Off 10:40:29 INFO - Online: Yes 10:40:29 INFO - Rotation: Supported 10:40:29 INFO - Return code: 0 10:40:29 INFO - Running post-action listener: _resource_record_post_action 10:40:29 INFO - ##### 10:40:29 INFO - ##### Finished run-tests step (success) 10:40:29 INFO - ##### 10:40:29 INFO - Running post-run listener: _resource_record_post_run 10:40:29 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 942080; Write bytes: 692197888; Read time: 6; Write time: 1894 10:40:29 INFO - TinderboxPrint: CPU usage
53.6% 10:40:29 INFO - TinderboxPrint: I/O read bytes / time
942,080 / 6 10:40:29 INFO - TinderboxPrint: I/O write bytes / time
692,197,888 / 1,894 10:40:29 INFO - TinderboxPrint: CPU idle
2,246.6 (46.4%) 10:40:29 INFO - TinderboxPrint: CPU system
966.6 (19.9%) 10:40:29 INFO - TinderboxPrint: CPU user
1,632.4 (33.7%) 10:40:29 INFO - TinderboxPrint: Swap in / out
395,882,496 / 0 10:40:29 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 376482304; Write bytes: 370869760; Read time: 28185; Write time: 758 10:40:29 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 942080; Write bytes: 294343680; Read time: 6; Write time: 1069 10:40:29 INFO - Running post-run listener: _upload_blobber_files 10:40:29 INFO - Blob upload gear active. 10:40:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:40:29 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 10:40:29 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'] 10:40:29 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 10:40:29 INFO - (blobuploader) - INFO - Open directory for files ... 10:40:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:40:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:40:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:30 INFO - (blobuploader) - INFO - Done attempting. 10:40:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:40:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:40:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:31 INFO - (blobuploader) - INFO - Done attempting. 10:40:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:40:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:40:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:34 INFO - (blobuploader) - INFO - Done attempting. 10:40:34 INFO - (blobuploader) - INFO - Iteration through files over. 10:40:34 INFO - Return code: 0 10:40:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:40:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:40:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d386c3e6e634d467fbe1590d66fd676e497370828d1a2dbcf8f307e9b1aae4cd744dfadc16e52b7020f98d5123872aaef40cd840aaa642d00e8f99a8308c932a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e05cc7310b6cb11a2a2c7aa88fa70a294ca16abca28a61ba1d2d806e60e605392978a51e04b60d1e879f94d86700b814bd2b6b5d4c99b27aecd5ad79382756cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/234dae43f2812b0a8d0fa68c55c98cbc1f63fd20062dfa86c55f0fb3f1986d58fcdc188a73461602324b8f5d76c5b139375022a58ad47f72bb024aa5c7474f8b"} 10:40:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:40:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:40:34 INFO - Contents: 10:40:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d386c3e6e634d467fbe1590d66fd676e497370828d1a2dbcf8f307e9b1aae4cd744dfadc16e52b7020f98d5123872aaef40cd840aaa642d00e8f99a8308c932a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e05cc7310b6cb11a2a2c7aa88fa70a294ca16abca28a61ba1d2d806e60e605392978a51e04b60d1e879f94d86700b814bd2b6b5d4c99b27aecd5ad79382756cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/234dae43f2812b0a8d0fa68c55c98cbc1f63fd20062dfa86c55f0fb3f1986d58fcdc188a73461602324b8f5d76c5b139375022a58ad47f72bb024aa5c7474f8b"} 10:40:34 INFO - Running post-run listener: copy_logs_to_upload_dir 10:40:34 INFO - Copying logs to upload dir... 10:40:34 INFO - mkdir: /builds/slave/test/build/upload/logs 10:40:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.419804 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-18 10:40:34.105685) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 10:40:34.109469) ========= 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.d8JZs3aQ3o/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.eH7e1xczai/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d386c3e6e634d467fbe1590d66fd676e497370828d1a2dbcf8f307e9b1aae4cd744dfadc16e52b7020f98d5123872aaef40cd840aaa642d00e8f99a8308c932a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e05cc7310b6cb11a2a2c7aa88fa70a294ca16abca28a61ba1d2d806e60e605392978a51e04b60d1e879f94d86700b814bd2b6b5d4c99b27aecd5ad79382756cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/234dae43f2812b0a8d0fa68c55c98cbc1f63fd20062dfa86c55f0fb3f1986d58fcdc188a73461602324b8f5d76c5b139375022a58ad47f72bb024aa5c7474f8b"} build_url:https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011183 build_url: 'https://queue.taskcluster.net/v1/task/PRhPmChrQ8ia89ffkqpTgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d386c3e6e634d467fbe1590d66fd676e497370828d1a2dbcf8f307e9b1aae4cd744dfadc16e52b7020f98d5123872aaef40cd840aaa642d00e8f99a8308c932a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e05cc7310b6cb11a2a2c7aa88fa70a294ca16abca28a61ba1d2d806e60e605392978a51e04b60d1e879f94d86700b814bd2b6b5d4c99b27aecd5ad79382756cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/234dae43f2812b0a8d0fa68c55c98cbc1f63fd20062dfa86c55f0fb3f1986d58fcdc188a73461602324b8f5d76c5b139375022a58ad47f72bb024aa5c7474f8b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 10:40:34.138433) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 10:40:34.138855) ========= 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.d8JZs3aQ3o/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.eH7e1xczai/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.005174 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 10:40:34.188167) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 10:40:34.188501) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 10:40:34.188871) ========= ========= Total master_lag: 0.17 =========