builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0261 starttime: 1463495503.21 results: success (0) buildid: 20160517053732 builduid: d3f584bf73e040a4bd1c655c71273364 revision: c11343f547559a1fc96da1e5d36ca3db82cd84d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:43.208601) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:43.209027) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:43.209315) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:43.228892) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:43.229339) ========= 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.X71LfVeAbc/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.oQIPYiLqSR/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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-17 07:31:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 74.2M=0s 2016-05-17 07:31:43 (74.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118867 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:43.371437) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:43.371792) ========= 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.X71LfVeAbc/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.oQIPYiLqSR/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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.025747 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:43.419975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-17 07:31:43.420369) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c11343f547559a1fc96da1e5d36ca3db82cd84d5 --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 c11343f547559a1fc96da1e5d36ca3db82cd84d5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.X71LfVeAbc/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.oQIPYiLqSR/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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-17 07:31:43,501 truncating revision to first 12 chars 2016-05-17 07:31:43,502 Setting DEBUG logging. 2016-05-17 07:31:43,502 attempt 1/10 2016-05-17 07:31:43,502 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c11343f54755?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 07:31:45,269 unpacking tar archive at: fx-team-c11343f54755/testing/mozharness/ program finished with exit code 0 elapsedTime=2.018413 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-17 07:31:45.456660) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:45.456977) ========= script_repo_revision: c11343f547559a1fc96da1e5d36ca3db82cd84d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:45.457372) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:45.457670) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 07:31:45.471410) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-17 07:31:45.471734) ========= /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.X71LfVeAbc/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.oQIPYiLqSR/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 07:31:45 INFO - MultiFileLogger online at 20160517 07:31:45 in /builds/slave/test 07:31:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:31:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:31:45 INFO - {'append_to_log': False, 07:31:45 INFO - 'base_work_dir': '/builds/slave/test', 07:31:45 INFO - 'blob_upload_branch': 'Fx-Team', 07:31:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:31:45 INFO - 'branch': 'Fx-Team', 07:31:45 INFO - 'buildbot_json_path': 'buildprops.json', 07:31:45 INFO - 'config_files': ('talos/mac_config.py',), 07:31:45 INFO - 'default_actions': ('clobber', 07:31:45 INFO - 'read-buildbot-config', 07:31:45 INFO - 'download-and-extract', 07:31:45 INFO - 'populate-webroot', 07:31:45 INFO - 'create-virtualenv', 07:31:45 INFO - 'install', 07:31:45 INFO - 'run-tests'), 07:31:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:31:45 INFO - 'download_minidump_stackwalk': True, 07:31:45 INFO - 'download_symbols': 'ondemand', 07:31:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:31:45 INFO - 'tooltool.py': '/tools/tooltool.py', 07:31:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:31:45 INFO - '/tools/misc-python/virtualenv.py')}, 07:31:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:31:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:31:45 INFO - 'installer_path': 'installer.exe', 07:31:45 INFO - 'log_level': 'info', 07:31:45 INFO - 'log_name': 'talos', 07:31:45 INFO - 'log_to_console': True, 07:31:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:31:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:31:45 INFO - 'opt_config_files': (), 07:31:45 INFO - 'pip_index': False, 07:31:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:45 INFO - 'cmd': ('bash', 07:31:45 INFO - '-c', 07:31:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:45 INFO - 'enabled': True, 07:31:45 INFO - 'halt_on_failure': False, 07:31:45 INFO - 'name': 'check_screen_resolution'},), 07:31:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:45 INFO - 'cmd': ('bash', 07:31:45 INFO - '-c', 07:31:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:45 INFO - 'enabled': True, 07:31:45 INFO - 'halt_on_failure': False, 07:31:45 INFO - 'name': 'check_screen_resolution'},), 07:31:45 INFO - 'run_cmd_checks_enabled': True, 07:31:45 INFO - 'sps_profile': False, 07:31:45 INFO - 'sps_profile_interval': 0, 07:31:45 INFO - 'suite': 'dromaeojs', 07:31:45 INFO - 'system_bits': '32', 07:31:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:31:45 INFO - 'title': 't-yosemite-r7-0261', 07:31:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:31:45 INFO - 'use_talos_json': True, 07:31:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:31:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:31:45 INFO - 'work_dir': 'build'} 07:31:45 INFO - ##### 07:31:45 INFO - ##### Running clobber step. 07:31:45 INFO - ##### 07:31:45 INFO - Running pre-action listener: _resource_record_pre_action 07:31:45 INFO - Running main action method: clobber 07:31:45 INFO - rmtree: /builds/slave/test/build 07:31:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:31:47 INFO - Running post-action listener: _resource_record_post_action 07:31:47 INFO - ##### 07:31:47 INFO - ##### Finished clobber step (success) 07:31:47 INFO - ##### 07:31:47 INFO - ##### 07:31:47 INFO - ##### Running read-buildbot-config step. 07:31:47 INFO - ##### 07:31:47 INFO - Running pre-action listener: _resource_record_pre_action 07:31:47 INFO - Running main action method: read_buildbot_config 07:31:47 INFO - Using buildbot properties: 07:31:47 INFO - { 07:31:47 INFO - "project": "", 07:31:47 INFO - "product": "firefox", 07:31:47 INFO - "script_repo_revision": "production", 07:31:47 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:31:47 INFO - "repository": "", 07:31:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:31:47 INFO - "buildid": "20160517053732", 07:31:47 INFO - "pgo_build": "False", 07:31:47 INFO - "basedir": "/builds/slave/test", 07:31:47 INFO - "buildnumber": 21, 07:31:47 INFO - "slavename": "t-yosemite-r7-0261", 07:31:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:31:47 INFO - "platform": "yosemite_r7", 07:31:47 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:31:47 INFO - "branch": "fx-team", 07:31:47 INFO - "repo_path": "integration/fx-team", 07:31:47 INFO - "revision": "c11343f547559a1fc96da1e5d36ca3db82cd84d5", 07:31:47 INFO - "stage_platform": "macosx64", 07:31:47 INFO - "builduid": "d3f584bf73e040a4bd1c655c71273364", 07:31:47 INFO - "slavebuilddir": "test" 07:31:47 INFO - } 07:31:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:31:47 INFO - Running post-action listener: _resource_record_post_action 07:31:47 INFO - ##### 07:31:47 INFO - ##### Finished read-buildbot-config step (success) 07:31:47 INFO - ##### 07:31:47 INFO - ##### 07:31:47 INFO - ##### Running download-and-extract step. 07:31:47 INFO - ##### 07:31:47 INFO - Running pre-action listener: _resource_record_pre_action 07:31:47 INFO - Running main action method: download_and_extract 07:31:47 INFO - mkdir: /builds/slave/test/build/tests 07:31: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')]} 07:31:47 INFO - https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:31:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:31:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:31:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:31:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:31:48 INFO - Downloaded 1199 bytes. 07:31:48 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:31:48 INFO - Using the following test package requirements: 07:31:48 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:31:48 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:31:48 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:31:48 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:31:48 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:31:48 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:31:48 INFO - u'jsshell-mac.zip'], 07:31:48 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:31:48 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:31:48 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:31:48 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:31:48 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:31:48 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:31:48 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:31:48 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:31:48 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:31:48 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:31:48 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:31:48 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:48 INFO - https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:31:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:31:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:31:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:31:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:31:50 INFO - Downloaded 18267719 bytes. 07:31:50 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'] 07:31:50 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 07:31:53 INFO - Return code: 0 07:31:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:53 INFO - https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:31:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:31:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:31:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:31:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:31:54 INFO - Downloaded 18267719 bytes. 07:31:54 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'] 07:31:54 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 07:31:57 INFO - Return code: 0 07:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:57 INFO - https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:31:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:31:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:31:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:31:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:31:59 INFO - Downloaded 10840194 bytes. 07:31:59 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'] 07:31:59 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 07:31:59 INFO - Return code: 0 07:31: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')]} 07:31:59 INFO - https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:31:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:31:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:31:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:31:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:32:02 INFO - Downloaded 109961212 bytes. 07:32:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:32:02 INFO - mkdir: /builds/slave/test/properties 07:32:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:32:02 INFO - Writing to file /builds/slave/test/properties/build_url 07:32:02 INFO - Contents: 07:32:02 INFO - build_url:https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:32:03 INFO - Running post-action listener: _resource_record_post_action 07:32:03 INFO - Running post-action listener: set_extra_try_arguments 07:32:03 INFO - ##### 07:32:03 INFO - ##### Finished download-and-extract step (success) 07:32:03 INFO - ##### 07:32:03 INFO - ##### 07:32:03 INFO - ##### Running populate-webroot step. 07:32:03 INFO - ##### 07:32:03 INFO - Running pre-action listener: _resource_record_pre_action 07:32:03 INFO - Running main action method: populate_webroot 07:32:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:32:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:32:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:32:04 INFO - '--tppagecycles', 07:32:04 INFO - '7'], 07:32:04 INFO - 'tests': ['tsvgm']}}, 07:32:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:32:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:32:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:32:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:32:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:32:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:32:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:04 INFO - 'talos_options': ['--disable-e10s'], 07:32:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:04 INFO - 'talos_options': ['--disable-e10s'], 07:32:04 INFO - 'tests': ['damp', 'tps']}, 07:32:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:04 INFO - 'tests': ['damp', 'tps']}, 07:32:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:32:04 INFO - 'tests': ['dromaeo_dom']}, 07:32:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:32:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:32:04 INFO - 'tests': ['a11yr', 07:32:04 INFO - 'ts_paint', 07:32:04 INFO - 'tpaint', 07:32:04 INFO - 'sessionrestore', 07:32:04 INFO - 'sessionrestore_no_auto_restore', 07:32:04 INFO - 'tabpaint']}, 07:32:04 INFO - 'other-e10s': {'tests': ['a11yr', 07:32:04 INFO - 'ts_paint', 07:32:04 INFO - 'tpaint', 07:32:04 INFO - 'sessionrestore', 07:32:04 INFO - 'sessionrestore_no_auto_restore', 07:32:04 INFO - 'tabpaint']}, 07:32:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:32:04 INFO - 'ts_paint', 07:32:04 INFO - 'tpaint', 07:32:04 INFO - 'sessionrestore', 07:32:04 INFO - 'sessionrestore_no_auto_restore', 07:32:04 INFO - 'tabpaint']}, 07:32:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:32:04 INFO - 'ts_paint', 07:32:04 INFO - 'tpaint', 07:32:04 INFO - 'sessionrestore', 07:32:04 INFO - 'sessionrestore_no_auto_restore', 07:32:04 INFO - 'tabpaint']}, 07:32:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:32:04 INFO - 'tests': ['a11yr', 07:32:04 INFO - 'ts_paint', 07:32:04 INFO - 'tpaint', 07:32:04 INFO - 'sessionrestore', 07:32:04 INFO - 'sessionrestore_no_auto_restore', 07:32:04 INFO - 'tabpaint']}, 07:32:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:32:04 INFO - 'tests': ['a11yr', 07:32:04 INFO - 'ts_paint', 07:32:04 INFO - 'tpaint', 07:32:04 INFO - 'sessionrestore', 07:32:04 INFO - 'sessionrestore_no_auto_restore', 07:32:04 INFO - 'tabpaint']}, 07:32:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:32:04 INFO - 'tests': ['tsvgx', 07:32:04 INFO - 'tsvgr_opacity', 07:32:04 INFO - 'tart', 07:32:04 INFO - 'tscrollx', 07:32:04 INFO - 'cart']}, 07:32:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:32:04 INFO - 'tsvgr_opacity', 07:32:04 INFO - 'tart', 07:32:04 INFO - 'tscrollx', 07:32:04 INFO - 'cart']}, 07:32:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:04 INFO - 'talos_options': ['--disable-e10s'], 07:32:04 INFO - 'tests': ['tp5o']}, 07:32:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:04 INFO - 'tests': ['tp5o']}, 07:32:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:04 INFO - 'talos_options': ['--disable-e10s', 07:32:04 INFO - '--xperf_path', 07:32:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:04 INFO - 'tests': ['tp5n']}, 07:32:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:04 INFO - 'talos_options': ['--xperf_path', 07:32:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:04 INFO - 'tests': ['tp5n']}}, 07:32:04 INFO - 'talos.zip': {'path': '', 07:32:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:32:04 INFO - Running post-action listener: _resource_record_post_action 07:32:04 INFO - ##### 07:32:04 INFO - ##### Finished populate-webroot step (success) 07:32:04 INFO - ##### 07:32:04 INFO - ##### 07:32:04 INFO - ##### Running create-virtualenv step. 07:32:04 INFO - ##### 07:32:04 INFO - Running pre-action listener: _resource_record_pre_action 07:32:04 INFO - Running main action method: create_virtualenv 07:32:04 INFO - Creating virtualenv /builds/slave/test/build/venv 07:32:04 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 07:32:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:32:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:32:04 INFO - Using real prefix '/tools/python27' 07:32:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:32:04 INFO - Installing distribute.............................................................................................................................................................................................done. 07:32:07 INFO - Installing pip.................done. 07:32:07 INFO - Return code: 0 07:32:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:32:07 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')]} 07:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:07 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')]} 07:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:07 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 0x10f800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f806570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9332d7dbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a4d30>, '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 0x10f4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f69bb20>, '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.X71LfVeAbc/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.oQIPYiLqSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 07:32:07 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 07:32:07 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 07:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X71LfVeAbc/Render', 07:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:07 INFO - 'HOME': '/Users/cltbld', 07:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:07 INFO - 'LOGNAME': 'cltbld', 07:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:07 INFO - 'MOZ_NO_REMOTE': '1', 07:32:07 INFO - 'NO_EM_RESTART': '1', 07:32:07 INFO - 'PAGER': '/bin/cat', 07:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:07 INFO - 'PWD': '/builds/slave/test', 07:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:07 INFO - 'SHELL': '/bin/bash', 07:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQIPYiLqSR/Listeners', 07:32:07 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 07:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:07 INFO - 'USER': 'cltbld', 07:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:07 INFO - 'XPC_FLAGS': '0x0', 07:32:07 INFO - 'XPC_SERVICE_NAME': '0', 07:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:07 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 07:32:07 INFO - Cleaning up... 07:32:07 INFO - Return code: 0 07:32:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:32:07 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')]} 07:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:07 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')]} 07:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:07 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 0x10f800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f806570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9332d7dbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a4d30>, '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 0x10f4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f69bb20>, '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.X71LfVeAbc/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.oQIPYiLqSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 07:32:07 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 07:32:07 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 07:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X71LfVeAbc/Render', 07:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:07 INFO - 'HOME': '/Users/cltbld', 07:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:07 INFO - 'LOGNAME': 'cltbld', 07:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:07 INFO - 'MOZ_NO_REMOTE': '1', 07:32:07 INFO - 'NO_EM_RESTART': '1', 07:32:07 INFO - 'PAGER': '/bin/cat', 07:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:07 INFO - 'PWD': '/builds/slave/test', 07:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:07 INFO - 'SHELL': '/bin/bash', 07:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQIPYiLqSR/Listeners', 07:32:07 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 07:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:07 INFO - 'USER': 'cltbld', 07:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:07 INFO - 'XPC_FLAGS': '0x0', 07:32:07 INFO - 'XPC_SERVICE_NAME': '0', 07:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:08 INFO - Downloading/unpacking psutil>=3.1.1 07:32:08 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 07:32:08 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 07:32:08 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 07:32:08 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 07:32:08 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 07:32:08 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 07:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:32:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:10 INFO - Installing collected packages: psutil 07:32:10 INFO - Running setup.py install for psutil 07:32:10 INFO - building 'psutil._psutil_osx' extension 07:32:10 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 07:32:11 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 07:32:11 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 07:32:11 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 07:32:11 INFO - building 'psutil._psutil_posix' extension 07:32:11 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 07:32:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:32:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:32:11 INFO - ^ 07:32:11 INFO - 1 warning generated. 07:32:11 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 07:32:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:11 INFO - Successfully installed psutil 07:32:11 INFO - Cleaning up... 07:32:12 INFO - Return code: 0 07:32:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f806570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9332d7dbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a4d30>, '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 0x10f4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f69bb20>, '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.X71LfVeAbc/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.oQIPYiLqSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 07:32: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:32: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 mozsystemmonitor==0.3 07:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X71LfVeAbc/Render', 07:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:12 INFO - 'HOME': '/Users/cltbld', 07:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:12 INFO - 'LOGNAME': 'cltbld', 07:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:12 INFO - 'MOZ_NO_REMOTE': '1', 07:32:12 INFO - 'NO_EM_RESTART': '1', 07:32:12 INFO - 'PAGER': '/bin/cat', 07:32:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:12 INFO - 'PWD': '/builds/slave/test', 07:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:12 INFO - 'SHELL': '/bin/bash', 07:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQIPYiLqSR/Listeners', 07:32:12 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 07:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:12 INFO - 'USER': 'cltbld', 07:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:12 INFO - 'XPC_FLAGS': '0x0', 07:32:12 INFO - 'XPC_SERVICE_NAME': '0', 07:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:32:14 INFO - Installing collected packages: mozsystemmonitor 07:32:14 INFO - Running setup.py install for mozsystemmonitor 07:32:14 INFO - Successfully installed mozsystemmonitor 07:32:14 INFO - Cleaning up... 07:32:14 INFO - Return code: 0 07:32:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:14 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 0x10f800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f806570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9332d7dbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a4d30>, '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 0x10f4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f69bb20>, '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.X71LfVeAbc/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.oQIPYiLqSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 07:32:14 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 07:32:14 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 07:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X71LfVeAbc/Render', 07:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:14 INFO - 'HOME': '/Users/cltbld', 07:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:14 INFO - 'LOGNAME': 'cltbld', 07:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:14 INFO - 'MOZ_NO_REMOTE': '1', 07:32:14 INFO - 'NO_EM_RESTART': '1', 07:32:14 INFO - 'PAGER': '/bin/cat', 07:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:14 INFO - 'PWD': '/builds/slave/test', 07:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:14 INFO - 'SHELL': '/bin/bash', 07:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQIPYiLqSR/Listeners', 07:32:14 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 07:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:14 INFO - 'USER': 'cltbld', 07:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:14 INFO - 'XPC_FLAGS': '0x0', 07:32:14 INFO - 'XPC_SERVICE_NAME': '0', 07:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:15 INFO - Downloading/unpacking blobuploader==1.2.4 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32:17 INFO - Downloading blobuploader-1.2.4.tar.gz 07:32:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:32:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:32:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:18 INFO - Downloading docopt-0.6.1.tar.gz 07:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:32:18 INFO - Installing collected packages: blobuploader, requests, docopt 07:32:18 INFO - Running setup.py install for blobuploader 07:32:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:32:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:32:19 INFO - Running setup.py install for requests 07:32:19 INFO - Running setup.py install for docopt 07:32:19 INFO - Successfully installed blobuploader requests docopt 07:32:19 INFO - Cleaning up... 07:32:19 INFO - Return code: 0 07:32:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:19 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 0x10f800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f806570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9332d7dbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a4d30>, '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 0x10f4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f69bb20>, '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.X71LfVeAbc/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.oQIPYiLqSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 07:32:19 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 07:32:19 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 07:32:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X71LfVeAbc/Render', 07:32:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:19 INFO - 'HOME': '/Users/cltbld', 07:32:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:19 INFO - 'LOGNAME': 'cltbld', 07:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:19 INFO - 'MOZ_NO_REMOTE': '1', 07:32:19 INFO - 'NO_EM_RESTART': '1', 07:32:19 INFO - 'PAGER': '/bin/cat', 07:32:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:19 INFO - 'PWD': '/builds/slave/test', 07:32:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:19 INFO - 'SHELL': '/bin/bash', 07:32:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQIPYiLqSR/Listeners', 07:32:19 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 07:32:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:19 INFO - 'USER': 'cltbld', 07:32:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:19 INFO - 'XPC_FLAGS': '0x0', 07:32:19 INFO - 'XPC_SERVICE_NAME': '0', 07:32:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-6rRx3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Pe18PL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Q4CUGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-0MwMIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-x1TrYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-r7nX6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-N8kHvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-wMXvAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-dK1nT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-6LbRDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-jP6TUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-p78af9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-b2H5yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-YLncpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-7XDy7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-DO9_Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-fcJTW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:32:21 INFO - Running setup.py install for manifestparser 07:32:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:32:21 INFO - Running setup.py install for mozcrash 07:32:22 INFO - Running setup.py install for mozdebug 07:32:22 INFO - Running setup.py install for mozdevice 07:32:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:32:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:32:22 INFO - Running setup.py install for mozfile 07:32:22 INFO - Running setup.py install for mozhttpd 07:32:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:32:22 INFO - Running setup.py install for mozinfo 07:32:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:32:22 INFO - Running setup.py install for mozInstall 07:32:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:32:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:32:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:32:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:32:22 INFO - Running setup.py install for mozleak 07:32:23 INFO - Running setup.py install for mozlog 07:32:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Running setup.py install for moznetwork 07:32:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Running setup.py install for mozprocess 07:32:23 INFO - Running setup.py install for mozprofile 07:32:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Running setup.py install for mozrunner 07:32:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Running setup.py install for mozscreenshot 07:32:24 INFO - Running setup.py install for moztest 07:32:24 INFO - Running setup.py install for mozversion 07:32:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:32:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:32:24 INFO - Cleaning up... 07:32:24 INFO - Return code: 0 07:32:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:24 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 0x10f800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f806570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9332d7dbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a4d30>, '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 0x10f4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f69bb20>, '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.X71LfVeAbc/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.oQIPYiLqSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 07:32:24 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 07:32:24 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 07:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X71LfVeAbc/Render', 07:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:24 INFO - 'HOME': '/Users/cltbld', 07:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:24 INFO - 'LOGNAME': 'cltbld', 07:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:24 INFO - 'MOZ_NO_REMOTE': '1', 07:32:24 INFO - 'NO_EM_RESTART': '1', 07:32:24 INFO - 'PAGER': '/bin/cat', 07:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:24 INFO - 'PWD': '/builds/slave/test', 07:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:24 INFO - 'SHELL': '/bin/bash', 07:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQIPYiLqSR/Listeners', 07:32:24 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 07:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:24 INFO - 'USER': 'cltbld', 07:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:24 INFO - 'XPC_FLAGS': '0x0', 07:32:24 INFO - 'XPC_SERVICE_NAME': '0', 07:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-pI0oWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:24 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)) 07:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Nia_kh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:24 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)) 07:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-rELDLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:24 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)) 07:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Qzzhxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:25 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)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-PDVIIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:25 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)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-zgIAWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:25 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)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-YGIi7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:25 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)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-TKJtu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:25 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)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-NKykQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:25 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)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-VxUbov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:25 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)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-IlJARC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:25 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)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-ghrhRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:25 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)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Y_MlZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:26 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Z42R4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:26 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-uveuYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:26 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-P0k17Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:26 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-3J4G3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:26 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)) 07:32:26 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)) 07:32:26 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)) 07:32:26 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)) 07:32:26 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)) 07:32:26 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)) 07:32:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:32:26 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 07:32:26 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 07:32:26 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 07:32:26 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 07:32:26 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 07:32:26 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 07:32:28 INFO - Downloading blessings-1.6.tar.gz 07:32:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:32:28 INFO - Installing collected packages: blessings 07:32:28 INFO - Running setup.py install for blessings 07:32:29 INFO - Successfully installed blessings 07:32:29 INFO - Cleaning up... 07:32:29 INFO - Return code: 0 07:32:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:32:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:29 INFO - Reading from file tmpfile_stdout 07:32:29 INFO - Current package versions: 07:32:29 INFO - blessings == 1.6 07:32:29 INFO - blobuploader == 1.2.4 07:32:29 INFO - docopt == 0.6.1 07:32:29 INFO - manifestparser == 1.1 07:32:29 INFO - mozInstall == 1.12 07:32:29 INFO - mozcrash == 0.17 07:32:29 INFO - mozdebug == 0.1 07:32:29 INFO - mozdevice == 0.48 07:32:29 INFO - mozfile == 1.2 07:32:29 INFO - mozhttpd == 0.7 07:32:29 INFO - mozinfo == 0.9 07:32:29 INFO - mozleak == 0.1 07:32:29 INFO - mozlog == 3.2 07:32:29 INFO - moznetwork == 0.27 07:32:29 INFO - mozprocess == 0.22 07:32:29 INFO - mozprofile == 0.28 07:32:29 INFO - mozrunner == 6.11 07:32:29 INFO - mozscreenshot == 0.1 07:32:29 INFO - mozsystemmonitor == 0.3 07:32:29 INFO - moztest == 0.7 07:32:29 INFO - mozversion == 1.4 07:32:29 INFO - psutil == 3.1.1 07:32:29 INFO - requests == 1.2.3 07:32:29 INFO - wsgiref == 0.1.2 07:32:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:29 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 0x10f800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f806570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9332d7dbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a4d30>, '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 0x10f4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f69bb20>, '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.X71LfVeAbc/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.oQIPYiLqSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 07:32:29 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 07:32:29 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 07:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X71LfVeAbc/Render', 07:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:29 INFO - 'HOME': '/Users/cltbld', 07:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:29 INFO - 'LOGNAME': 'cltbld', 07:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:29 INFO - 'MOZ_NO_REMOTE': '1', 07:32:29 INFO - 'NO_EM_RESTART': '1', 07:32:29 INFO - 'PAGER': '/bin/cat', 07:32:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:29 INFO - 'PWD': '/builds/slave/test', 07:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:29 INFO - 'SHELL': '/bin/bash', 07:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQIPYiLqSR/Listeners', 07:32:29 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 07:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:29 INFO - 'USER': 'cltbld', 07:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:29 INFO - 'XPC_FLAGS': '0x0', 07:32:29 INFO - 'XPC_SERVICE_NAME': '0', 07:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:29 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)) 07:32:29 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)) 07:32:29 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)) 07:32:29 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)) 07:32:29 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)) 07:32:29 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)) 07:32:29 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)) 07:32:29 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)) 07:32:29 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)) 07:32:29 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)) 07:32:29 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)) 07:32:29 INFO - Cleaning up... 07:32:29 INFO - Return code: 0 07:32:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:29 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 0x10f800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f806570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9332d7dbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a4d30>, '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 0x10f4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f69bb20>, '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.X71LfVeAbc/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.oQIPYiLqSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 07:32:29 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 07:32:29 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 07:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X71LfVeAbc/Render', 07:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:29 INFO - 'HOME': '/Users/cltbld', 07:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:29 INFO - 'LOGNAME': 'cltbld', 07:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:29 INFO - 'MOZ_NO_REMOTE': '1', 07:32:29 INFO - 'NO_EM_RESTART': '1', 07:32:29 INFO - 'PAGER': '/bin/cat', 07:32:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:29 INFO - 'PWD': '/builds/slave/test', 07:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:29 INFO - 'SHELL': '/bin/bash', 07:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQIPYiLqSR/Listeners', 07:32:29 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 07:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:29 INFO - 'USER': 'cltbld', 07:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:29 INFO - 'XPC_FLAGS': '0x0', 07:32:29 INFO - 'XPC_SERVICE_NAME': '0', 07:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:29 INFO - Downloading/unpacking jsonschema 07:32:29 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 07:32:29 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 07:32:29 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 07:32:29 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 07:32:29 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 07:32:29 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 07:32:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:32:32 INFO - Downloading/unpacking functools32 (from jsonschema) 07:32:32 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 07:32:32 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 07:32:32 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 07:32:32 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 07:32:32 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 07:32:32 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 07:32:32 INFO - Downloading functools32-3.2.3-2.tar.gz 07:32:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:32:32 INFO - warning: no files found matching '*.txt' 07:32:32 INFO - no previously-included directories found matching 'build' 07:32:32 INFO - no previously-included directories found matching 'dist' 07:32:32 INFO - no previously-included directories found matching '.git*' 07:32:32 INFO - Installing collected packages: jsonschema, functools32 07:32:32 INFO - Running setup.py install for functools32 07:32:33 INFO - warning: no files found matching '*.txt' 07:32:33 INFO - no previously-included directories found matching 'build' 07:32:33 INFO - no previously-included directories found matching 'dist' 07:32:33 INFO - no previously-included directories found matching '.git*' 07:32:33 INFO - Successfully installed jsonschema functools32 07:32:33 INFO - Cleaning up... 07:32:33 INFO - Return code: 0 07:32:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:33 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 0x10f800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f806570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9332d7dbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6a4d30>, '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 0x10f4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f69bb20>, '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.X71LfVeAbc/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.oQIPYiLqSR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 07:32:33 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 07:32:33 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 07:32:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X71LfVeAbc/Render', 07:32:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:33 INFO - 'HOME': '/Users/cltbld', 07:32:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:33 INFO - 'LOGNAME': 'cltbld', 07:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:33 INFO - 'MOZ_NO_REMOTE': '1', 07:32:33 INFO - 'NO_EM_RESTART': '1', 07:32:33 INFO - 'PAGER': '/bin/cat', 07:32:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:33 INFO - 'PWD': '/builds/slave/test', 07:32:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:33 INFO - 'SHELL': '/bin/bash', 07:32:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQIPYiLqSR/Listeners', 07:32:33 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 07:32:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:33 INFO - 'USER': 'cltbld', 07:32:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:33 INFO - 'XPC_FLAGS': '0x0', 07:32:33 INFO - 'XPC_SERVICE_NAME': '0', 07:32:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:33 INFO - Downloading/unpacking flake8 07:32:33 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 07:32:33 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 07:32:33 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 07:32:33 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 07:32:33 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 07:32:33 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 07:32:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:32:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:32:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:32:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:32:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:32:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:32:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:32:37 INFO - no previously-included directories found matching 'docs/_build' 07:32:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:32:37 INFO - Running setup.py install for pep8 07:32:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:32:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:32:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:32:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:32:37 INFO - no previously-included directories found matching 'docs/_build' 07:32:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:32:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:32:37 INFO - Cleaning up... 07:32:37 INFO - Return code: 0 07:32:37 INFO - Running post-action listener: _resource_record_post_action 07:32:37 INFO - Running post-action listener: _start_resource_monitoring 07:32:37 INFO - Starting resource monitoring. 07:32:37 INFO - ##### 07:32:37 INFO - ##### Finished create-virtualenv step (success) 07:32:37 INFO - ##### 07:32:37 INFO - ##### 07:32:37 INFO - ##### Running install step. 07:32:37 INFO - ##### 07:32:37 INFO - Running pre-action listener: _resource_record_pre_action 07:32:37 INFO - Running main action method: install 07:32:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:37 INFO - Reading from file tmpfile_stdout 07:32:37 INFO - Detecting whether we're running mozinstall >=1.0... 07:32:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:32:37 INFO - Reading from file tmpfile_stdout 07:32:37 INFO - Output received: 07:32:37 INFO - Usage: mozinstall [options] installer 07:32:37 INFO - Options: 07:32:37 INFO - -h, --help show this help message and exit 07:32:37 INFO - -d DEST, --destination=DEST 07:32:37 INFO - Directory to install application into. [default: 07:32:37 INFO - "/builds/slave/test"] 07:32:37 INFO - --app=APP Application being installed. [default: firefox] 07:32:37 INFO - mkdir: /builds/slave/test/build/application 07:32:37 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'] 07:32:37 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 07:33:11 INFO - Reading from file tmpfile_stdout 07:33:11 INFO - Output received: 07:33:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:33:11 INFO - Running post-action listener: _resource_record_post_action 07:33:11 INFO - ##### 07:33:11 INFO - ##### Finished install step (success) 07:33:11 INFO - ##### 07:33:11 INFO - ##### 07:33:11 INFO - ##### Running run-tests step. 07:33:11 INFO - ##### 07:33:11 INFO - Running pre-action listener: _resource_record_pre_action 07:33:11 INFO - Running main action method: run_tests 07:33:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:33:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:33:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:33:11 INFO - 2016-05-17 07:33:11.812 screenresolution[1640:7374] starting screenresolution argv=screenresolution get 07:33:11 INFO - 2016-05-17 07:33:11.815 screenresolution[1640:7374] Display 0: 1600x1200x32@60 07:33:11 INFO - 2016-05-17 07:33:11.821 screenresolution[1641:7376] starting screenresolution argv=screenresolution list 07:33:11 INFO - Available Modes on Display 0 07:33:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:33:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:33:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:33:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:33:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:33:12 INFO - Intel Iris: 07:33:12 INFO - Chipset Model: Intel Iris 07:33:12 INFO - Type: GPU 07:33:12 INFO - Bus: Built-In 07:33:12 INFO - VRAM (Dynamic, Max): 1536 MB 07:33:12 INFO - Vendor: Intel (0x8086) 07:33:12 INFO - Device ID: 0x0a2e 07:33:12 INFO - Revision ID: 0x0009 07:33:12 INFO - Displays: 07:33:12 INFO - Display: 07:33:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:33:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:33:12 INFO - Display Serial Number: 1600x1200 60 07:33:12 INFO - Main Display: Yes 07:33:12 INFO - Mirror: Off 07:33:12 INFO - Online: Yes 07:33:12 INFO - Rotation: Supported 07:33:12 INFO - Return code: 0 07:33:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:33:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:33:12 INFO - Python 2.7.3 07:33:12 INFO - Return code: 0 07:33:12 INFO - grabbing minidump binary from tooltool 07:33: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')]} 07:33:12 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 0x10f6a4d30>, '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 0x10f4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f69bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:33:12 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 07:33:12 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 07:33:12 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 07:33:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:33:12 INFO - Return code: 0 07:33:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:33:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:33:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:33:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:33:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:33:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:33:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:33:13 INFO - Return code: 0 07:33:13 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/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0261', '--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 07:33:13 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/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0261 --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 07:33:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X71LfVeAbc/Render', 07:33:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:13 INFO - 'HOME': '/Users/cltbld', 07:33:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:13 INFO - 'LOGNAME': 'cltbld', 07:33:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:33:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:33:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:13 INFO - 'MOZ_NO_REMOTE': '1', 07:33:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:33:13 INFO - 'NO_EM_RESTART': '1', 07:33:13 INFO - 'PAGER': '/bin/cat', 07:33:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:13 INFO - 'PWD': '/builds/slave/test', 07:33:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:33:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:13 INFO - 'SHELL': '/bin/bash', 07:33:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQIPYiLqSR/Listeners', 07:33:13 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 07:33:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:13 INFO - 'USER': 'cltbld', 07:33:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:13 INFO - 'XPC_FLAGS': '0x0', 07:33:13 INFO - 'XPC_SERVICE_NAME': '0', 07:33:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:13 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/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0261', '--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 07:33:13 INFO - mozversion application_buildid: 20160517053732 07:33:13 INFO - mozversion application_changeset: c11343f547559a1fc96da1e5d36ca3db82cd84d5 07:33:13 INFO - mozversion application_display_name: Nightly 07:33:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:33:13 INFO - mozversion application_name: Firefox 07:33:13 INFO - mozversion application_remotingname: firefox 07:33:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:33:13 INFO - mozversion application_vendor: Mozilla 07:33:13 INFO - mozversion application_version: 49.0a1 07:33:13 INFO - mozversion platform_buildid: 20160517053732 07:33:13 INFO - mozversion platform_changeset: c11343f547559a1fc96da1e5d36ca3db82cd84d5 07:33:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:33:13 INFO - mozversion platform_version: 49.0a1 07:33:13 INFO - using testdate: 1463495593 07:33:13 INFO - actual date: 1463495593 07:33:13 INFO - starting webserver on 'localhost:49273' 07:33:13 INFO - SUITE-START | Running 2 tests 07:33:13 INFO - TEST-START | dromaeo_css 07:33:13 INFO - operating with platform_type : mac_ 07:33:13 INFO - Initialising browser for dromaeo_css test... 07:33:13 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmptqJ9iQ/profile http://localhost:49273/getInfo.html) 07:33:15 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 07:33:15 INFO - PROCESS | 1659 | colorDepth:24 07:33:15 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 07:33:15 INFO - PROCESS | 1659 | __metrics 07:33:15 INFO - PROCESS | 1659 | 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" 07:33:15 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:33:16 INFO - TEST-INFO | 1659: exit 0 07:33:16 INFO - Browser initialized. 07:33:16 INFO - Running cycle 1/1 for dromaeo_css test... 07:33:16 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmptqJ9iQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:28 INFO - PROCESS | 1664 | RSS: Main: 239312896 07:33:28 INFO - PROCESS | 1664 | 07:34:19 INFO - PROCESS | 1664 | 1463495659690 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] 07:36:30 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 07:36:32 INFO - PROCESS | 1664 | RSS: Main: 374050816 07:36:32 INFO - PROCESS | 1664 | 07:39:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 07:39:36 INFO - PROCESS | 1664 | RSS: Main: 376631296 07:39:36 INFO - PROCESS | 1664 | 07:42:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 07:42:38 INFO - PROCESS | 1664 | RSS: Main: 371937280 07:42:38 INFO - PROCESS | 1664 | 07:45:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 07:45:41 INFO - PROCESS | 1664 | RSS: Main: 368766976 07:45:41 INFO - PROCESS | 1664 | 07:48:45 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 07:48:45 INFO - PROCESS | 1664 | RSS: Main: 338554880 07:48:45 INFO - PROCESS | 1664 | 07:51:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 07:51:48 INFO - PROCESS | 1664 | RSS: Main: 385097728 07:51:48 INFO - PROCESS | 1664 | 07:51:48 INFO - PROCESS | 1664 | __start_tp_report 07:51:48 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 07:51:48 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 07:51:48 INFO - PROCESS | 1664 | |i|pagename|runs| 07:51:49 INFO - PROCESS | 1664 | |0;dojo.html;3465.272292818508;3481.529993450885;3506.421440462323;3506.631803660617;3550.1124718820297;5637.46444087811;5678.716064196784;5705.172749951254;5773.162891380747;5843.2403787507565;5446.537044351225;5745.166950792132;5869.295684517864;5873.559483038768;5936.406359364061;5075.365579302587;5083.339165908426;5186.170212765958;5194.948050519503;5292.391374991887;5308.601854860882;5315.521603055724;5343.438938911417;5350.036993341217;5379.704116273623;5138.2549530318265;5179.8446046618665;5180.922286165711;5250.816221432251;5277.1556550951855;5228.47715228478;5231.633785635007;5235.057689615867;5238.109521381374;5239.79040838366;5106.4382917879;5144.897102057976;5188.118019936582;5202.193659982709;5210.921836172461;5601.80393686219;5630.380658127603;5655.095184770417;5676.971615141939;5722.37053924068;6212.782552610637;6265.467435267962;6291.7797877074545;6303.401176888188;6308.3060863305;6156.3843615638525;6205.937940620581;6251.12484252205;6256.092866534373;6271;5938.198343223696;5978.701064946736;6013.759449621966;6015.09773533969;6031.728572214173;6362.713677884506;6370.362963703686;6377.107204991305;6396.648184349905;6396.808095757135;4967.627427942919;6122.632642041492;6124.846878828065;6162.414570615874;6165.013597824327;6215.658138802319;6238.438540531374;6263.749450022039;6296.779612713533;6304.401081897213;6219.129321894939;6225.657588832658;6269.843253918689;6285.779997700059;6322.114903913456;6133.242854861876;6272.3100458949475;6293.622382657055;6298.622082675054;6310.211223597051;4917;4955.108080545464;5007.24891266313;5036.967421678547;5038.34501514808;4480.484744254452;4503.549645035536;4509.413776209072;4516.6160876325885;4550.385697930803;5553.77784888608;5768.7692492300675;5770.855728606734;5786.594938354275;5794.710264486843;6194.628322300677;6208.689565521706;6212.658303793335;6274.466670333073;6310.652914089769;5751.396103409082;5763.6541807491685;5774.68239246837;5777.566682498746;5804.651720896675;5328.946710532922;5430.864228394243;5446.700431476307;5451.127819548932;5456.236126942231;6239.313675495692;6356.332585078592;6362.077498762561;6373.904391434225;6454.741810327722;6264.31092579825;6265.874682506415;6267.96866015667;6280.0893870389455;6287.056941458818;5990.910136347871;6204.100405441276;6337.2395312562785;6442.710078046593;6448.387403196689;6142.867137759686;6150.354212807591;6211.503079753699;6262.749490020348;6267.62394256346;6241.375862413723;6262.46769024638;6265.937340626535;6276.309605943434;6290.716917738621;6054.66699331541;6096.950036952638;6200.100985357189;6210.378962103753;6228.159198508143;6138.478229350555;6200.875982480235;6204.13142160107;6208.255009398901;6226.59527130735;6013.6579366300275;6026.216591843032;6059.33347331802;6069.813313143749;6118.694065296806;6076.058210977306;6109.526129009804;6156.722947467285;6187.690615469299;6191.442770150708;6101.307919489844;6116.305337586134;6126.27339965311;6129.358790882775;6136.1821086261625 07:51:49 INFO - PROCESS | 1664 | |1;ext.html;464.8698364457952;480.6875530904909;500.2396357536547;522.2557855056546;537.8467136865994;28611.856940715294;33214.66785332146;42641.786791066;42843.78578107113;43401;42077.158456830846;42400.99999999992;44634.77682611591;44790.32814507785;44914;8423.410361274713;8787.209151176392;9656.468894210815;9856.556454959507;9874.407535547854;11600.477978490946;11844.170908036442;11849.407529623528;12037.097217708706;12051.15641905067;11948.522059117626;11974.940125299405;12050.879491205063;12058.216215945979;12058.758824823499;11889.643310700692;11932.04543636507;11963.222390544586;11964.64106076819;11971.341576213305;11583.18917675763;11847.229930054518;11886.40567971598;11949.64151075469;11969.281843089355;42002.57997420017;42254.57745422537;42320.365194522106;42352;42369.57630423687;3192.8563214655396;3290.6380298167182;3358.9496157557655;3415.624281329052;3661.4690869823726;29889;30030.399392012037;30793.846030769702;30831.691683083107;30843.53734693959;2578.697757632384;2613.9085132020286;2618.4370360372614;2643.035292118363;2646.073874144053;504910;508779;509721;510172;510201.44899275264;63957.36042639607;65262;65331.10016748763;65428.345716543176;65492.67253663701;69240.6537967307;69443.65278173577;69479.65260173767;69505.65247173731;69600.08399286117;17181.656366872594;17445.7383139253;17462;17463;17513.299468021138;12069.879301206876;12287.447064882102;13147.67130821718;13293.601191964257;13377.264250466123;18020.909895450437;18363.17365718546;18389.632207356044;18430.539236518925;18456.26174953014;6951.93048069523;7020.7893763187185;7081.433485321162;7089.361957423856;7113.89329160063;900.8828852249165;959.5298303831072;1021.4586269277295;1035.471909326249;1127.4757237884423;30324;30487.390252194833;30514.54228186579;30869;30986.690133098833;1113.5545781687229;1119.776044791045;1123.8988491035846;1160.4313886195703;1180.740237147826;15464.226788660522;15522.5343239703;15566.688666226839;15577.610559736097;16011.359545618277;670799.6460017767;679703;684725;686026;686072;63162.68418657877;63336.68331658312;63367.732645346834;63465;63534;1332.227308161264;1469.1038466535192;1494.1632685696045;1509.663947406553;1510.9168995705343;29816.254593635334;31091;31419.371612568077;31939.201519962186;32113.83943080363;53562;58917.82164356603;59921;60182.398176017676;64563;523663;622230;626659;632825;633399;23790.264119626463;24297.271081867573;24357.26928192157;25099.745012748925;27290;35653.465198021535;40705.59294407018;47594;48229.517704823906;50658.49341506685;38520.2295954085;38768.6123138765;39212.01969950774;40202.798986004884;40461.988450288976;38849.6115038846;39019.609803902735;39380;39828.00429989273;40419.595804041586;38212;38772;39179.60820391874;39518.802405987786;41685.166296674506;28324.575131372632;32006.51990220195;34939.82530087333;34980;35055;20540.69188962137;25488.362790929634;25576.488470230863;25749.485010300064;26205.868970655025 07:51:49 INFO - PROCESS | 1664 | |2;jquery.html;389.4392075411407;390.1118586043549;390.9315869722798;393.82081153075353;413.4191460997298;14100.57698269051;20490.28284010058;21391.144354225813;21631.99999999998;21633.134674613015;20807.375778726626;20821.27125550606;20859.895700521512;21160.89419552904;21336.146554137817;5825.67958762269;6108;6150.784722534712;6154.199954005973;6177.25872895251;5160.509751573613;5857.3849745776715;6058.606190597619;6066.726997285111;6091.0863370494335;5668.43315668434;6117.724702388381;6142.723577439026;6142.877142457148;6150.04674275488;4606.470255920579;5964;6022.578419510636;6024.09638554216;6139.447449729523;5218.895622087537;5225.555827754607;5240.292560504321;5249.895002099975;5261.263423120766;26378.73621263782;27055.86472067647;27429;27843;27863.303417414525;2263.762304957972;2274.2494976657663;2344.788968992799;2405.579023670851;2769.3076730817297;13796.655083622889;14475.493357732534;14525.273736313247;14810.62973425673;14928.402863885432;1583.6990971715338;1747.8426941575192;1760.111143872349;1763.6208215233787;1766.8144844791368;116764;119406.05939406077;120216;122244.99999999911;122441;10580.894191058145;10807.78384432307;11171.329720216812;11234.213605047731;11422.428878556038;12691.809622855664;13436.529721459701;13483.865161348458;13753.174809511462;13825.585232443043;8717.433366831136;8902.73291801247;8971.461712297283;8977.910220897838;9080.682176123935;8956.193942545202;8966.282697384193;8977.551122443982;9063.773405664911;9091.454512729126;5759.740811663485;6900.482463815234;7021.613811240329;7096.025847177035;7101.396381307647;1927.5855691026497;1932.1498540642071;1939.3890924359064;1940.0687669918518;1940.4469726127986;868.769776009358;950.0452045694171;970.5341436110706;1010.8786945566579;1012.8885822559513;18871.433856984313;20077.29729459462;22190.556188876133;22273;22295.665565016538;959.5298303831072;984.3503287829998;997.481309718942;1006.6879267426974;1012.8936461671418;8304.70933517324;11362;11805.291682498906;11806.17356785034;11861.46623401949;582728;597858;603443;613631;614750;108962.91037089686;118065;119839.40080299543;121383;123168.76831231752;1036.5646428499897;1039.948002599882;1064.7710742190466;1065.2010991756183;1065.5151905882806;19014.239430423182;19375.515612109248;19388.321408750628;19419.2232310706;19420.4173874784;49478.50521494739;51280.743596281776;51685.7415712919;51808.48191518037;51922.480775193275;69939.30060699327;72264.55470890658;73481.6325918367;73962;75560.24439755752;8201.09787923316;8229.218224268685;8264.545450000067;8309.95845020771;8598.570071496526;8763.386562940534;10075.891651918137;12908.096433249582;12951.935240323737;13311.800322995357;10861.076808471053;10985.505652245509;11151.052160566118;11385.715357116138;11544.88455115438;9051.456912585267;10970.780584388427;11251.493682784458;11546.364949927834;11547.902949219811;9967.651132210338;10735.443360712808;11435.885641143483;11473.82789258145;11894.226875253082;8108.594570271581;8111.107778144517;8238.711645092393;8356.080831108451;8570.142985701379;9968.850467243143;11388.487518061873;11439.485223165144;11497;11727.179097463095 07:51:49 INFO - PROCESS | 1664 | |3;mootools.html;1421.4172189402348;1479.3860547872632;1521.7565189569664;1819.208644239755;2006.5685877536337;8009.439339246255;8781.517016564087;8992.595333210005;9003.90996090039;9114.407563508368;8693;8978.865317020234;8981.730548083568;8986.550672466383;8993.640254389817;2381.6308472186797;2422.321749910019;2424.090965887792;2427.490227052312;2428.6357046443;2378.3340664613934;2396.1134380279323;2404.915827946022;2420.4311986683115;2424.1636635360774;2393.3537944754908;2405.1101092595677;2412.95174096518;2420.5642984262827;2422.0917156066475;1991.6813309870352;2381.047580967618;2388.7014123234594;2395.7244916834525;2401.4836810085917;2173.467500462397;2177.4556360909774;2296.793288604017;2381.4165529445395;2382.273406610983;7977.641006154737;8201.917980820175;8904.154105360045;9015.233705135071;9015.36892417531;4889.804407823683;5060.937203187335;5063.088644044071;5317.680939143625;5501.202325662796;3012.969870301291;3282.4748040313434;3316.668333166688;3522.894313170609;3526.894193174209;924.6347692661428;949.8670186173941;969.9418034917927;1534.7314220011453;1589.8966567173097;12034.398280085961;12169.269843809312;12182.573609923698;12184;12192.146549741521;4360.825566977349;4508.594226519564;4524.796384162721;4525.275955847049;4597.885052873705;9175.357724959322;9366.157045865904;9370.95314523423;9501;9511.619535218651;5153.742312884416;5196.168613021899;5216.973915130476;5219.034478621467;5229.111051121243;4692.906141877212;4825.782839772219;4839.854804355875;4905.852824415273;4978.850634480971;3602.2435288589168;3644.2347107107275;3737.663610275034;3768.5289338832645;3783.810809459516;911.2163539356148;1096.8683757949098;1098.5880294889416;1101.898101898102;1102.3000394749392;884.0408157149565;936.3305236755753;942.533445944262;955.6846240740539;972.1833659725864;5047.066292735855;5986.401359864066;6221.346758590374;6240.7503699851495;6271.937280627227;921.7188757429049;929.6188562689265;933.1135421349745;950.7242899559188;979.6620166042704;3996.960030399659;4093.406456063854;4104.897377565525;4135.441715368447;4176.60322269384;8723.65105395777;10846.511906964206;10968.670939871816;10971.670849874357;10999.505022273857;10628.681139565826;10775.353478791298;10826.729331766612;10897.618583349546;10936.398498082683;8283.875741863876;8351.081381048201;8449.535275559903;8475.872861907077;8480;5870.9412905870395;5908.586398952204;5926.496247818984;6089.847753806191;6109.511239100772;9790.363626364264;10116.393016419039;10266.6406675766;10292;10320.329178603366;10332.638357657446;10435.791284174426;10469.214808889212;10479.738006549593;10488.632897848538;7453.515521491086;7710.8843367350655;7716.961415192888;7900.051993760785;7900.644470998934;7211.891821622785;8669.696560620348;8704.868367112234;8768.868466972874;8771.605277762388;7448.851022979402;7674.769756907302;7690.423218258765;7803.804904877196;7931.087924888481;7214.206437291999;7365.005724227375;7488.625568721651;7520.736774212876;7557.924420755722;7185.353318201387;7212;7240.239774823778;7249.130104387507;7250.854982900207;4864.36763220779;4884.65807393479;4985.975070124771;5031.773570189277;5221.086309895829;5000.899982000266;5130.384353877558;5130.743462826769;5173.844784656316;5355.732213389237 07:51:49 INFO - PROCESS | 1664 | |4;prototype.html;308.9274020605159;309.7723173467502;320.65529555727585;325.1318978327864;327.29467996467605;4814.542618451242;5197.740112994349;5258.50221144956;5537.446255374461;5558.9166162507545;4602.447706275251;5193.402758682752;5331.253624492574;5366.758495867685;5507.531859791922;3982.5420076691116;4110.403991421257;4159.376093585971;4178.641941369052;4189.287821070426;3507.5089487471773;3938.9015274618077;4129.421880936671;4192.392103145042;4216.798212509436;3425.72594192464;3589.6769290763827;4036.697247706419;4037.4347591337237;4191.476065491814;4099.6720262379085;4126.442930204429;4138.772367519785;4156.625903668654;4165.271077561445;3073.800202986799;3187.21913131282;3633.5276414066266;3643.4716966039778;3662.853485860589;4977.576905962997;5293.717894905145;5334.27987221724;5343.465653434664;5350.571954243651;4144.730592511477;4706.882327941828;4739.289106634033;4783.689060211075;4791.448983366922;4863.756812159413;5731.598788084836;5747.683877386742;5750.2237197978575;5752.108423194411;3750.906227344311;3938;3957.7229593928437;3975.5229372475487;3983.7410568312966;5154.561862241677;6106.908396374058;6115.113308570232;6129.896618608649;6144.539159563051;3261.6249131349723;3857.1128640412862;4001.4998125234347;4033.616806403387;4053.4799450206174;3487.232808782064;3533.469979503095;4054.9797251014147;4075.9416494670004;4101.871985204033;4685.718856868599;4698.687425365109;4762.404699412574;4772.284157376422;4774.665773395898;5410.513053825174;5850.3564607893095;5874.853128671732;5942.70286485674;5959.910601340984;1242.87571242875;1369.8835598974;1381.894484412466;1383.73017261633;1391.4921053815488;761.6252663941639;820.1634332980445;829.4152622401193;832.6128350317132;844.2865778417228;4062.532808727034;4589.33454649074;4642.558956899089;4660.4174478190225;4670.6179848277625;4498.820047198141;4816.783244753994;5042.738885641627;5134.458443070629;5705.258316418839;4584.747838868828;4594.379758732528;5249.973750131225;5413.052715774802;5435.592330575223;2682.8926842926453;2861.957070643942;3142.3715256948703;3152.7793054485965;3175.872965081417;5312.415634280226;5763.366029736726;5827.184194212814;6014.51883849291;6243.843903902348;4714.6464015199;4836.395450568679;5471.753771080311;6139.539534534839;6175.3515880832765;5016.1723315653235;5392.272043274108;5787.739551720183;6105.473631592102;6289.528285378615;5868.979748872036;5982.222311099529;5984.648596785813;6003.6397816131175;6040.335563087969;5307.920381194208;5862.325832529314;5943.405659434022;6005.6396616203165;6025.186599809145;4994.275830004702;6182.814515564541;6210.850992566389;6250.468710159687;6255.437010669061;3799.7840690978624;3827.4067519534515;3830.712696547714;3833.290841194388;3889.144388234584;5103.693778373309;5267.525922666979;5945.702714864326;5997.640141591519;6027.9504968310985;4756.524347565216;5058.54473097423;5176.689398636094;5324;5441.932065814965;5564.443555644403;5746.42535746422;5752.9137062943255;5763.97118014424;5807.535397168301;4329.6536277098385;5623;5659.971700141473;5670.809130082729;5730.885382292414;4907.33750943618;5098.872528186825;5858.125399872;5875.178694604686;5877.177195192591;5058.645894787477;5484.561235101262;5542.501174894118;5742.339630942495;5781.971090144522 07:51:49 INFO - PROCESS | 1664 | |5;yui.html;650.9739610415583;710.5665544018148;750.6246876561719;759.3431681595418;790.8418316336735;15032.924835375821;15497;15499.147546884917;15521.611959700998;15600.531984040483;11684.649460516199;14324.068935519184;14338;14370.784438233435;14998.550043498684;922.7093465558361;940.9106134917172;943;943.7593413679537;947.0198344713223;927.7819712367622;937.5968333616543;942.9151376376125;943.4810854030256;947.6114792934864;997.2869398380157;998.2064258914181;999.6801023672427;999.7900440907382;1000.2398177385166;979.1823827104377;984.394597322645;986.960521579136;987.9703608891745;990.5641517732175;729.1760310848707;732.8241222106709;735.0260904301822;738.8596166728305;739.0909181706477;15147.848521514865;15240;15260.160691161982;15260.236988150547;15260.771088433683;11994.400279985966;12010.219335743148;12086.697832554168;12375.93812030943;12493.312867792269;10792.51433685486;11089;11091.889081109248;11164.776704465947;11210.327380357205;3950.9123042839547;3982.721209515335;3994.08136128692;4031.57668444812;4115.54728979812;90528;91871.54064229703;92909.60635590472;92946.53526732391;95465.045349547;9246.722598322061;9307.20888724466;9401.388909720847;9516.571754270935;9592.136707696201;19788.802111978697;20311.4922126948;20808.167673292897;20834.58330833405;20870.165193392397;6015.368386319461;6411.935880641227;7460.701571937062;7693.307602315818;7738.032745906762;3484.7977447780477;3963.009247688078;4067.3085575452246;4070.6946978976694;4073.165001174752;3047.5123980163066;3515.2793677296095;3651.014226158922;3651.7991510467177;3653.5433070866143;589.7026541608478;653.6274323635482;655.416679155552;669.0833558025536;669.6116252573593;582.5630776917312;599.9280086389574;602.16299343912;604.2507290959267;608.2670382189451;8173.550454724928;8193.918060819435;8214.671413143527;8325.542095184823;8377.581120943929;493.46459091884986;525.8869343091254;525.9105951988171;567.5544697412512;569.5728203847115;3846.442265871489;3849.7305188637088;3866.7873266970587;3880.3791393376923;4052.5339585947404;86663;87092.5645371769;87238.69141962875;92629;93170;60588.69705651444;72199.63900180465;77772;79135.60432197803;80724.59637701891;71779.56440871154;72256.63871680608;72437.18907027367;72519;72759.63620181865;7100.644967751488;7672.693092276247;7730.961345193238;7771.8834217485655;7796;32163;40283.187256574114;43734.781326093165;46424;46505.06989860116;60669.69665151646;60793.69603151956;60826.08760868502;60918.39081609127;62245.688771555855;10155.238357123097;10177.592896284066;10937.781244375226;11019.559217631206;11093.778124437305;11552.884471155181;11840.644780656594;11995.1003674723;12024.158308918291;12047.096467764777;11566.248193867372;11584.536618535165;11670.183087183816;11835.053195744147;12040.157188996685;11526.88473115258;11738.002269807163;11961.282323060645;12127.029837613147;12395.318257495925;11406.087512999105;11454.885451145716;11482.82775758381;11747.647570572897;11825.526978920747;9613.230941524802;9652.034796520291;9992.700218993441;10101.191904647463;10205.642802501878;9218.585163667785;9863.95068024684;9889.554970026222;9894.703158905244;9900.108990190918 07:51:49 INFO - PROCESS | 1664 | __end_tp_report 07:51:49 INFO - PROCESS | 1664 | __start_cc_report 07:51:49 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1358 07:51:49 INFO - PROCESS | 1664 | __end_cc_report 07:51:49 INFO - PROCESS | 1664 | __startTimestamp1463496708991__endTimestamp 07:51:49 INFO - PROCESS | 1664 | 07:51:49 INFO - PROCESS | 1664 | ------- Summary: start ------- 07:51:49 INFO - PROCESS | 1664 | Number of tests: 6 07:51:49 INFO - PROCESS | 1664 | 07:51:49 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5790.87 Median:6076.06 stddev:633.28 (10.4%) stddev-sans-first:608.38 07:51:49 INFO - PROCESS | 1664 | Values: 3465.3 3481.5 3506.4 3506.6 3550.1 5637.5 5678.7 5705.2 5773.2 5843.2 5446.5 5745.2 5869.3 5873.6 5936.4 5075.4 5083.3 5186.2 5194.9 5292.4 5308.6 5315.5 5343.4 5350.0 5379.7 5138.3 5179.8 5180.9 5250.8 5277.2 5228.5 5231.6 5235.1 5238.1 5239.8 5106.4 5144.9 5188.1 5202.2 5210.9 5601.8 5630.4 5655.1 5677.0 5722.4 6212.8 6265.5 6291.8 6303.4 6308.3 6156.4 6205.9 6251.1 6256.1 6271.0 5938.2 5978.7 6013.8 6015.1 6031.7 6362.7 6370.4 6377.1 6396.6 6396.8 4967.6 6122.6 6124.8 6162.4 6165.0 6215.7 6238.4 6263.7 6296.8 6304.4 6219.1 6225.7 6269.8 6285.8 6322.1 6133.2 6272.3 6293.6 6298.6 6310.2 4917.0 4955.1 5007.2 5037.0 5038.3 4480.5 4503.5 4509.4 4516.6 4550.4 5553.8 5768.8 5770.9 5786.6 5794.7 6194.6 6208.7 6212.7 6274.5 6310.7 5751.4 5763.7 5774.7 5777.6 5804.7 5328.9 5430.9 5446.7 5451.1 5456.2 6239.3 6356.3 6362.1 6373.9 6454.7 6264.3 6265.9 6268.0 6280.1 6287.1 5990.9 6204.1 6337.2 6442.7 6448.4 6142.9 6150.4 6211.5 6262.7 6267.6 6241.4 6262.5 6265.9 6276.3 6290.7 6054.7 6097.0 6200.1 6210.4 6228.2 6138.5 6200.9 6204.1 6208.3 6226.6 6013.7 6026.2 6059.3 6069.8 6118.7 6076.1 6109.5 6156.7 6187.7 6191.4 6101.3 6116.3 6126.3 6129.4 6136.2 07:51:49 INFO - PROCESS | 1664 | 07:51:49 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:73777.25 Median:19177.72 stddev:161516.83 (842.2%) stddev-sans-first:161876.14 07:51:49 INFO - PROCESS | 1664 | Values: 464.9 480.7 500.2 522.3 537.8 28611.9 33214.7 42641.8 42843.8 43401.0 42077.2 42401.0 44634.8 44790.3 44914.0 8423.4 8787.2 9656.5 9856.6 9874.4 11600.5 11844.2 11849.4 12037.1 12051.2 11948.5 11974.9 12050.9 12058.2 12058.8 11889.6 11932.0 11963.2 11964.6 11971.3 11583.2 11847.2 11886.4 11949.6 11969.3 42002.6 42254.6 42320.4 42352.0 42369.6 3192.9 3290.6 3358.9 3415.6 3661.5 29889.0 30030.4 30793.8 30831.7 30843.5 2578.7 2613.9 2618.4 2643.0 2646.1 504910.0 508779.0 509721.0 510172.0 510201.4 63957.4 65262.0 65331.1 65428.3 65492.7 69240.7 69443.7 69479.7 69505.7 69600.1 17181.7 17445.7 17462.0 17463.0 17513.3 12069.9 12287.4 13147.7 13293.6 13377.3 18020.9 18363.2 18389.6 18430.5 18456.3 6951.9 7020.8 7081.4 7089.4 7113.9 900.9 959.5 1021.5 1035.5 1127.5 30324.0 30487.4 30514.5 30869.0 30986.7 1113.6 1119.8 1123.9 1160.4 1180.7 15464.2 15522.5 15566.7 15577.6 16011.4 670799.6 679703.0 684725.0 686026.0 686072.0 63162.7 63336.7 63367.7 63465.0 63534.0 1332.2 1469.1 1494.2 1509.7 1510.9 29816.3 31091.0 31419.4 31939.2 32113.8 53562.0 58917.8 59921.0 60182.4 64563.0 523663.0 622230.0 626659.0 632825.0 633399.0 23790.3 24297.3 24357.3 25099.7 27290.0 35653.5 40705.6 47594.0 48229.5 50658.5 38520.2 38768.6 39212.0 40202.8 40462.0 38849.6 39019.6 39380.0 39828.0 40419.6 38212.0 38772.0 39179.6 39518.8 41685.2 28324.6 32006.5 34939.8 34980.0 35055.0 20540.7 25488.4 25576.5 25749.5 26205.9 07:51:49 INFO - PROCESS | 1664 | 07:51:49 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34989.52 Median:2309.52 stddev:100236.42 (4340.1%) stddev-sans-first:100483.94 07:51:49 INFO - PROCESS | 1664 | Values: 389.4 390.1 390.9 393.8 413.4 14100.6 20490.3 21391.1 21632.0 21633.1 20807.4 20821.3 20859.9 21160.9 21336.1 5825.7 6108.0 6150.8 6154.2 6177.3 5160.5 5857.4 6058.6 6066.7 6091.1 5668.4 6117.7 6142.7 6142.9 6150.0 4606.5 5964.0 6022.6 6024.1 6139.4 5218.9 5225.6 5240.3 5249.9 5261.3 26378.7 27055.9 27429.0 27843.0 27863.3 2263.8 2274.2 2344.8 2405.6 2769.3 13796.7 14475.5 14525.3 14810.6 14928.4 1583.7 1747.8 1760.1 1763.6 1766.8 116764.0 119406.1 120216.0 122245.0 122441.0 10580.9 10807.8 11171.3 11234.2 11422.4 12691.8 13436.5 13483.9 13753.2 13825.6 8717.4 8902.7 8971.5 8977.9 9080.7 8956.2 8966.3 8977.6 9063.8 9091.5 5759.7 6900.5 7021.6 7096.0 7101.4 1927.6 1932.1 1939.4 1940.1 1940.4 868.8 950.0 970.5 1010.9 1012.9 18871.4 20077.3 22190.6 22273.0 22295.7 959.5 984.4 997.5 1006.7 1012.9 8304.7 11362.0 11805.3 11806.2 11861.5 582728.0 597858.0 603443.0 613631.0 614750.0 108962.9 118065.0 119839.4 121383.0 123168.8 1036.6 1039.9 1064.8 1065.2 1065.5 19014.2 19375.5 19388.3 19419.2 19420.4 49478.5 51280.7 51685.7 51808.5 51922.5 69939.3 72264.6 73481.6 73962.0 75560.2 8201.1 8229.2 8264.5 8310.0 8598.6 8763.4 10075.9 12908.1 12951.9 13311.8 10861.1 10985.5 11151.1 11385.7 11544.9 9051.5 10970.8 11251.5 11546.4 11547.9 9967.7 10735.4 11435.9 11473.8 11894.2 8108.6 8111.1 8238.7 8356.1 8570.1 9968.9 11388.5 11439.5 11497.0 11727.2 07:51:49 INFO - PROCESS | 1664 | 07:51:49 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5757.88 Median:5054.00 stddev:3260.02 (64.5%) stddev-sans-first:3252.88 07:51:49 INFO - PROCESS | 1664 | Values: 1421.4 1479.4 1521.8 1819.2 2006.6 8009.4 8781.5 8992.6 9003.9 9114.4 8693.0 8978.9 8981.7 8986.6 8993.6 2381.6 2422.3 2424.1 2427.5 2428.6 2378.3 2396.1 2404.9 2420.4 2424.2 2393.4 2405.1 2413.0 2420.6 2422.1 1991.7 2381.0 2388.7 2395.7 2401.5 2173.5 2177.5 2296.8 2381.4 2382.3 7977.6 8201.9 8904.2 9015.2 9015.4 4889.8 5060.9 5063.1 5317.7 5501.2 3013.0 3282.5 3316.7 3522.9 3526.9 924.6 949.9 969.9 1534.7 1589.9 12034.4 12169.3 12182.6 12184.0 12192.1 4360.8 4508.6 4524.8 4525.3 4597.9 9175.4 9366.2 9371.0 9501.0 9511.6 5153.7 5196.2 5217.0 5219.0 5229.1 4692.9 4825.8 4839.9 4905.9 4978.9 3602.2 3644.2 3737.7 3768.5 3783.8 911.2 1096.9 1098.6 1101.9 1102.3 884.0 936.3 942.5 955.7 972.2 5047.1 5986.4 6221.3 6240.8 6271.9 921.7 929.6 933.1 950.7 979.7 3997.0 4093.4 4104.9 4135.4 4176.6 8723.7 10846.5 10968.7 10971.7 10999.5 10628.7 10775.4 10826.7 10897.6 10936.4 8283.9 8351.1 8449.5 8475.9 8480.0 5870.9 5908.6 5926.5 6089.8 6109.5 9790.4 10116.4 10266.6 10292.0 10320.3 10332.6 10435.8 10469.2 10479.7 10488.6 7453.5 7710.9 7717.0 7900.1 7900.6 7211.9 8669.7 8704.9 8768.9 8771.6 7448.9 7674.8 7690.4 7803.8 7931.1 7214.2 7365.0 7488.6 7520.7 7557.9 7185.4 7212.0 7240.2 7249.1 7250.9 4864.4 4884.7 4986.0 5031.8 5221.1 5000.9 5130.4 5130.7 5173.8 5355.7 07:51:49 INFO - PROCESS | 1664 | 07:51:49 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4592.42 Median:5050.64 stddev:1432.78 (28.4%) stddev-sans-first:1400.27 07:51:49 INFO - PROCESS | 1664 | Values: 308.9 309.8 320.7 325.1 327.3 4814.5 5197.7 5258.5 5537.4 5558.9 4602.4 5193.4 5331.3 5366.8 5507.5 3982.5 4110.4 4159.4 4178.6 4189.3 3507.5 3938.9 4129.4 4192.4 4216.8 3425.7 3589.7 4036.7 4037.4 4191.5 4099.7 4126.4 4138.8 4156.6 4165.3 3073.8 3187.2 3633.5 3643.5 3662.9 4977.6 5293.7 5334.3 5343.5 5350.6 4144.7 4706.9 4739.3 4783.7 4791.4 4863.8 5731.6 5747.7 5750.2 5752.1 3750.9 3938.0 3957.7 3975.5 3983.7 5154.6 6106.9 6115.1 6129.9 6144.5 3261.6 3857.1 4001.5 4033.6 4053.5 3487.2 3533.5 4055.0 4075.9 4101.9 4685.7 4698.7 4762.4 4772.3 4774.7 5410.5 5850.4 5874.9 5942.7 5959.9 1242.9 1369.9 1381.9 1383.7 1391.5 761.6 820.2 829.4 832.6 844.3 4062.5 4589.3 4642.6 4660.4 4670.6 4498.8 4816.8 5042.7 5134.5 5705.3 4584.7 4594.4 5250.0 5413.1 5435.6 2682.9 2862.0 3142.4 3152.8 3175.9 5312.4 5763.4 5827.2 6014.5 6243.8 4714.6 4836.4 5471.8 6139.5 6175.4 5016.2 5392.3 5787.7 6105.5 6289.5 5869.0 5982.2 5984.6 6003.6 6040.3 5307.9 5862.3 5943.4 6005.6 6025.2 4994.3 6182.8 6210.9 6250.5 6255.4 3799.8 3827.4 3830.7 3833.3 3889.1 5103.7 5267.5 5945.7 5997.6 6028.0 4756.5 5058.5 5176.7 5324.0 5441.9 5564.4 5746.4 5752.9 5764.0 5807.5 4329.7 5623.0 5660.0 5670.8 5730.9 4907.3 5098.9 5858.1 5875.2 5877.2 5058.6 5484.6 5542.5 5742.3 5782.0 07:51:49 INFO - PROCESS | 1664 | 07:51:49 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18115.62 Median:3307.65 stddev:25687.76 (776.6%) stddev-sans-first:25726.35 07:51:49 INFO - PROCESS | 1664 | Values: 651.0 710.6 750.6 759.3 790.8 15032.9 15497.0 15499.1 15521.6 15600.5 11684.6 14324.1 14338.0 14370.8 14998.6 922.7 940.9 943.0 943.8 947.0 927.8 937.6 942.9 943.5 947.6 997.3 998.2 999.7 999.8 1000.2 979.2 984.4 987.0 988.0 990.6 729.2 732.8 735.0 738.9 739.1 15147.8 15240.0 15260.2 15260.2 15260.8 11994.4 12010.2 12086.7 12375.9 12493.3 10792.5 11089.0 11091.9 11164.8 11210.3 3950.9 3982.7 3994.1 4031.6 4115.5 90528.0 91871.5 92909.6 92946.5 95465.0 9246.7 9307.2 9401.4 9516.6 9592.1 19788.8 20311.5 20808.2 20834.6 20870.2 6015.4 6411.9 7460.7 7693.3 7738.0 3484.8 3963.0 4067.3 4070.7 4073.2 3047.5 3515.3 3651.0 3651.8 3653.5 589.7 653.6 655.4 669.1 669.6 582.6 599.9 602.2 604.3 608.3 8173.6 8193.9 8214.7 8325.5 8377.6 493.5 525.9 525.9 567.6 569.6 3846.4 3849.7 3866.8 3880.4 4052.5 86663.0 87092.6 87238.7 92629.0 93170.0 60588.7 72199.6 77772.0 79135.6 80724.6 71779.6 72256.6 72437.2 72519.0 72759.6 7100.6 7672.7 7731.0 7771.9 7796.0 32163.0 40283.2 43734.8 46424.0 46505.1 60669.7 60793.7 60826.1 60918.4 62245.7 10155.2 10177.6 10937.8 11019.6 11093.8 11552.9 11840.6 11995.1 12024.2 12047.1 11566.2 11584.5 11670.2 11835.1 12040.2 11526.9 11738.0 11961.3 12127.0 12395.3 11406.1 11454.9 11482.8 11747.6 11825.5 9613.2 9652.0 9992.7 10101.2 10205.6 9218.6 9864.0 9889.6 9894.7 9900.1 07:51:49 INFO - PROCESS | 1664 | -------- Summary: end -------- 07:51:49 INFO - PROCESS | 1664 | 07:51:49 INFO - TEST-INFO | 1664: exit 0 07:51:49 INFO - TEST-OK | dromaeo_css | took 1116074ms 07:51:49 INFO - TEST-START | kraken 07:51:49 INFO - operating with platform_type : mac_ 07:51:49 INFO - Initialising browser for kraken test... 07:51:49 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmp1Lp13X/profile http://localhost:49273/getInfo.html) 07:51:51 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 07:51:51 INFO - PROCESS | 1671 | colorDepth:24 07:51:51 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 07:51:51 INFO - PROCESS | 1671 | __metrics 07:51:51 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:51:51 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:51:52 INFO - TEST-INFO | 1671: exit 0 07:51:52 INFO - Browser initialized. 07:51:52 INFO - Running cycle 1/1 for kraken test... 07:51:52 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmp1Lp13X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:52:04 INFO - PROCESS | 1673 | RSS: Main: 239259648 07:52:04 INFO - PROCESS | 1673 | 07:52:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 07:52:08 INFO - PROCESS | 1673 | RSS: Main: 287916032 07:52:08 INFO - PROCESS | 1673 | 07:52:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 07:52:11 INFO - PROCESS | 1673 | RSS: Main: 296747008 07:52:11 INFO - PROCESS | 1673 | 07:52:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 07:52:15 INFO - PROCESS | 1673 | RSS: Main: 386830336 07:52:15 INFO - PROCESS | 1673 | 07:52:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 07:52:18 INFO - PROCESS | 1673 | RSS: Main: 284520448 07:52:18 INFO - PROCESS | 1673 | 07:52:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:52:21 INFO - PROCESS | 1673 | RSS: Main: 288776192 07:52:21 INFO - PROCESS | 1673 | 07:52:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 07:52:25 INFO - PROCESS | 1673 | RSS: Main: 326733824 07:52:25 INFO - PROCESS | 1673 | 07:52:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 07:52:28 INFO - PROCESS | 1673 | RSS: Main: 330002432 07:52:28 INFO - PROCESS | 1673 | 07:52:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 07:52:32 INFO - PROCESS | 1673 | RSS: Main: 327839744 07:52:32 INFO - PROCESS | 1673 | 07:52:34 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:52:34 INFO - PROCESS | 1673 | RSS: Main: 316944384 07:52:34 INFO - PROCESS | 1673 | 07:52:36 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:52:37 INFO - PROCESS | 1673 | RSS: Main: 324341760 07:52:37 INFO - PROCESS | 1673 | 07:52:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:52:40 INFO - PROCESS | 1673 | RSS: Main: 326291456 07:52:40 INFO - PROCESS | 1673 | 07:52:43 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:52:44 INFO - PROCESS | 1673 | RSS: Main: 330256384 07:52:44 INFO - PROCESS | 1673 | 07:52:46 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:52:47 INFO - PROCESS | 1673 | RSS: Main: 327884800 07:52:47 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 07:52:49 INFO - PROCESS | 1673 | RSS: Main: 374337536 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | __start_tp_report 07:52:49 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 07:52:49 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 07:52:49 INFO - PROCESS | 1673 | |i|pagename|runs| 07:52:49 INFO - PROCESS | 1673 | |0;ai-astar;112;105;113;111;111;111;111;113;111;112 07:52:49 INFO - PROCESS | 1673 | |1;audio-beat-detection;170;149;137;195;118;135;136;117;161;120 07:52:49 INFO - PROCESS | 1673 | |2;audio-dft;184;186;183;184;183;181;182;180;181;184 07:52:49 INFO - PROCESS | 1673 | |3;audio-fft;78;73;56;129;71;85;47;76;129;79 07:52:49 INFO - PROCESS | 1673 | |4;audio-oscillator;91;101;136;78;79;77;125;115;114;100 07:52:49 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;89;86;91;87;90;86;90;87;89;85 07:52:49 INFO - PROCESS | 1673 | |6;imaging-darkroom;93;93;94;94;94;94;94;93;93;93 07:52:49 INFO - PROCESS | 1673 | |7;imaging-desaturate;85;84;85;86;83;85;84;85;86;85 07:52:49 INFO - PROCESS | 1673 | |8;json-parse-financial;57;57;57;56;56;57;56;116;55;56 07:52:49 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;62;68;68;78;69;65;67;69;67;66 07:52:49 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;85;83;82;85;86;96;106;104;76;99 07:52:49 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;141;143;133;137;135;135;136;139;234;145 07:52:49 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;163;159;163;161;161;164;164;164;160;163 07:52:49 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;67;65;66;65;64;62;65;64;63;64 07:52:49 INFO - PROCESS | 1673 | __end_tp_report 07:52:49 INFO - PROCESS | 1673 | __start_cc_report 07:52:49 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2280 07:52:49 INFO - PROCESS | 1673 | __end_cc_report 07:52:49 INFO - PROCESS | 1673 | __startTimestamp1463496769455__endTimestamp 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | ------- Summary: start ------- 07:52:49 INFO - PROCESS | 1673 | Number of tests: 14 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.50 stddev:2.26 (2.0%) stddev-sans-first:2.37 07:52:49 INFO - PROCESS | 1673 | Values: 112.0 105.0 113.0 111.0 111.0 111.0 111.0 113.0 111.0 112.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:143.00 stddev:25.31 (17.7%) stddev-sans-first:25.01 07:52:49 INFO - PROCESS | 1673 | Values: 170.0 149.0 137.0 195.0 118.0 135.0 136.0 117.0 161.0 120.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.50 stddev:1.81 (1.0%) stddev-sans-first:1.87 07:52:49 INFO - PROCESS | 1673 | Values: 184.0 186.0 183.0 184.0 183.0 181.0 182.0 180.0 181.0 184.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:82.30 Median:74.50 stddev:27.06 (36.3%) stddev-sans-first:28.66 07:52:49 INFO - PROCESS | 1673 | Values: 78.0 73.0 56.0 129.0 71.0 85.0 47.0 76.0 129.0 79.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:106.50 stddev:20.69 (19.4%) stddev-sans-first:21.59 07:52:49 INFO - PROCESS | 1673 | Values: 91.0 101.0 136.0 78.0 79.0 77.0 125.0 115.0 114.0 100.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:89.00 stddev:2.05 (2.3%) stddev-sans-first:2.15 07:52:49 INFO - PROCESS | 1673 | Values: 89.0 86.0 91.0 87.0 90.0 86.0 90.0 87.0 89.0 85.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 07:52:49 INFO - PROCESS | 1673 | Values: 93.0 93.0 94.0 94.0 94.0 94.0 94.0 93.0 93.0 93.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:0.92 (1.1%) stddev-sans-first:0.97 07:52:49 INFO - PROCESS | 1673 | Values: 85.0 84.0 85.0 86.0 83.0 85.0 84.0 85.0 86.0 85.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:56.50 stddev:18.88 (33.4%) stddev-sans-first:19.93 07:52:49 INFO - PROCESS | 1673 | Values: 57.0 57.0 57.0 56.0 56.0 57.0 56.0 116.0 55.0 56.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:68.00 stddev:4.12 (6.1%) stddev-sans-first:3.78 07:52:49 INFO - PROCESS | 1673 | Values: 62.0 68.0 68.0 78.0 69.0 65.0 67.0 69.0 67.0 66.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:85.00 stddev:10.24 (12.0%) stddev-sans-first:10.69 07:52:49 INFO - PROCESS | 1673 | Values: 85.0 83.0 82.0 85.0 86.0 96.0 106.0 104.0 76.0 99.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:140.00 stddev:30.53 (21.8%) stddev-sans-first:32.28 07:52:49 INFO - PROCESS | 1673 | Values: 141.0 143.0 133.0 137.0 135.0 135.0 136.0 139.0 234.0 145.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:163.00 stddev:1.81 (1.1%) stddev-sans-first:1.90 07:52:49 INFO - PROCESS | 1673 | Values: 163.0 159.0 163.0 161.0 161.0 164.0 164.0 164.0 160.0 163.0 07:52:49 INFO - PROCESS | 1673 | 07:52:49 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:65.00 stddev:1.43 (2.2%) stddev-sans-first:1.20 07:52:49 INFO - PROCESS | 1673 | Values: 67.0 65.0 66.0 65.0 64.0 62.0 65.0 64.0 63.0 64.0 07:52:49 INFO - PROCESS | 1673 | -------- Summary: end -------- 07:52:49 INFO - PROCESS | 1673 | 07:52:50 INFO - TEST-INFO | 1673: exit 0 07:52:50 INFO - TEST-OK | kraken | took 60738ms 07:52:50 INFO - SUITE-END | took 1176s 07:52:50 INFO - Completed test suite (00:19:37) 07:52:50 INFO - javascript benchmark 07:52:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3465.272292818508, 3481.529993450885, 3506.421440462323, 3506.631803660617, 3550.1124718820297, 5637.46444087811, 5678.716064196784, 5705.172749951254, 5773.162891380747, 5843.2403787507565, 5446.537044351225, 5745.166950792132, 5869.295684517864, 5873.559483038768, 5936.406359364061, 5075.365579302587, 5083.339165908426, 5186.170212765958, 5194.948050519503, 5292.391374991887, 5308.601854860882, 5315.521603055724, 5343.438938911417, 5350.036993341217, 5379.704116273623, 5138.2549530318265, 5179.8446046618665, 5180.922286165711, 5250.816221432251, 5277.1556550951855, 5228.47715228478, 5231.633785635007, 5235.057689615867, 5238.109521381374, 5239.79040838366, 5106.4382917879, 5144.897102057976, 5188.118019936582, 5202.193659982709, 5210.921836172461, 5601.80393686219, 5630.380658127603, 5655.095184770417, 5676.971615141939, 5722.37053924068, 6212.782552610637, 6265.467435267962, 6291.7797877074545, 6303.401176888188, 6308.3060863305, 6156.3843615638525, 6205.937940620581, 6251.12484252205, 6256.092866534373, 6271.0, 5938.198343223696, 5978.701064946736, 6013.759449621966, 6015.09773533969, 6031.728572214173, 6362.713677884506, 6370.362963703686, 6377.107204991305, 6396.648184349905, 6396.808095757135, 4967.627427942919, 6122.632642041492, 6124.846878828065, 6162.414570615874, 6165.013597824327, 6215.658138802319, 6238.438540531374, 6263.749450022039, 6296.779612713533, 6304.401081897213, 6219.129321894939, 6225.657588832658, 6269.843253918689, 6285.779997700059, 6322.114903913456, 6133.242854861876, 6272.3100458949475, 6293.622382657055, 6298.622082675054, 6310.211223597051, 4917.0, 4955.108080545464, 5007.24891266313, 5036.967421678547, 5038.34501514808, 4480.484744254452, 4503.549645035536, 4509.413776209072, 4516.6160876325885, 4550.385697930803, 5553.77784888608, 5768.7692492300675, 5770.855728606734, 5786.594938354275, 5794.710264486843, 6194.628322300677, 6208.689565521706, 6212.658303793335, 6274.466670333073, 6310.652914089769, 5751.396103409082, 5763.6541807491685, 5774.68239246837, 5777.566682498746, 5804.651720896675, 5328.946710532922, 5430.864228394243, 5446.700431476307, 5451.127819548932, 5456.236126942231, 6239.313675495692, 6356.332585078592, 6362.077498762561, 6373.904391434225, 6454.741810327722, 6264.31092579825, 6265.874682506415, 6267.96866015667, 6280.0893870389455, 6287.056941458818, 5990.910136347871, 6204.100405441276, 6337.2395312562785, 6442.710078046593, 6448.387403196689, 6142.867137759686, 6150.354212807591, 6211.503079753699, 6262.749490020348, 6267.62394256346, 6241.375862413723, 6262.46769024638, 6265.937340626535, 6276.309605943434, 6290.716917738621, 6054.66699331541, 6096.950036952638, 6200.100985357189, 6210.378962103753, 6228.159198508143, 6138.478229350555, 6200.875982480235, 6204.13142160107, 6208.255009398901, 6226.59527130735, 6013.6579366300275, 6026.216591843032, 6059.33347331802, 6069.813313143749, 6118.694065296806, 6076.058210977306, 6109.526129009804, 6156.722947467285, 6187.690615469299, 6191.442770150708, 6101.307919489844, 6116.305337586134, 6126.27339965311, 6129.358790882775, 6136.1821086261625], "value": 5751.515930712707, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.8698364457952, 480.6875530904909, 500.2396357536547, 522.2557855056546, 537.8467136865994, 28611.856940715294, 33214.66785332146, 42641.786791066, 42843.78578107113, 43401.0, 42077.158456830846, 42400.99999999992, 44634.77682611591, 44790.32814507785, 44914.0, 8423.410361274713, 8787.209151176392, 9656.468894210815, 9856.556454959507, 9874.407535547854, 11600.477978490946, 11844.170908036442, 11849.407529623528, 12037.097217708706, 12051.15641905067, 11948.522059117626, 11974.940125299405, 12050.879491205063, 12058.216215945979, 12058.758824823499, 11889.643310700692, 11932.04543636507, 11963.222390544586, 11964.64106076819, 11971.341576213305, 11583.18917675763, 11847.229930054518, 11886.40567971598, 11949.64151075469, 11969.281843089355, 42002.57997420017, 42254.57745422537, 42320.365194522106, 42352.0, 42369.57630423687, 3192.8563214655396, 3290.6380298167182, 3358.9496157557655, 3415.624281329052, 3661.4690869823726, 29889.0, 30030.399392012037, 30793.846030769702, 30831.691683083107, 30843.53734693959, 2578.697757632384, 2613.9085132020286, 2618.4370360372614, 2643.035292118363, 2646.073874144053, 504910.0, 508779.0, 509721.0, 510172.0, 510201.44899275264, 63957.36042639607, 65262.0, 65331.10016748763, 65428.345716543176, 65492.67253663701, 69240.6537967307, 69443.65278173577, 69479.65260173767, 69505.65247173731, 69600.08399286117, 17181.656366872594, 17445.7383139253, 17462.0, 17463.0, 17513.299468021138, 12069.879301206876, 12287.447064882102, 13147.67130821718, 13293.601191964257, 13377.264250466123, 18020.909895450437, 18363.17365718546, 18389.632207356044, 18430.539236518925, 18456.26174953014, 6951.93048069523, 7020.7893763187185, 7081.433485321162, 7089.361957423856, 7113.89329160063, 900.8828852249165, 959.5298303831072, 1021.4586269277295, 1035.471909326249, 1127.4757237884423, 30324.0, 30487.390252194833, 30514.54228186579, 30869.0, 30986.690133098833, 1113.5545781687229, 1119.776044791045, 1123.8988491035846, 1160.4313886195703, 1180.740237147826, 15464.226788660522, 15522.5343239703, 15566.688666226839, 15577.610559736097, 16011.359545618277, 670799.6460017767, 679703.0, 684725.0, 686026.0, 686072.0, 63162.68418657877, 63336.68331658312, 63367.732645346834, 63465.0, 63534.0, 1332.227308161264, 1469.1038466535192, 1494.1632685696045, 1509.663947406553, 1510.9168995705343, 29816.254593635334, 31091.0, 31419.371612568077, 31939.201519962186, 32113.83943080363, 53562.0, 58917.82164356603, 59921.0, 60182.398176017676, 64563.0, 523663.0, 622230.0, 626659.0, 632825.0, 633399.0, 23790.264119626463, 24297.271081867573, 24357.26928192157, 25099.745012748925, 27290.0, 35653.465198021535, 40705.59294407018, 47594.0, 48229.517704823906, 50658.49341506685, 38520.2295954085, 38768.6123138765, 39212.01969950774, 40202.798986004884, 40461.988450288976, 38849.6115038846, 39019.609803902735, 39380.0, 39828.00429989273, 40419.595804041586, 38212.0, 38772.0, 39179.60820391874, 39518.802405987786, 41685.166296674506, 28324.575131372632, 32006.51990220195, 34939.82530087333, 34980.0, 35055.0, 20540.69188962137, 25488.362790929634, 25576.488470230863, 25749.485010300064, 26205.868970655025], "value": 20761.383753660004, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.4392075411407, 390.1118586043549, 390.9315869722798, 393.82081153075353, 413.4191460997298, 14100.57698269051, 20490.28284010058, 21391.144354225813, 21631.99999999998, 21633.134674613015, 20807.375778726626, 20821.27125550606, 20859.895700521512, 21160.89419552904, 21336.146554137817, 5825.67958762269, 6108.0, 6150.784722534712, 6154.199954005973, 6177.25872895251, 5160.509751573613, 5857.3849745776715, 6058.606190597619, 6066.726997285111, 6091.0863370494335, 5668.43315668434, 6117.724702388381, 6142.723577439026, 6142.877142457148, 6150.04674275488, 4606.470255920579, 5964.0, 6022.578419510636, 6024.09638554216, 6139.447449729523, 5218.895622087537, 5225.555827754607, 5240.292560504321, 5249.895002099975, 5261.263423120766, 26378.73621263782, 27055.86472067647, 27429.0, 27843.0, 27863.303417414525, 2263.762304957972, 2274.2494976657663, 2344.788968992799, 2405.579023670851, 2769.3076730817297, 13796.655083622889, 14475.493357732534, 14525.273736313247, 14810.62973425673, 14928.402863885432, 1583.6990971715338, 1747.8426941575192, 1760.111143872349, 1763.6208215233787, 1766.8144844791368, 116764.0, 119406.05939406077, 120216.0, 122244.99999999911, 122441.0, 10580.894191058145, 10807.78384432307, 11171.329720216812, 11234.213605047731, 11422.428878556038, 12691.809622855664, 13436.529721459701, 13483.865161348458, 13753.174809511462, 13825.585232443043, 8717.433366831136, 8902.73291801247, 8971.461712297283, 8977.910220897838, 9080.682176123935, 8956.193942545202, 8966.282697384193, 8977.551122443982, 9063.773405664911, 9091.454512729126, 5759.740811663485, 6900.482463815234, 7021.613811240329, 7096.025847177035, 7101.396381307647, 1927.5855691026497, 1932.1498540642071, 1939.3890924359064, 1940.0687669918518, 1940.4469726127986, 868.769776009358, 950.0452045694171, 970.5341436110706, 1010.8786945566579, 1012.8885822559513, 18871.433856984313, 20077.29729459462, 22190.556188876133, 22273.0, 22295.665565016538, 959.5298303831072, 984.3503287829998, 997.481309718942, 1006.6879267426974, 1012.8936461671418, 8304.70933517324, 11362.0, 11805.291682498906, 11806.17356785034, 11861.46623401949, 582728.0, 597858.0, 603443.0, 613631.0, 614750.0, 108962.91037089686, 118065.0, 119839.40080299543, 121383.0, 123168.76831231752, 1036.5646428499897, 1039.948002599882, 1064.7710742190466, 1065.2010991756183, 1065.5151905882806, 19014.239430423182, 19375.515612109248, 19388.321408750628, 19419.2232310706, 19420.4173874784, 49478.50521494739, 51280.743596281776, 51685.7415712919, 51808.48191518037, 51922.480775193275, 69939.30060699327, 72264.55470890658, 73481.6325918367, 73962.0, 75560.24439755752, 8201.09787923316, 8229.218224268685, 8264.545450000067, 8309.95845020771, 8598.570071496526, 8763.386562940534, 10075.891651918137, 12908.096433249582, 12951.935240323737, 13311.800322995357, 10861.076808471053, 10985.505652245509, 11151.052160566118, 11385.715357116138, 11544.88455115438, 9051.456912585267, 10970.780584388427, 11251.493682784458, 11546.364949927834, 11547.902949219811, 9967.651132210338, 10735.443360712808, 11435.885641143483, 11473.82789258145, 11894.226875253082, 8108.594570271581, 8111.107778144517, 8238.711645092393, 8356.080831108451, 8570.142985701379, 9968.850467243143, 11388.487518061873, 11439.485223165144, 11497.0, 11727.179097463095], "value": 9802.438348564767, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.4172189402348, 1479.3860547872632, 1521.7565189569664, 1819.208644239755, 2006.5685877536337, 8009.439339246255, 8781.517016564087, 8992.595333210005, 9003.90996090039, 9114.407563508368, 8693.0, 8978.865317020234, 8981.730548083568, 8986.550672466383, 8993.640254389817, 2381.6308472186797, 2422.321749910019, 2424.090965887792, 2427.490227052312, 2428.6357046443, 2378.3340664613934, 2396.1134380279323, 2404.915827946022, 2420.4311986683115, 2424.1636635360774, 2393.3537944754908, 2405.1101092595677, 2412.95174096518, 2420.5642984262827, 2422.0917156066475, 1991.6813309870352, 2381.047580967618, 2388.7014123234594, 2395.7244916834525, 2401.4836810085917, 2173.467500462397, 2177.4556360909774, 2296.793288604017, 2381.4165529445395, 2382.273406610983, 7977.641006154737, 8201.917980820175, 8904.154105360045, 9015.233705135071, 9015.36892417531, 4889.804407823683, 5060.937203187335, 5063.088644044071, 5317.680939143625, 5501.202325662796, 3012.969870301291, 3282.4748040313434, 3316.668333166688, 3522.894313170609, 3526.894193174209, 924.6347692661428, 949.8670186173941, 969.9418034917927, 1534.7314220011453, 1589.8966567173097, 12034.398280085961, 12169.269843809312, 12182.573609923698, 12184.0, 12192.146549741521, 4360.825566977349, 4508.594226519564, 4524.796384162721, 4525.275955847049, 4597.885052873705, 9175.357724959322, 9366.157045865904, 9370.95314523423, 9501.0, 9511.619535218651, 5153.742312884416, 5196.168613021899, 5216.973915130476, 5219.034478621467, 5229.111051121243, 4692.906141877212, 4825.782839772219, 4839.854804355875, 4905.852824415273, 4978.850634480971, 3602.2435288589168, 3644.2347107107275, 3737.663610275034, 3768.5289338832645, 3783.810809459516, 911.2163539356148, 1096.8683757949098, 1098.5880294889416, 1101.898101898102, 1102.3000394749392, 884.0408157149565, 936.3305236755753, 942.533445944262, 955.6846240740539, 972.1833659725864, 5047.066292735855, 5986.401359864066, 6221.346758590374, 6240.7503699851495, 6271.937280627227, 921.7188757429049, 929.6188562689265, 933.1135421349745, 950.7242899559188, 979.6620166042704, 3996.960030399659, 4093.406456063854, 4104.897377565525, 4135.441715368447, 4176.60322269384, 8723.65105395777, 10846.511906964206, 10968.670939871816, 10971.670849874357, 10999.505022273857, 10628.681139565826, 10775.353478791298, 10826.729331766612, 10897.618583349546, 10936.398498082683, 8283.875741863876, 8351.081381048201, 8449.535275559903, 8475.872861907077, 8480.0, 5870.9412905870395, 5908.586398952204, 5926.496247818984, 6089.847753806191, 6109.511239100772, 9790.363626364264, 10116.393016419039, 10266.6406675766, 10292.0, 10320.329178603366, 10332.638357657446, 10435.791284174426, 10469.214808889212, 10479.738006549593, 10488.632897848538, 7453.515521491086, 7710.8843367350655, 7716.961415192888, 7900.051993760785, 7900.644470998934, 7211.891821622785, 8669.696560620348, 8704.868367112234, 8768.868466972874, 8771.605277762388, 7448.851022979402, 7674.769756907302, 7690.423218258765, 7803.804904877196, 7931.087924888481, 7214.206437291999, 7365.005724227375, 7488.625568721651, 7520.736774212876, 7557.924420755722, 7185.353318201387, 7212.0, 7240.239774823778, 7249.130104387507, 7250.854982900207, 4864.36763220779, 4884.65807393479, 4985.975070124771, 5031.773570189277, 5221.086309895829, 5000.899982000266, 5130.384353877558, 5130.743462826769, 5173.844784656316, 5355.732213389237], "value": 4611.119393173211, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.9274020605159, 309.7723173467502, 320.65529555727585, 325.1318978327864, 327.29467996467605, 4814.542618451242, 5197.740112994349, 5258.50221144956, 5537.446255374461, 5558.9166162507545, 4602.447706275251, 5193.402758682752, 5331.253624492574, 5366.758495867685, 5507.531859791922, 3982.5420076691116, 4110.403991421257, 4159.376093585971, 4178.641941369052, 4189.287821070426, 3507.5089487471773, 3938.9015274618077, 4129.421880936671, 4192.392103145042, 4216.798212509436, 3425.72594192464, 3589.6769290763827, 4036.697247706419, 4037.4347591337237, 4191.476065491814, 4099.6720262379085, 4126.442930204429, 4138.772367519785, 4156.625903668654, 4165.271077561445, 3073.800202986799, 3187.21913131282, 3633.5276414066266, 3643.4716966039778, 3662.853485860589, 4977.576905962997, 5293.717894905145, 5334.27987221724, 5343.465653434664, 5350.571954243651, 4144.730592511477, 4706.882327941828, 4739.289106634033, 4783.689060211075, 4791.448983366922, 4863.756812159413, 5731.598788084836, 5747.683877386742, 5750.2237197978575, 5752.108423194411, 3750.906227344311, 3938.0, 3957.7229593928437, 3975.5229372475487, 3983.7410568312966, 5154.561862241677, 6106.908396374058, 6115.113308570232, 6129.896618608649, 6144.539159563051, 3261.6249131349723, 3857.1128640412862, 4001.4998125234347, 4033.616806403387, 4053.4799450206174, 3487.232808782064, 3533.469979503095, 4054.9797251014147, 4075.9416494670004, 4101.871985204033, 4685.718856868599, 4698.687425365109, 4762.404699412574, 4772.284157376422, 4774.665773395898, 5410.513053825174, 5850.3564607893095, 5874.853128671732, 5942.70286485674, 5959.910601340984, 1242.87571242875, 1369.8835598974, 1381.894484412466, 1383.73017261633, 1391.4921053815488, 761.6252663941639, 820.1634332980445, 829.4152622401193, 832.6128350317132, 844.2865778417228, 4062.532808727034, 4589.33454649074, 4642.558956899089, 4660.4174478190225, 4670.6179848277625, 4498.820047198141, 4816.783244753994, 5042.738885641627, 5134.458443070629, 5705.258316418839, 4584.747838868828, 4594.379758732528, 5249.973750131225, 5413.052715774802, 5435.592330575223, 2682.8926842926453, 2861.957070643942, 3142.3715256948703, 3152.7793054485965, 3175.872965081417, 5312.415634280226, 5763.366029736726, 5827.184194212814, 6014.51883849291, 6243.843903902348, 4714.6464015199, 4836.395450568679, 5471.753771080311, 6139.539534534839, 6175.3515880832765, 5016.1723315653235, 5392.272043274108, 5787.739551720183, 6105.473631592102, 6289.528285378615, 5868.979748872036, 5982.222311099529, 5984.648596785813, 6003.6397816131175, 6040.335563087969, 5307.920381194208, 5862.325832529314, 5943.405659434022, 6005.6396616203165, 6025.186599809145, 4994.275830004702, 6182.814515564541, 6210.850992566389, 6250.468710159687, 6255.437010669061, 3799.7840690978624, 3827.4067519534515, 3830.712696547714, 3833.290841194388, 3889.144388234584, 5103.693778373309, 5267.525922666979, 5945.702714864326, 5997.640141591519, 6027.9504968310985, 4756.524347565216, 5058.54473097423, 5176.689398636094, 5324.0, 5441.932065814965, 5564.443555644403, 5746.42535746422, 5752.9137062943255, 5763.97118014424, 5807.535397168301, 4329.6536277098385, 5623.0, 5659.971700141473, 5670.809130082729, 5730.885382292414, 4907.33750943618, 5098.872528186825, 5858.125399872, 5875.178694604686, 5877.177195192591, 5058.645894787477, 5484.561235101262, 5542.501174894118, 5742.339630942495, 5781.971090144522], "value": 4135.49871538564, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.9739610415583, 710.5665544018148, 750.6246876561719, 759.3431681595418, 790.8418316336735, 15032.924835375821, 15497.0, 15499.147546884917, 15521.611959700998, 15600.531984040483, 11684.649460516199, 14324.068935519184, 14338.0, 14370.784438233435, 14998.550043498684, 922.7093465558361, 940.9106134917172, 943.0, 943.7593413679537, 947.0198344713223, 927.7819712367622, 937.5968333616543, 942.9151376376125, 943.4810854030256, 947.6114792934864, 997.2869398380157, 998.2064258914181, 999.6801023672427, 999.7900440907382, 1000.2398177385166, 979.1823827104377, 984.394597322645, 986.960521579136, 987.9703608891745, 990.5641517732175, 729.1760310848707, 732.8241222106709, 735.0260904301822, 738.8596166728305, 739.0909181706477, 15147.848521514865, 15240.0, 15260.160691161982, 15260.236988150547, 15260.771088433683, 11994.400279985966, 12010.219335743148, 12086.697832554168, 12375.93812030943, 12493.312867792269, 10792.51433685486, 11089.0, 11091.889081109248, 11164.776704465947, 11210.327380357205, 3950.9123042839547, 3982.721209515335, 3994.08136128692, 4031.57668444812, 4115.54728979812, 90528.0, 91871.54064229703, 92909.60635590472, 92946.53526732391, 95465.045349547, 9246.722598322061, 9307.20888724466, 9401.388909720847, 9516.571754270935, 9592.136707696201, 19788.802111978697, 20311.4922126948, 20808.167673292897, 20834.58330833405, 20870.165193392397, 6015.368386319461, 6411.935880641227, 7460.701571937062, 7693.307602315818, 7738.032745906762, 3484.7977447780477, 3963.009247688078, 4067.3085575452246, 4070.6946978976694, 4073.165001174752, 3047.5123980163066, 3515.2793677296095, 3651.014226158922, 3651.7991510467177, 3653.5433070866143, 589.7026541608478, 653.6274323635482, 655.416679155552, 669.0833558025536, 669.6116252573593, 582.5630776917312, 599.9280086389574, 602.16299343912, 604.2507290959267, 608.2670382189451, 8173.550454724928, 8193.918060819435, 8214.671413143527, 8325.542095184823, 8377.581120943929, 493.46459091884986, 525.8869343091254, 525.9105951988171, 567.5544697412512, 569.5728203847115, 3846.442265871489, 3849.7305188637088, 3866.7873266970587, 3880.3791393376923, 4052.5339585947404, 86663.0, 87092.5645371769, 87238.69141962875, 92629.0, 93170.0, 60588.69705651444, 72199.63900180465, 77772.0, 79135.60432197803, 80724.59637701891, 71779.56440871154, 72256.63871680608, 72437.18907027367, 72519.0, 72759.63620181865, 7100.644967751488, 7672.693092276247, 7730.961345193238, 7771.8834217485655, 7796.0, 32163.0, 40283.187256574114, 43734.781326093165, 46424.0, 46505.06989860116, 60669.69665151646, 60793.69603151956, 60826.08760868502, 60918.39081609127, 62245.688771555855, 10155.238357123097, 10177.592896284066, 10937.781244375226, 11019.559217631206, 11093.778124437305, 11552.884471155181, 11840.644780656594, 11995.1003674723, 12024.158308918291, 12047.096467764777, 11566.248193867372, 11584.536618535165, 11670.183087183816, 11835.053195744147, 12040.157188996685, 11526.88473115258, 11738.002269807163, 11961.282323060645, 12127.029837613147, 12395.318257495925, 11406.087512999105, 11454.885451145716, 11482.82775758381, 11747.647570572897, 11825.526978920747, 9613.230941524802, 9652.034796520291, 9992.700218993441, 10101.191904647463, 10205.642802501878, 9218.585163667785, 9863.95068024684, 9889.554970026222, 9894.703158905244, 9900.108990190918], "value": 6927.562129756061, "unit": "score"}], "name": "dromaeo_css", "value": 7326.373611753593, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 113.0, 111.0, 111.0, 111.0, 111.0, 113.0, 111.0, 112.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 149.0, 137.0, 195.0, 118.0, 135.0, 136.0, 117.0, 161.0, 120.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 183.0, 184.0, 183.0, 181.0, 182.0, 180.0, 181.0, 184.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 56.0, 129.0, 71.0, 85.0, 47.0, 76.0, 129.0, 79.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 101.0, 136.0, 78.0, 79.0, 77.0, 125.0, 115.0, 114.0, 100.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 91.0, 87.0, 90.0, 86.0, 90.0, 87.0, 89.0, 85.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 86.0, 83.0, 85.0, 84.0, 85.0, 86.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 56.0, 56.0, 57.0, 56.0, 116.0, 55.0, 56.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 68.0, 68.0, 78.0, 69.0, 65.0, 67.0, 69.0, 67.0, 66.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 82.0, 85.0, 86.0, 96.0, 106.0, 104.0, 76.0, 99.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 143.0, 133.0, 137.0, 135.0, 135.0, 136.0, 139.0, 234.0, 145.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 159.0, 163.0, 161.0, 161.0, 164.0, 164.0, 164.0, 160.0, 163.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 65.0, 64.0, 62.0, 65.0, 64.0, 63.0, 64.0], "value": 64.5, "unit": "score"}], "name": "kraken", "value": 1482.6999999999998, "alertThreshold": 2.0}]} 07:52:50 INFO - Return code: 0 07:52:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:52:50 ERROR - # TBPL SUCCESS # 07:52:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:52:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:52:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:52:50 INFO - 2016-05-17 07:52:50.760 screenresolution[1677:9589] starting screenresolution argv=screenresolution get 07:52:50 INFO - 2016-05-17 07:52:50.762 screenresolution[1677:9589] Display 0: 1600x1200x32@60 07:52:50 INFO - 2016-05-17 07:52:50.768 screenresolution[1678:9592] starting screenresolution argv=screenresolution list 07:52:50 INFO - Available Modes on Display 0 07:52:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:52:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:52:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:52:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:52:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:52:50 INFO - Intel Iris: 07:52:50 INFO - Chipset Model: Intel Iris 07:52:50 INFO - Type: GPU 07:52:50 INFO - Bus: Built-In 07:52:50 INFO - VRAM (Dynamic, Max): 1536 MB 07:52:50 INFO - Vendor: Intel (0x8086) 07:52:50 INFO - Device ID: 0x0a2e 07:52:50 INFO - Revision ID: 0x0009 07:52:50 INFO - Displays: 07:52:50 INFO - Display: 07:52:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:52:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:52:50 INFO - Display Serial Number: 1600x1200 60 07:52:50 INFO - Main Display: Yes 07:52:51 INFO - Mirror: Off 07:52:51 INFO - Online: Yes 07:52:51 INFO - Rotation: Supported 07:52:51 INFO - Return code: 0 07:52:51 INFO - Running post-action listener: _resource_record_post_action 07:52:51 INFO - ##### 07:52:51 INFO - ##### Finished run-tests step (success) 07:52:51 INFO - ##### 07:52:51 INFO - Running post-run listener: _resource_record_post_run 07:52:51 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36677120; Write bytes: 694626304; Read time: 247; Write time: 1885 07:52:51 INFO - TinderboxPrint: CPU usage
53.6% 07:52:51 INFO - TinderboxPrint: I/O read bytes / time
36,677,120 / 247 07:52:51 INFO - TinderboxPrint: I/O write bytes / time
694,626,304 / 1,885 07:52:51 INFO - TinderboxPrint: CPU idle
2,251.0 (46.4%) 07:52:51 INFO - TinderboxPrint: CPU system
960.6 (19.8%) 07:52:51 INFO - TinderboxPrint: CPU user
1,638.1 (33.8%) 07:52:51 INFO - TinderboxPrint: Swap in / out
431,390,720 / 0 07:52:51 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 393183744; Write bytes: 388048384; Read time: 30064; Write time: 788 07:52:51 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34862592; Write bytes: 295539200; Read time: 230; Write time: 1061 07:52:51 INFO - Running post-run listener: _upload_blobber_files 07:52:51 INFO - Blob upload gear active. 07:52:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:52:51 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 07:52:51 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'] 07:52:51 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 07:52:51 INFO - (blobuploader) - INFO - Open directory for files ... 07:52:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:52:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:52:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:52 INFO - (blobuploader) - INFO - Done attempting. 07:52:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:52:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:52:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:53 INFO - (blobuploader) - INFO - Done attempting. 07:52:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:52:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:52:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:56 INFO - (blobuploader) - INFO - Done attempting. 07:52:56 INFO - (blobuploader) - INFO - Iteration through files over. 07:52:56 INFO - Return code: 0 07:52:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:52:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:52:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/06bc02cc2428e6d9aabcee5d02ceea1bdd48dfe557969d2613ff16a8a599767c7ce3d3a6c5b225f064af5f4f03134194064dc9c093f0c413f477a729bd00062c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef45195fb6eca2376ac680d7bc5c5c5b5d0df894d6d8dfac2416a6577a463b48e525c027d2f94ec5d89b8e4b1049b824456518b789142723640301933d3c701c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1e1e6b8e7a6a62e3e107e94f42c721974467b4e18a18be864ade60000f80deefb637c4fe0dd12e7dc4489bc0d4d958d87beeda44fb3f7380b238992409f5f077"} 07:52:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:52:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:52:56 INFO - Contents: 07:52:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/06bc02cc2428e6d9aabcee5d02ceea1bdd48dfe557969d2613ff16a8a599767c7ce3d3a6c5b225f064af5f4f03134194064dc9c093f0c413f477a729bd00062c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef45195fb6eca2376ac680d7bc5c5c5b5d0df894d6d8dfac2416a6577a463b48e525c027d2f94ec5d89b8e4b1049b824456518b789142723640301933d3c701c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1e1e6b8e7a6a62e3e107e94f42c721974467b4e18a18be864ade60000f80deefb637c4fe0dd12e7dc4489bc0d4d958d87beeda44fb3f7380b238992409f5f077"} 07:52:56 INFO - Running post-run listener: copy_logs_to_upload_dir 07:52:56 INFO - Copying logs to upload dir... 07:52:56 INFO - mkdir: /builds/slave/test/build/upload/logs 07:52:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.076431 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-17 07:52:56.619693) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 07:52:56.623599) ========= 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.X71LfVeAbc/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.oQIPYiLqSR/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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/06bc02cc2428e6d9aabcee5d02ceea1bdd48dfe557969d2613ff16a8a599767c7ce3d3a6c5b225f064af5f4f03134194064dc9c093f0c413f477a729bd00062c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef45195fb6eca2376ac680d7bc5c5c5b5d0df894d6d8dfac2416a6577a463b48e525c027d2f94ec5d89b8e4b1049b824456518b789142723640301933d3c701c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1e1e6b8e7a6a62e3e107e94f42c721974467b4e18a18be864ade60000f80deefb637c4fe0dd12e7dc4489bc0d4d958d87beeda44fb3f7380b238992409f5f077"} build_url:https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011016 build_url: 'https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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/06bc02cc2428e6d9aabcee5d02ceea1bdd48dfe557969d2613ff16a8a599767c7ce3d3a6c5b225f064af5f4f03134194064dc9c093f0c413f477a729bd00062c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef45195fb6eca2376ac680d7bc5c5c5b5d0df894d6d8dfac2416a6577a463b48e525c027d2f94ec5d89b8e4b1049b824456518b789142723640301933d3c701c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1e1e6b8e7a6a62e3e107e94f42c721974467b4e18a18be864ade60000f80deefb637c4fe0dd12e7dc4489bc0d4d958d87beeda44fb3f7380b238992409f5f077"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 07:52:56.654090) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:52:56.654420) ========= 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.X71LfVeAbc/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.oQIPYiLqSR/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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.005040 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:52:56.702973) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 07:52:56.703300) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 07:52:56.703625) ========= ========= Total master_lag: 0.20 =========