builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0257 starttime: 1463189192.65 results: success (0) buildid: 20160513164637 builduid: 3eb6a8b01f64475dbad2c6f598f67f32 revision: 93d60e9db618bd817bdba3fec6151d701bd5627a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:32.645266) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:32.645628) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:32.645912) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:32.667379) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:32.667679) ========= 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.eClUXEWP97/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.hCjMaiHxGP/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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-13 18:26:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.62M=0.001s 2016-05-13 18:26:32 (9.62 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134769 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:32.945472) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:32.945761) ========= 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.eClUXEWP97/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.hCjMaiHxGP/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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.026529 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:32.988041) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:32.988344) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 93d60e9db618bd817bdba3fec6151d701bd5627a --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 93d60e9db618bd817bdba3fec6151d701bd5627a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eClUXEWP97/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.hCjMaiHxGP/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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-13 18:26:33,068 truncating revision to first 12 chars 2016-05-13 18:26:33,068 Setting DEBUG logging. 2016-05-13 18:26:33,068 attempt 1/10 2016-05-13 18:26:33,068 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/93d60e9db618?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 18:26:33,715 unpacking tar archive at: fx-team-93d60e9db618/testing/mozharness/ program finished with exit code 0 elapsedTime=0.927156 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:33.932483) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:33.932846) ========= script_repo_revision: 93d60e9db618bd817bdba3fec6151d701bd5627a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:33.933233) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:33.933556) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 18:26:33.948844) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-13 18:26:33.949221) ========= /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.eClUXEWP97/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.hCjMaiHxGP/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:26:34 INFO - MultiFileLogger online at 20160513 18:26:34 in /builds/slave/test 18:26:34 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 18:26:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:26:34 INFO - {'append_to_log': False, 18:26:34 INFO - 'base_work_dir': '/builds/slave/test', 18:26:34 INFO - 'blob_upload_branch': 'Fx-Team', 18:26:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:26:34 INFO - 'branch': 'Fx-Team', 18:26:34 INFO - 'buildbot_json_path': 'buildprops.json', 18:26:34 INFO - 'config_files': ('talos/mac_config.py',), 18:26:34 INFO - 'default_actions': ('clobber', 18:26:34 INFO - 'read-buildbot-config', 18:26:34 INFO - 'download-and-extract', 18:26:34 INFO - 'populate-webroot', 18:26:34 INFO - 'create-virtualenv', 18:26:34 INFO - 'install', 18:26:34 INFO - 'run-tests'), 18:26:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:26:34 INFO - 'download_minidump_stackwalk': True, 18:26:34 INFO - 'download_symbols': 'ondemand', 18:26:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:26:34 INFO - 'tooltool.py': '/tools/tooltool.py', 18:26:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:26:34 INFO - '/tools/misc-python/virtualenv.py')}, 18:26:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:26:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:26:34 INFO - 'installer_path': 'installer.exe', 18:26:34 INFO - 'log_level': 'info', 18:26:34 INFO - 'log_name': 'talos', 18:26:34 INFO - 'log_to_console': True, 18:26:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:26:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:26:34 INFO - 'opt_config_files': (), 18:26:34 INFO - 'pip_index': False, 18:26:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:26:34 INFO - 'cmd': ('bash', 18:26:34 INFO - '-c', 18:26:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:26:34 INFO - 'enabled': True, 18:26:34 INFO - 'halt_on_failure': False, 18:26:34 INFO - 'name': 'check_screen_resolution'},), 18:26:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:26:34 INFO - 'cmd': ('bash', 18:26:34 INFO - '-c', 18:26:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:26:34 INFO - 'enabled': True, 18:26:34 INFO - 'halt_on_failure': False, 18:26:34 INFO - 'name': 'check_screen_resolution'},), 18:26:34 INFO - 'run_cmd_checks_enabled': True, 18:26:34 INFO - 'sps_profile': False, 18:26:34 INFO - 'sps_profile_interval': 0, 18:26:34 INFO - 'suite': 'dromaeojs', 18:26:34 INFO - 'system_bits': '32', 18:26:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:26:34 INFO - 'title': 't-yosemite-r7-0257', 18:26:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:26:34 INFO - 'use_talos_json': True, 18:26:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:26:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:26:34 INFO - 'work_dir': 'build'} 18:26:34 INFO - ##### 18:26:34 INFO - ##### Running clobber step. 18:26:34 INFO - ##### 18:26:34 INFO - Running pre-action listener: _resource_record_pre_action 18:26:34 INFO - Running main action method: clobber 18:26:34 INFO - rmtree: /builds/slave/test/build 18:26:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:26:36 INFO - Running post-action listener: _resource_record_post_action 18:26:36 INFO - ##### 18:26:36 INFO - ##### Finished clobber step (success) 18:26:36 INFO - ##### 18:26:36 INFO - ##### 18:26:36 INFO - ##### Running read-buildbot-config step. 18:26:36 INFO - ##### 18:26:36 INFO - Running pre-action listener: _resource_record_pre_action 18:26:36 INFO - Running main action method: read_buildbot_config 18:26:36 INFO - Using buildbot properties: 18:26:36 INFO - { 18:26:36 INFO - "project": "", 18:26:36 INFO - "product": "firefox", 18:26:36 INFO - "script_repo_revision": "production", 18:26:36 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:26:36 INFO - "repository": "", 18:26:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:26:36 INFO - "buildid": "20160513164637", 18:26:36 INFO - "pgo_build": "False", 18:26:36 INFO - "basedir": "/builds/slave/test", 18:26:36 INFO - "buildnumber": 702, 18:26:36 INFO - "slavename": "t-yosemite-r7-0257", 18:26:36 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:26:36 INFO - "platform": "yosemite_r7", 18:26:36 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:26:36 INFO - "branch": "fx-team", 18:26:36 INFO - "repo_path": "integration/fx-team", 18:26:36 INFO - "revision": "93d60e9db618bd817bdba3fec6151d701bd5627a", 18:26:36 INFO - "stage_platform": "macosx64", 18:26:36 INFO - "builduid": "3eb6a8b01f64475dbad2c6f598f67f32", 18:26:36 INFO - "slavebuilddir": "test" 18:26:36 INFO - } 18:26:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:26:36 INFO - Running post-action listener: _resource_record_post_action 18:26:36 INFO - ##### 18:26:36 INFO - ##### Finished read-buildbot-config step (success) 18:26:36 INFO - ##### 18:26:36 INFO - ##### 18:26:36 INFO - ##### Running download-and-extract step. 18:26:36 INFO - ##### 18:26:36 INFO - Running pre-action listener: _resource_record_pre_action 18:26:36 INFO - Running main action method: download_and_extract 18:26:36 INFO - mkdir: /builds/slave/test/build/tests 18:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:36 INFO - https://queue.taskcluster.net/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:26:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:26:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:26:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/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 18:26:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/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 18:26:38 INFO - Downloaded 1199 bytes. 18:26:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:26:38 INFO - Using the following test package requirements: 18:26:38 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:26:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:26:38 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:26:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:26:38 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:26:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:26:38 INFO - u'jsshell-mac.zip'], 18:26:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:26:38 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:26:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:26:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:26:38 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:26:38 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:26:38 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:26:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:26:38 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:26:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:26:38 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:26:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:38 INFO - https://queue.taskcluster.net/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:26:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:26:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:26:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/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 18:26:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/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 18:26:42 INFO - Downloaded 18266818 bytes. 18:26:42 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'] 18:26:42 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 18:26:45 INFO - Return code: 0 18:26:45 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 18:26:45 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')]} 18:26:45 INFO - https://queue.taskcluster.net/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:26:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:26:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:26:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/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 18:26:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/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 18:26:46 INFO - Downloaded 18266818 bytes. 18:26:46 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'] 18:26:46 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 18:26:50 INFO - Return code: 0 18:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:50 INFO - https://queue.taskcluster.net/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:26:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:26:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:26:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/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 18:26:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/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 18:26:51 INFO - Downloaded 10840194 bytes. 18:26:51 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'] 18:26:51 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 18:26:51 INFO - Return code: 0 18:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:51 INFO - https://queue.taskcluster.net/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:26:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:26:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:26:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:26:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/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 18:26:54 INFO - Downloaded 110011920 bytes. 18:26:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:26:54 INFO - mkdir: /builds/slave/test/properties 18:26:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:26:54 INFO - Writing to file /builds/slave/test/properties/build_url 18:26:54 INFO - Contents: 18:26:54 INFO - build_url:https://queue.taskcluster.net/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:26:56 INFO - Running post-action listener: _resource_record_post_action 18:26:56 INFO - Running post-action listener: set_extra_try_arguments 18:26:56 INFO - ##### 18:26:56 INFO - ##### Finished download-and-extract step (success) 18:26:56 INFO - ##### 18:26:56 INFO - ##### 18:26:56 INFO - ##### Running populate-webroot step. 18:26:56 INFO - ##### 18:26:56 INFO - Running pre-action listener: _resource_record_pre_action 18:26:56 INFO - Running main action method: populate_webroot 18:26:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:26:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:26:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:26:56 INFO - '--tppagecycles', 18:26:56 INFO - '7'], 18:26:56 INFO - 'tests': ['tsvgm']}}, 18:26:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:26:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:26:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:26:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:26:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:26:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:26:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:56 INFO - 'talos_options': ['--disable-e10s'], 18:26:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:56 INFO - 'talos_options': ['--disable-e10s'], 18:26:56 INFO - 'tests': ['damp', 'tps']}, 18:26:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:56 INFO - 'tests': ['damp', 'tps']}, 18:26:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:26:56 INFO - 'tests': ['dromaeo_dom']}, 18:26:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:26:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:26:56 INFO - 'tests': ['a11yr', 18:26:56 INFO - 'ts_paint', 18:26:56 INFO - 'tpaint', 18:26:56 INFO - 'sessionrestore', 18:26:56 INFO - 'sessionrestore_no_auto_restore', 18:26:56 INFO - 'tabpaint']}, 18:26:56 INFO - 'other-e10s': {'tests': ['a11yr', 18:26:56 INFO - 'ts_paint', 18:26:56 INFO - 'tpaint', 18:26:56 INFO - 'sessionrestore', 18:26:56 INFO - 'sessionrestore_no_auto_restore', 18:26:56 INFO - 'tabpaint']}, 18:26:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:26:56 INFO - 'ts_paint', 18:26:56 INFO - 'tpaint', 18:26:56 INFO - 'sessionrestore', 18:26:56 INFO - 'sessionrestore_no_auto_restore', 18:26:56 INFO - 'tabpaint']}, 18:26:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:26:56 INFO - 'ts_paint', 18:26:56 INFO - 'tpaint', 18:26:56 INFO - 'sessionrestore', 18:26:56 INFO - 'sessionrestore_no_auto_restore', 18:26:56 INFO - 'tabpaint']}, 18:26:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:26:56 INFO - 'tests': ['a11yr', 18:26:56 INFO - 'ts_paint', 18:26:56 INFO - 'tpaint', 18:26:56 INFO - 'sessionrestore', 18:26:56 INFO - 'sessionrestore_no_auto_restore', 18:26:56 INFO - 'tabpaint']}, 18:26:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:26:56 INFO - 'tests': ['a11yr', 18:26:56 INFO - 'ts_paint', 18:26:56 INFO - 'tpaint', 18:26:56 INFO - 'sessionrestore', 18:26:56 INFO - 'sessionrestore_no_auto_restore', 18:26:56 INFO - 'tabpaint']}, 18:26:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:26:56 INFO - 'tests': ['tsvgx', 18:26:56 INFO - 'tsvgr_opacity', 18:26:56 INFO - 'tart', 18:26:56 INFO - 'tscrollx', 18:26:56 INFO - 'cart']}, 18:26:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:26:56 INFO - 'tsvgr_opacity', 18:26:56 INFO - 'tart', 18:26:56 INFO - 'tscrollx', 18:26:56 INFO - 'cart']}, 18:26:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:56 INFO - 'talos_options': ['--disable-e10s'], 18:26:56 INFO - 'tests': ['tp5o']}, 18:26:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:56 INFO - 'tests': ['tp5o']}, 18:26:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:56 INFO - 'talos_options': ['--disable-e10s', 18:26:56 INFO - '--xperf_path', 18:26:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:56 INFO - 'tests': ['tp5n']}, 18:26:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:56 INFO - 'talos_options': ['--xperf_path', 18:26:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:56 INFO - 'tests': ['tp5n']}}, 18:26:56 INFO - 'talos.zip': {'path': '', 18:26:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:26:56 INFO - Running post-action listener: _resource_record_post_action 18:26:56 INFO - ##### 18:26:56 INFO - ##### Finished populate-webroot step (success) 18:26:56 INFO - ##### 18:26:56 INFO - ##### 18:26:56 INFO - ##### Running create-virtualenv step. 18:26:56 INFO - ##### 18:26:56 INFO - Running pre-action listener: _resource_record_pre_action 18:26:56 INFO - Running main action method: create_virtualenv 18:26:56 INFO - Creating virtualenv /builds/slave/test/build/venv 18:26:56 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 18:26:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:26:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:26:56 INFO - Using real prefix '/tools/python27' 18:26:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:26:57 INFO - Installing distribute.............................................................................................................................................................................................done. 18:26:59 INFO - Installing pip.................done. 18:26:59 INFO - Return code: 0 18:26:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:26: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')]} 18:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26: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')]} 18:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:59 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 0x101c52a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff518cb1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101af6d30>, '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 0x10191adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aedb20>, '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.eClUXEWP97/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.hCjMaiHxGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:26:59 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 18:26:59 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 18:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eClUXEWP97/Render', 18:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:59 INFO - 'HOME': '/Users/cltbld', 18:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:59 INFO - 'LOGNAME': 'cltbld', 18:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:59 INFO - 'MOZ_NO_REMOTE': '1', 18:26:59 INFO - 'NO_EM_RESTART': '1', 18:26:59 INFO - 'PAGER': '/bin/cat', 18:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:59 INFO - 'PWD': '/builds/slave/test', 18:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:59 INFO - 'SHELL': '/bin/bash', 18:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCjMaiHxGP/Listeners', 18:26:59 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:59 INFO - 'USER': 'cltbld', 18:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:59 INFO - 'XPC_FLAGS': '0x0', 18:26:59 INFO - 'XPC_SERVICE_NAME': '0', 18:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:00 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 18:27:00 INFO - Cleaning up... 18:27:00 INFO - Return code: 0 18:27:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101c52a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff518cb1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101af6d30>, '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 0x10191adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aedb20>, '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.eClUXEWP97/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.hCjMaiHxGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:27:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:27:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eClUXEWP97/Render', 18:27:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:00 INFO - 'HOME': '/Users/cltbld', 18:27:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:00 INFO - 'LOGNAME': 'cltbld', 18:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:00 INFO - 'MOZ_NO_REMOTE': '1', 18:27:00 INFO - 'NO_EM_RESTART': '1', 18:27:00 INFO - 'PAGER': '/bin/cat', 18:27:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:00 INFO - 'PWD': '/builds/slave/test', 18:27:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:00 INFO - 'SHELL': '/bin/bash', 18:27:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCjMaiHxGP/Listeners', 18:27:00 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:27:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:00 INFO - 'USER': 'cltbld', 18:27:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:00 INFO - 'XPC_FLAGS': '0x0', 18:27:00 INFO - 'XPC_SERVICE_NAME': '0', 18:27:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:00 INFO - Downloading/unpacking psutil>=3.1.1 18:27:00 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 18:27:00 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 18:27:00 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 18:27:00 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 18:27:00 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 18:27:00 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 18:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:27:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:03 INFO - Installing collected packages: psutil 18:27:03 INFO - Running setup.py install for psutil 18:27:03 INFO - building 'psutil._psutil_osx' extension 18:27:03 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 18:27:04 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 18:27:04 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 18:27:04 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 18:27:04 INFO - building 'psutil._psutil_posix' extension 18:27:04 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 18:27:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:27:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:27:04 INFO - ^ 18:27:04 INFO - 1 warning generated. 18:27:04 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 18:27:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:04 INFO - Successfully installed psutil 18:27:04 INFO - Cleaning up... 18:27:04 INFO - Return code: 0 18:27:04 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 18:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:04 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.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c52a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff518cb1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101af6d30>, '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 0x10191adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aedb20>, '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.eClUXEWP97/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.hCjMaiHxGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:27:04 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.1'] in /builds/slave/test/build 18:27:04 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.1 18:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eClUXEWP97/Render', 18:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:04 INFO - 'HOME': '/Users/cltbld', 18:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:04 INFO - 'LOGNAME': 'cltbld', 18:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:04 INFO - 'MOZ_NO_REMOTE': '1', 18:27:04 INFO - 'NO_EM_RESTART': '1', 18:27:04 INFO - 'PAGER': '/bin/cat', 18:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:04 INFO - 'PWD': '/builds/slave/test', 18:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:04 INFO - 'SHELL': '/bin/bash', 18:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCjMaiHxGP/Listeners', 18:27:04 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:04 INFO - 'USER': 'cltbld', 18:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:04 INFO - 'XPC_FLAGS': '0x0', 18:27:04 INFO - 'XPC_SERVICE_NAME': '0', 18:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:04 INFO - Downloading/unpacking mozsystemmonitor==0.1 18:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:07 INFO - Downloading mozsystemmonitor-0.1.tar.gz 18:27:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:27:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 18:27:07 INFO - Installing collected packages: mozsystemmonitor 18:27:07 INFO - Running setup.py install for mozsystemmonitor 18:27:07 INFO - Successfully installed mozsystemmonitor 18:27:07 INFO - Cleaning up... 18:27:07 INFO - Return code: 0 18:27:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:27: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')]} 18:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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')]} 18:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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', '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 0x101c52a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff518cb1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101af6d30>, '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 0x10191adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aedb20>, '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.eClUXEWP97/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.hCjMaiHxGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:27: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:27: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 blobuploader==1.2.4 18:27:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eClUXEWP97/Render', 18:27:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:07 INFO - 'HOME': '/Users/cltbld', 18:27:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:07 INFO - 'LOGNAME': 'cltbld', 18:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:07 INFO - 'MOZ_NO_REMOTE': '1', 18:27:07 INFO - 'NO_EM_RESTART': '1', 18:27:07 INFO - 'PAGER': '/bin/cat', 18:27:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:07 INFO - 'PWD': '/builds/slave/test', 18:27:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:07 INFO - 'SHELL': '/bin/bash', 18:27:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCjMaiHxGP/Listeners', 18:27:07 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:27:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:07 INFO - 'USER': 'cltbld', 18:27:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:07 INFO - 'XPC_FLAGS': '0x0', 18:27:07 INFO - 'XPC_SERVICE_NAME': '0', 18:27:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:07 INFO - Downloading/unpacking blobuploader==1.2.4 18:27:07 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 18:27:07 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 18:27:07 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 18:27:07 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 18:27:07 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 18:27:07 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 18:27:10 INFO - Downloading blobuploader-1.2.4.tar.gz 18:27:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:27:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:27:10 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 18:27:10 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 18:27:10 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 18:27:10 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 18:27:10 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 18:27:10 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 18:27:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:27:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:11 INFO - Downloading docopt-0.6.1.tar.gz 18:27:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:27:11 INFO - Installing collected packages: blobuploader, requests, docopt 18:27:11 INFO - Running setup.py install for blobuploader 18:27:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:27:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:27:11 INFO - Running setup.py install for requests 18:27:12 INFO - Running setup.py install for docopt 18:27:12 INFO - Successfully installed blobuploader requests docopt 18:27:12 INFO - Cleaning up... 18:27:12 INFO - Return code: 0 18:27:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27: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')]} 18:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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')]} 18:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:12 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 0x101c52a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff518cb1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101af6d30>, '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 0x10191adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aedb20>, '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.eClUXEWP97/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.hCjMaiHxGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:27:12 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 18:27:12 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 18:27:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eClUXEWP97/Render', 18:27:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:12 INFO - 'HOME': '/Users/cltbld', 18:27:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:12 INFO - 'LOGNAME': 'cltbld', 18:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:12 INFO - 'MOZ_NO_REMOTE': '1', 18:27:12 INFO - 'NO_EM_RESTART': '1', 18:27:12 INFO - 'PAGER': '/bin/cat', 18:27:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:12 INFO - 'PWD': '/builds/slave/test', 18:27:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:12 INFO - 'SHELL': '/bin/bash', 18:27:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCjMaiHxGP/Listeners', 18:27:12 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:27:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:12 INFO - 'USER': 'cltbld', 18:27:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:12 INFO - 'XPC_FLAGS': '0x0', 18:27:12 INFO - 'XPC_SERVICE_NAME': '0', 18:27:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:27:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-ySLh2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:27:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-ouhLmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:27:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-QwUMBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:27:13 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-rW4L9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:27:13 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-xlb_Fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:27:13 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-9VAlpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:27:13 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-ShEobR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:27:13 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-G5puWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:27:13 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-2bijPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:27:13 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-bEmCTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:27:13 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-b2CKGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:27:13 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-fSgEEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:27:13 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Ub7F2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:27:14 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-UPLfun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:14 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-rfBYZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:27:14 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-qLkMtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:27:14 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-2dFAPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:27:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:27:14 INFO - Running setup.py install for manifestparser 18:27:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:27:14 INFO - Running setup.py install for mozcrash 18:27:14 INFO - Running setup.py install for mozdebug 18:27:14 INFO - Running setup.py install for mozdevice 18:27:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:27:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:27:15 INFO - Running setup.py install for mozfile 18:27:15 INFO - Running setup.py install for mozhttpd 18:27:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:27:15 INFO - Running setup.py install for mozinfo 18:27:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:27:15 INFO - Running setup.py install for mozInstall 18:27:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:27:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:27:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:27:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:27:15 INFO - Running setup.py install for mozleak 18:27:15 INFO - Running setup.py install for mozlog 18:27:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:27:15 INFO - Running setup.py install for moznetwork 18:27:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:27:16 INFO - Running setup.py install for mozprocess 18:27:16 INFO - Running setup.py install for mozprofile 18:27:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:27:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:27:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:27:16 INFO - Running setup.py install for mozrunner 18:27:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:27:16 INFO - Running setup.py install for mozscreenshot 18:27:16 INFO - Running setup.py install for moztest 18:27:16 INFO - Running setup.py install for mozversion 18:27:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:27:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:27:17 INFO - Cleaning up... 18:27:17 INFO - Return code: 0 18:27:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:17 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 0x101c52a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff518cb1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101af6d30>, '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 0x10191adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aedb20>, '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.eClUXEWP97/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.hCjMaiHxGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:27:17 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 18:27:17 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 18:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eClUXEWP97/Render', 18:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:17 INFO - 'HOME': '/Users/cltbld', 18:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:17 INFO - 'LOGNAME': 'cltbld', 18:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:17 INFO - 'MOZ_NO_REMOTE': '1', 18:27:17 INFO - 'NO_EM_RESTART': '1', 18:27:17 INFO - 'PAGER': '/bin/cat', 18:27:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:17 INFO - 'PWD': '/builds/slave/test', 18:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:17 INFO - 'SHELL': '/bin/bash', 18:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCjMaiHxGP/Listeners', 18:27:17 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:17 INFO - 'USER': 'cltbld', 18:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:17 INFO - 'XPC_FLAGS': '0x0', 18:27:17 INFO - 'XPC_SERVICE_NAME': '0', 18:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:27:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-xssIsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:27:17 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)) 18:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:27:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-9O8TLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:27:17 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)) 18:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:27:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-b68qXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:27:17 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)) 18:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:27:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-UrfjmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:27:17 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)) 18:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:27:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-ceHrk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:27:17 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)) 18:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:27:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-XEKZOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:27:17 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)) 18:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:27:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-0dwSZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:27:18 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)) 18:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:27:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-hrHMks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:27:18 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)) 18:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:27:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-KXJpn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:27:18 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)) 18:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:27:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-WmeBmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:27:18 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)) 18:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:27:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-jgVmaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:27:18 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)) 18:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:27:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-T2DGLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:27:18 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)) 18:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:27:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-MZOKCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:27:18 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)) 18:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:27:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-zhYvKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:27:18 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)) 18:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-QWgQFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:18 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)) 18:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:27:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-15HwnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:27:18 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)) 18:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:27:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-dpJZkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:27:19 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)) 18:27:19 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)) 18:27:19 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)) 18:27:19 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)) 18:27:19 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)) 18:27:19 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)) 18:27:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:21 INFO - Downloading blessings-1.6.tar.gz 18:27:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:27:21 INFO - Installing collected packages: blessings 18:27:21 INFO - Running setup.py install for blessings 18:27:21 INFO - Successfully installed blessings 18:27:21 INFO - Cleaning up... 18:27:21 INFO - Return code: 0 18:27:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:27:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:27:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:27:22 INFO - Reading from file tmpfile_stdout 18:27:22 INFO - Current package versions: 18:27:22 INFO - blessings == 1.6 18:27:22 INFO - blobuploader == 1.2.4 18:27:22 INFO - docopt == 0.6.1 18:27:22 INFO - manifestparser == 1.1 18:27:22 INFO - mozInstall == 1.12 18:27:22 INFO - mozcrash == 0.17 18:27:22 INFO - mozdebug == 0.1 18:27:22 INFO - mozdevice == 0.48 18:27:22 INFO - mozfile == 1.2 18:27:22 INFO - mozhttpd == 0.7 18:27:22 INFO - mozinfo == 0.9 18:27:22 INFO - mozleak == 0.1 18:27:22 INFO - mozlog == 3.2 18:27:22 INFO - moznetwork == 0.27 18:27:22 INFO - mozprocess == 0.22 18:27:22 INFO - mozprofile == 0.28 18:27:22 INFO - mozrunner == 6.11 18:27:22 INFO - mozscreenshot == 0.1 18:27:22 INFO - mozsystemmonitor == 0.1 18:27:22 INFO - moztest == 0.7 18:27:22 INFO - mozversion == 1.4 18:27:22 INFO - psutil == 3.1.1 18:27:22 INFO - requests == 1.2.3 18:27:22 INFO - wsgiref == 0.1.2 18:27:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27:22 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')]} 18:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:22 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')]} 18:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:22 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 0x101c52a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff518cb1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101af6d30>, '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 0x10191adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aedb20>, '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.eClUXEWP97/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.hCjMaiHxGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:27:22 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 18:27:22 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 18:27:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eClUXEWP97/Render', 18:27:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:22 INFO - 'HOME': '/Users/cltbld', 18:27:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:22 INFO - 'LOGNAME': 'cltbld', 18:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:22 INFO - 'MOZ_NO_REMOTE': '1', 18:27:22 INFO - 'NO_EM_RESTART': '1', 18:27:22 INFO - 'PAGER': '/bin/cat', 18:27:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:22 INFO - 'PWD': '/builds/slave/test', 18:27:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:22 INFO - 'SHELL': '/bin/bash', 18:27:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCjMaiHxGP/Listeners', 18:27:22 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:27:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:22 INFO - 'USER': 'cltbld', 18:27:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:22 INFO - 'XPC_FLAGS': '0x0', 18:27:22 INFO - 'XPC_SERVICE_NAME': '0', 18:27:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:22 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)) 18:27:22 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)) 18:27:22 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)) 18:27:22 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)) 18:27:22 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)) 18:27:22 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)) 18:27:22 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)) 18:27:22 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)) 18:27:22 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)) 18:27:22 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)) 18:27:22 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)) 18:27:22 INFO - Cleaning up... 18:27:22 INFO - Return code: 0 18:27:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:27:22 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')]} 18:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:22 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')]} 18:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:22 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 0x101c52a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff518cb1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101af6d30>, '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 0x10191adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aedb20>, '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.eClUXEWP97/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.hCjMaiHxGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:27:22 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 18:27:22 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 18:27:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eClUXEWP97/Render', 18:27:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:22 INFO - 'HOME': '/Users/cltbld', 18:27:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:22 INFO - 'LOGNAME': 'cltbld', 18:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:22 INFO - 'MOZ_NO_REMOTE': '1', 18:27:22 INFO - 'NO_EM_RESTART': '1', 18:27:22 INFO - 'PAGER': '/bin/cat', 18:27:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:22 INFO - 'PWD': '/builds/slave/test', 18:27:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:22 INFO - 'SHELL': '/bin/bash', 18:27:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCjMaiHxGP/Listeners', 18:27:22 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:27:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:22 INFO - 'USER': 'cltbld', 18:27:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:22 INFO - 'XPC_FLAGS': '0x0', 18:27:22 INFO - 'XPC_SERVICE_NAME': '0', 18:27:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:22 INFO - Downloading/unpacking jsonschema 18:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:27:25 INFO - Downloading/unpacking functools32 (from jsonschema) 18:27:25 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 18:27:25 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 18:27:25 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 18:27:25 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 18:27:25 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 18:27:25 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 18:27:25 INFO - Downloading functools32-3.2.3-2.tar.gz 18:27:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:27:25 INFO - warning: no files found matching '*.txt' 18:27:25 INFO - no previously-included directories found matching 'build' 18:27:25 INFO - no previously-included directories found matching 'dist' 18:27:25 INFO - no previously-included directories found matching '.git*' 18:27:25 INFO - Installing collected packages: jsonschema, functools32 18:27:25 INFO - Running setup.py install for functools32 18:27:25 INFO - warning: no files found matching '*.txt' 18:27:25 INFO - no previously-included directories found matching 'build' 18:27:25 INFO - no previously-included directories found matching 'dist' 18:27:25 INFO - no previously-included directories found matching '.git*' 18:27:25 INFO - Successfully installed jsonschema functools32 18:27:25 INFO - Cleaning up... 18:27:25 INFO - Return code: 0 18:27:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:27:25 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')]} 18:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:25 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')]} 18:27:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:25 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 0x101c52a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c58570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff518cb1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101af6d30>, '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 0x10191adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aedb20>, '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.eClUXEWP97/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.hCjMaiHxGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:27:25 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 18:27:25 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 18:27:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eClUXEWP97/Render', 18:27:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:25 INFO - 'HOME': '/Users/cltbld', 18:27:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:25 INFO - 'LOGNAME': 'cltbld', 18:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:25 INFO - 'MOZ_NO_REMOTE': '1', 18:27:25 INFO - 'NO_EM_RESTART': '1', 18:27:25 INFO - 'PAGER': '/bin/cat', 18:27:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:25 INFO - 'PWD': '/builds/slave/test', 18:27:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:25 INFO - 'SHELL': '/bin/bash', 18:27:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCjMaiHxGP/Listeners', 18:27:25 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:27:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:25 INFO - 'USER': 'cltbld', 18:27:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:25 INFO - 'XPC_FLAGS': '0x0', 18:27:25 INFO - 'XPC_SERVICE_NAME': '0', 18:27:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:26 INFO - Downloading/unpacking flake8 18:27: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 18:27: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 18:27: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 18:27: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 18:27: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 18:27: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 18:27:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:27:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:27:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:27: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 18:27: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 18:27: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 18:27: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 18:27: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 18:27: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 18:27:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:27:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:27:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:27:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:27:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:27:29 INFO - no previously-included directories found matching 'docs/_build' 18:27:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:27:29 INFO - Running setup.py install for pep8 18:27:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:27:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:27:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:27:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:27:29 INFO - no previously-included directories found matching 'docs/_build' 18:27:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:27:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:27:29 INFO - Cleaning up... 18:27:30 INFO - Return code: 0 18:27:30 INFO - Running post-action listener: _resource_record_post_action 18:27:30 INFO - Running post-action listener: _start_resource_monitoring 18:27:30 INFO - Starting resource monitoring. 18:27:30 INFO - ##### 18:27:30 INFO - ##### Finished create-virtualenv step (success) 18:27:30 INFO - ##### 18:27:30 INFO - ##### 18:27:30 INFO - ##### Running install step. 18:27:30 INFO - ##### 18:27:30 INFO - Running pre-action listener: _resource_record_pre_action 18:27:30 INFO - Running main action method: install 18:27:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:27:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:27:30 INFO - Reading from file tmpfile_stdout 18:27:30 INFO - Detecting whether we're running mozinstall >=1.0... 18:27:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:27:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:27:30 INFO - Reading from file tmpfile_stdout 18:27:30 INFO - Output received: 18:27:30 INFO - Usage: mozinstall [options] installer 18:27:30 INFO - Options: 18:27:30 INFO - -h, --help show this help message and exit 18:27:30 INFO - -d DEST, --destination=DEST 18:27:30 INFO - Directory to install application into. [default: 18:27:30 INFO - "/builds/slave/test"] 18:27:30 INFO - --app=APP Application being installed. [default: firefox] 18:27:30 INFO - mkdir: /builds/slave/test/build/application 18:27:30 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'] 18:27:30 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 18:28:03 INFO - Reading from file tmpfile_stdout 18:28:03 INFO - Output received: 18:28:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:28:03 INFO - Running post-action listener: _resource_record_post_action 18:28:03 INFO - ##### 18:28:03 INFO - ##### Finished install step (success) 18:28:03 INFO - ##### 18:28:03 INFO - ##### 18:28:03 INFO - ##### Running run-tests step. 18:28:03 INFO - ##### 18:28:03 INFO - Running pre-action listener: _resource_record_pre_action 18:28:03 INFO - Running main action method: run_tests 18:28:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:28:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:28:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:28:03 INFO - 2016-05-13 18:28:03.995 screenresolution[1661:7589] starting screenresolution argv=screenresolution get 18:28:03 INFO - 2016-05-13 18:28:03.997 screenresolution[1661:7589] Display 0: 1600x1200x32@60 18:28:04 INFO - 2016-05-13 18:28:04.004 screenresolution[1662:7596] starting screenresolution argv=screenresolution list 18:28:04 INFO - Available Modes on Display 0 18:28:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:28:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:28:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:28:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:28:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:28:04 INFO - Intel Iris: 18:28:04 INFO - Chipset Model: Intel Iris 18:28:04 INFO - Type: GPU 18:28:04 INFO - Bus: Built-In 18:28:04 INFO - VRAM (Dynamic, Max): 1536 MB 18:28:04 INFO - Vendor: Intel (0x8086) 18:28:04 INFO - Device ID: 0x0a2e 18:28:04 INFO - Revision ID: 0x0009 18:28:04 INFO - Displays: 18:28:04 INFO - Display: 18:28:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:28:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:28:04 INFO - Display Serial Number: 1600x1200 60 18:28:04 INFO - Main Display: Yes 18:28:04 INFO - Mirror: Off 18:28:04 INFO - Online: Yes 18:28:04 INFO - Rotation: Supported 18:28:04 INFO - Return code: 0 18:28:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:28:04 INFO - Python 2.7.3 18:28:04 INFO - Return code: 0 18:28:04 INFO - grabbing minidump binary from tooltool 18:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:04 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 0x101af6d30>, '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 0x10191adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101aedb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:28:04 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 18:28:04 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 18:28:04 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 18:28:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:28:04 INFO - Return code: 0 18:28:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:28:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:28:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:28:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:28:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:28:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:28:05 INFO - Return code: 0 18:28:05 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/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--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 18:28:05 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/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0257 --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 18:28:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eClUXEWP97/Render', 18:28:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:05 INFO - 'HOME': '/Users/cltbld', 18:28:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:05 INFO - 'LOGNAME': 'cltbld', 18:28:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:28:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:05 INFO - 'MOZ_NO_REMOTE': '1', 18:28:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:28:05 INFO - 'NO_EM_RESTART': '1', 18:28:05 INFO - 'PAGER': '/bin/cat', 18:28:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:05 INFO - 'PWD': '/builds/slave/test', 18:28:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:28:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:05 INFO - 'SHELL': '/bin/bash', 18:28:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCjMaiHxGP/Listeners', 18:28:05 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:28:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:05 INFO - 'USER': 'cltbld', 18:28:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:05 INFO - 'XPC_FLAGS': '0x0', 18:28:05 INFO - 'XPC_SERVICE_NAME': '0', 18:28:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:05 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/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--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 18:28:05 INFO - mozversion application_buildid: 20160513164637 18:28:05 INFO - mozversion application_changeset: 93d60e9db618bd817bdba3fec6151d701bd5627a 18:28:05 INFO - mozversion application_display_name: Nightly 18:28:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:28:05 INFO - mozversion application_name: Firefox 18:28:05 INFO - mozversion application_remotingname: firefox 18:28:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:28:05 INFO - mozversion application_vendor: Mozilla 18:28:05 INFO - mozversion application_version: 49.0a1 18:28:05 INFO - mozversion platform_buildid: 20160513164637 18:28:05 INFO - mozversion platform_changeset: 93d60e9db618bd817bdba3fec6151d701bd5627a 18:28:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:28:05 INFO - mozversion platform_version: 49.0a1 18:28:05 INFO - using testdate: 1463189285 18:28:05 INFO - actual date: 1463189285 18:28:05 INFO - starting webserver on 'localhost:49274' 18:28:05 INFO - SUITE-START | Running 2 tests 18:28:05 INFO - TEST-START | dromaeo_css 18:28:05 INFO - operating with platform_type : mac_ 18:28:05 INFO - Initialising browser for dromaeo_css test... 18:28:05 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpKVAJpa/profile http://localhost:49274/getInfo.html) 18:28:07 INFO - PROCESS | 1680 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 18:28:07 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 18:28:07 INFO - PROCESS | 1680 | colorDepth:24 18:28:07 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 18:28:07 INFO - PROCESS | 1680 | __metrics 18:28:08 INFO - PROCESS | 1680 | 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" 18:28:08 ERROR - PROCESS | 1680 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:28:08 INFO - TEST-INFO | 1680: exit 0 18:28:08 INFO - Browser initialized. 18:28:08 INFO - Running cycle 1/1 for dromaeo_css test... 18:28:08 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpKVAJpa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:28:20 INFO - PROCESS | 1682 | RSS: Main: 238862336 18:28:20 INFO - PROCESS | 1682 | 18:29:11 INFO - PROCESS | 1682 | 1463189351671 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] 18:31:23 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 18:31:24 INFO - PROCESS | 1682 | RSS: Main: 393498624 18:31:24 INFO - PROCESS | 1682 | 18:34:28 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 18:34:28 INFO - PROCESS | 1682 | RSS: Main: 372883456 18:34:28 INFO - PROCESS | 1682 | 18:37:30 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 18:37:31 INFO - PROCESS | 1682 | RSS: Main: 360595456 18:37:31 INFO - PROCESS | 1682 | 18:40:33 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 18:40:34 INFO - PROCESS | 1682 | RSS: Main: 368775168 18:40:34 INFO - PROCESS | 1682 | 18:43:37 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 18:43:38 INFO - PROCESS | 1682 | RSS: Main: 335695872 18:43:38 INFO - PROCESS | 1682 | 18:46:41 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 18:46:41 INFO - PROCESS | 1682 | RSS: Main: 374796288 18:46:41 INFO - PROCESS | 1682 | 18:46:41 INFO - PROCESS | 1682 | __start_tp_report 18:46:41 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 18:46:41 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 18:46:41 INFO - PROCESS | 1682 | |i|pagename|runs| 18:46:41 INFO - PROCESS | 1682 | |0;dojo.html;3245.4320493913565;3451.879184228551;3459.1870910336083;3466.324066806972;3508.491268766029;5567.916481252784;5607.355154157273;5639.520640745542;5663.235463212465;5687.090065589507;4553.2942393928915;4742.928856067162;4759.643026772988;5652.773889044443;5735.225744524488;5033.622478314122;5078.64449488536;5144.0740666679985;5146.228065790124;5277.604179686519;5241.737913104349;5257.684538927677;5263.1578947368425;5269.552088072518;5322.2282768998475;5090.287359769635;5147.382314122329;5147.639665223436;5187.247849061884;5216.66091704038;4059.9797001014804;5166.974165129188;5175.068487672217;5179.559737422323;5184.27420161174;5071.416787069497;5077.746112694387;5108.182690769496;5135.84592462223;5162.509561591681;4843.782029808667;5551.278333816618;5558.333000039981;5588.966041282376;5592.328920529521;6174.135621013062;6234.937650623481;6235.407636274546;6236.594621349643;6257.687115644245;5583.804566840182;6148.907766383508;6199.101130336121;6300.432961033529;6330.303666596671;5747.568932330092;5784.1034639630925;5784.681842498619;5793.188953546507;5824.417558244142;6248.156498872594;6300.684965751786;6320.051992201114;6327.557071005078;6327.936720632827;5945.672987985616;6003.459688628044;6005.459508644243;6075.33171351151;6132.938670613326;5168.61235407346;6111.713288451782;6167.753289868445;6181.96909015452;6187.504999600112;6090.878182436327;6095.25698559494;6101.186783078283;6125.449118208347;6135.07955838608;6173.41352571505;6211.782587609412;6221.440070393686;6225.657588832658;6249.375062493714;4866.46468888422;4877.219644856806;4880.682755620874;4892.804287828518;4914.680545764514;4321.308590625485;4362.236608593484;4413.227685155085;4443.511213766483;4470.374147619271;5732.197492351074;5738.68437235956;5744.080947048452;5762.250907382014;5776;6226.750929962841;6302.684865756693;6303.873922521616;6318.62088274705;6329.7468101275435;5632.267805185382;5677.772889084473;5697.515711164515;5719.370869204384;5760.769569217185;5292.391374991848;5325;5357.223202635674;5370.812021579226;5374.973125134427;6329.461995730322;6331.335209803088;6339.904901426482;6352.745890164341;6358.618482890949;6101.005087479836;6233.065040244;6259.311475737756;6271.153394291895;6277.089821975696;6230.657313847782;6272.8431789203805;6286.402791734778;6308.810735677937;6363.904541431974;5453.263809385682;5834.27071616048;6140.017597184429;6151.630902145886;6163.075538669235;5144.6913185209005;6115.571909966259;6217.880781459381;6258.968705156445;6269.7178626962805;4916.237983112624;5987.131865879336;6012.729427175875;6024.728887199999;6028.57799953999;4970.478099799469;6057.757689692363;6063.151158837678;6081.27024757032;6137.41694539018;5876.794312199053;5902.321233058254;5912.349641539513;5947.91078133837;5964.791232307022;5932.851678707894;5972.074328479092;6033.529525519089;6100.023996160593;6111.877762444815;5869.53043756507;5895.557833162616;5906.852328691817;5946.643201407929;5960.105984102246 18:46:41 INFO - PROCESS | 1682 | |1;ext.html;499.60859007663663;503.9521245481678;505.81537738725365;543.4212563619744;581.7033313010367;31193;42768.78615606922;43788.905277368045;43998.560014399845;44230.33654495185;45557.7722111389;45998;46009.76995115028;46017.53982460174;46358;8617.138286171365;9675.758106047335;9876.45679487628;9904.455254960974;9914.157296629795;8658.004329502093;11523.250988685708;11747.7063073423;11757.118216133782;11925.821112683318;11756.882431175707;11824.349660768654;11847.881521184807;11851.703707407298;11863.52545898163;7521;8922.01857795642;8933.240674542672;11794.410279486034;11907.761844763056;8760.342974276955;11380;11701.765964680744;11705.824412633818;11830.290182588986;34408.655913440794;42637;42638.786806066084;42674;42740.358894616606;3106.751459883204;3131.2015436063884;3307.851146698404;3335.182880194368;3482.0424383294544;29348.559771603443;29576;29803.25491862699;30200.09399718012;30416.239594010327;2700.743429374226;2767.5433553463654;2778;2785.56823692328;2837.7162283771663;508447.9999999963;508504.45747771394;509680;511609;513066;62342.376576234565;62583;62642.0603690945;62823.6858815703;62833;36187.638123618955;36842.447363289575;36918.89243322705;38118.047048824;38153;17283.913580432018;17304.394346197823;17471;17485.825141748675;17507.29970801154;10280.177585793119;12082.456289466994;12241.32672702992;12463.75072498545;13225.867741322463;16775.161241937854;17548.473545793648;19307.90346048261;19586.57777155808;20380.38858834237;7938.801529961797;8026.7591972240925;8031.397645176635;8059.677612895419;8118.309943654737;921.6037104045191;1017.6794309792392;1034.6895931220754;1095.5179720922768;1147.6744360263742;29877;30019.399612007637;30092.097237082922;30558.54670176303;30855.537166942515;1045.7921101128209;1070.7323169207698;1128.1651577832508;1138.698244965085;1159.2986243322837;15188.01277916932;15285.6178595536;15518.379264829506;15543.533693989199;15859.444919427764;662459;670813;673272;673335;673441;61796.69101654463;62333.68833155896;62370;62476.68761656163;62515.74968500604;1328.3424704771007;1446.2334962469909;1465.5383554180398;1487.3530014443752;1506.1414993453627;30575.694243057285;30636;30869.919552816442;31347;31397.686023139475;52717.47282527126;53038.46961530335;53335.733321333144;53401.46598533965;53668.19497707616;541973;545551;546972;547313;547775;22803.51777134481;23739.881300593388;26970.730292696822;26981.190564283104;27736.306592335353;46595.301070484646;46755.76622116868;47220.291695623906;47578.76210618925;48126.55620331396;37805.24389512249;38116.618833811306;38560.8431747048;39401.60598394094;39476.605233947295;37714.381990179354;38196.61803382042;38764.80617596894;39003.609963899995;39056.21887562289;37124;37927.86216413512;38022.23955520819;38993.41509877411;39021;29484.852575736983;32606.695732170447;33146.83426582852;34020.829895850366;34038.48942265918;23285.41786455352;25099.87450062811;25117.24648260555;25368.873155634104;25631.23106306814 18:46:41 INFO - PROCESS | 1682 | |2;jquery.html;387.67435354302387;394.8223299515218;396.3005295653173;401.6064257028114;419.1282133163021;14708.338124784384;20065.498362540926;20905.059272332743;21385.893070534647;21392.930353482323;15668.686626267468;17731.113444327795;20022.799772002276;20497.487562810937;20509.384718458434;5808.3320418151925;6049;6074.301455332649;6077.696115194245;6081.604695694788;5714;5923.674197919113;5979.581429299951;5987.461128498434;5988.580799344048;5886.264216972879;5911.556633252501;5927.02204136317;5930.88138237235;5931.347551769323;5613.887722245553;5869.618474799125;5882.764689412418;5883.382244864313;5923.466887980081;5126.974365128151;5161.8193363232485;5162.070827251093;5166.250893620441;5177.145770947789;24853.378665533324;25740.227793166046;26413.73586264132;26838.86580567104;27246.863765681243;2089.5925294567564;2128.2444732120184;2138.32642717545;2189.1462329691435;2206;14835.258237088103;14900.62748431287;15217.239138043164;15286.464973725977;15609.375624974988;1565.81210254769;1695.4574536148436;1749.7200447928271;1752.7972010669166;1762.2862740589953;126321;130609;131665.6833431663;133141;133395;10043.342848429927;11541.76916461666;11629.709257268636;11663.35851528157;11751.412429378497;14883.181425021512;14947.70104597902;14986.025908315925;14997.850021499864;15017.174055426838;6463.321351258144;6609.239937407164;6765.357291057342;7545;8849.867251991225;8727.123668411195;8749.65001399937;8751.343649226334;8856.512891790884;8859.955700221586;4525.162844873709;4606.907861842745;4609.308603709471;4651.046535460223;4681.110588988081;1587.9920600396924;1947.4060411574324;1949.9122539485475;1951.6779731344452;1960.402077366417;831.7629475599425;859.0464584311409;919.4391421233042;1018.8420794776821;1086.277625379114;16222.432214872422;17634.91182544079;19218;20338.389848304574;20420.978951052388;880.7137680253943;971.3589031239349;976.3214565876648;979.7746518300688;981.8919918808925;10633.680989570325;10746.14982708435;10820.485539706791;10886.564537418573;11768.646940591796;614930.1041937498;622208;624781;625872.870635644;637323;134574;138629.3068534651;138895;143247.28376358052;149962;1033.0702367868828;1047.6804574604819;1069.337011053152;1076.8169411200115;1080.4381721504774;18853.24587016504;19111.90444047771;19187.424377268704;19208.423747287605;19246.037698115317;53741.92516149733;54791.178132328845;56103;56516.5870853232;56995.71502142463;71037.64481177561;77330;78778.60610696909;80223;84196.73704894309;7775;7842.960785196037;7929.167437418988;7962.522248665098;8215.34277257806;11623.186376953532;12238;12414.937925310316;12639.05207109452;12812.935935320264;10583.576656933636;10812.729681758019;10813.729656758644;10874.64066991626;11092.279001864854;5176.223566465061;10261.358182690734;10543.103836174012;10897.67306980792;11006.669799906016;11032.889671103187;11213.271137376045;11462.770744584896;11577.6526704199;11623.186376953532;8103.311218546254;8194.262516373556;8314.459560128573;8324.209200125979;8364.707235246737;10623.89376106229;10779.407132607781;10799.892001079888;10800.399201596807;10958.506867191156 18:46:41 INFO - PROCESS | 1682 | |3;mootools.html;1431.9212443315614;1600.9759853602197;1605.7109720250355;1886.5000774794682;2026.8175864172222;8314;8968.968568614604;9231;9234.39976401535;9252.352335336529;8773.341999350057;9083.000869904321;9097;9229.215516681073;9230.076992300767;1973.2600274896913;2058.7838276980924;2385.1294277588727;2392.3779817247555;2444.6455263986713;2478.925632231036;2502.5620516409645;2503.249025292414;2503.7996960243136;2508;2359.8938047787806;2465.100238412975;2467.2351571012955;2467.7408872068445;2468.5309791139716;2119.6502577074843;2331.615283478241;2402.0631953538136;2428.890699918508;2429.6112621980574;2436.1595249638845;2440.060576677974;2471.8146139039645;2473.641322008317;2474.2948259746067;9051.954740226323;9220.354575179741;9220.538973051387;9240.537973101385;9250.768730781718;5484.067708489526;5646.717664116778;5647.604667673265;5650.29371328584;5659.066254068115;3499.772514786531;3602.657747513982;3635.3638113330326;3724;3724.3798947457944;1030.294248439821;1047.4919663963049;1047.9214058945533;1049.569676432659;1051.7791263834604;10257.897421025844;12608.936955315256;12612.873871261261;12613.810792838023;12651.177673451288;4891.633127515451;4986.601071914238;5092.516210959953;5097.8725531862;5124.205748109049;10705.197110216764;10719.732006699895;10747.032767051003;10748.623798167027;10750.354978701303;5656.462636049569;5662.66024038559;5663.093904975184;5665.036943719578;5668.376478587352;4547.454305483363;4704.623630109583;4933.1613625683;5086.23706444029;5086.338775959176;4110.794460276974;4122.093139509303;4215.536291007987;4255.851045213402;4256.233877902007;1119.972000699989;1363.7272545490941;1369.9726005479833;1375.1199232491213;1378.0147194755798;818.108261994429;902.5306840442933;915.1397686174975;919.1727445299284;925.9767956408207;5250;5898.64608123514;5925.4074592541265;6142.170806941006;6164.660943648053;453.41509452805514;465.96039336656764;475.519725077674;478.058225296166;481.63155186282523;3450.6894379505966;3973.920521589552;4029.8589549365634;4035.0315924177985;4067.6949228807957;10882.782344353069;11253.437328133561;11434.714132146597;11455.770884582262;11464.36945967963;10628.681139565826;11293.830592541119;11324.660260192208;11429.485673144729;11532.192746507832;7487.737929172453;8303.8339233215;8515.276201522816;8645.610947507377;8701.738947831574;6129.816105516842;6139.938600614116;6171.938280617136;6172.598781079216;6220.191375121205;10362.689119326433;10433.530491127765;10452.268341216042;10492.842607360742;10508.737281567715;10023.198144148304;10434.947825261128;10463.424511651932;10666.946665266934;10671.94664026675;7141.82145446368;7337;7370.705171793283;7769.261920117579;7966.123726389977;7358.926410735824;8067.717629882926;8186.386021048326;8671.262942649693;8805.647774088964;7775.261350171724;7799.883001755092;7807.492512986638;7873.488223265469;7906.5651389174145;7590.582517961565;7616.781314989575;7684.3084122429245;7738.2261773822165;7759.72840950564;6226.346233645402;7108.786736397917;7396.371308438843;7459.738909138154;7479.252074792695;4940.086084074458;5013.373328333958;5051.87370315745;5103.693778373309;5266;5002.074616196015;5070.3155074064525;5109.974450127875;5181.637285389987;5346.304980352529 18:46:41 INFO - PROCESS | 1682 | |4;prototype.html;307.7445720052358;312.63890206811135;325.04436955351275;329.3133815993653;331.47130327128235;4784.784684689189;5089.10940585397;5264.210368444736;5300.840974770758;5350.545203657684;4159.606531811839;4985.825496107637;4987.1547527414905;5112.568480825365;5355.571554275658;3578.731595130376;3677.485152078711;3835.405512145615;3997.060690737674;4017.6785857131504;3339.5658564386595;3687.907802304937;3743.454450973337;4016.618421249991;4043.4137050127715;3130.514770210621;3608.1520842601967;3726.0810682498536;3888.863889763859;3987.4816273884353;3440.0023993041987;3785.5614866350747;3924.9018774530864;3972.940405893914;4024.8390064397536;3232.8383580820864;3503.4394496880377;3508.3684936711384;3510.9297814043575;3533.1697051193205;4484.798184081724;4759.595434388081;4848.539388758062;5103.260027296058;5137.229415587692;4097;4583.885402864922;4627.930581041287;4637.652176086807;4665.836695715668;5147.124988751922;5463.644863083927;5531.008836818742;5562.4993750562235;5586.581006424534;3868.3617203161725;3904.403571624866;3910.0449775112443;3915.0441416730187;3933.6263055009726;4772.451168115642;4928.841722195281;5519.641223320512;5697.316322041382;5736.680563470425;3916.4908561886773;3920.313945059603;3921.568627450978;3928.1161738609158;3945.6054394560315;3881.7865017423746;4011.9970007498127;4043.838246470108;4046.7167298288837;4049.8785036448953;3203.871845126169;4137.606927341898;4485.416895803524;4573.565511276423;4669.7431641260055;4710.599599034121;5087.465816089258;5566.4990150886615;5602.663840169603;5611.745187791072;1190.422645017166;1261.7886828644605;1304.5193705144538;1339.5445548513553;1349.7030653256268;844.6156998565638;857.6355049104105;883;905.6377449020313;918.6968300460832;5188.429272779991;5197.350331208599;5234.476552344735;5235.319408476874;5282.920756188583;5299.44355842631;5602.187682785973;5623.578231632644;5706.657600543981;5735.283089613798;4917.713263332496;4969.111737539784;5205.9219111713355;5297.231901374356;5323.281357016831;2987.14866263114;3007.383486385286;3023.894163704304;3026.8789248429816;3027.9848600756854;5817.069268916953;6002.879942401128;6013.526685961967;6022.5483088768515;6027.156198132265;5421.728913554307;5813.633796057923;5834.533237341003;5853.64878107315;5879.559033072536;5326.97336513315;5937.970310148422;5946.305302988628;5947.699027039947;5961.463468287789;5111.769970351269;5278.865044015555;5327.467253274796;5639.377689374461;5759.798407055733;5104.846854594368;5352.654590880699;5608.186812912187;5634;5647.661140331593;5573.773769770644;5864.44287792659;5883.352831188651;5887.322957859901;5922.703864806828;3360.8655653773576;3435.261418794971;3567;3620.8913732588067;3881;5762.222100016612;5778.642019125587;5798.94201057984;5806.883862322646;5821.243238379153;5383.650062745909;5579.497845193952;5639.407862174577;5673.8297851064535;5684.63049901755;4983.875403114951;5360.0887849064975;5499.340079190522;5531.557475402039;5596.824666820014;4977.904860930735;5098.235264710175;5333.599980001593;5406.810761623325;5463.781448742165;4925.285833554043;5016.8210470540125;5470.480304371078;5494.066008778437;5530.142827861688;4811.663183577116;5234.921476177937;5458.4814442627885;5462.180672899097;5511.559075273888 18:46:41 INFO - PROCESS | 1682 | |5;yui.html;653.5719103986888;729.6133049483772;749.5764892835551;754.1892465599481;762.2111114995973;15005.324760385782;15685.84314156858;17099.82900170998;17856.464306070808;17907.73138402925;16607.418740344092;16752.413665521708;16838.7474187887;16857.915710421432;16888;727.6652739739726;931.1340453378356;942.1803031362724;942.7925856311634;942.8020115775695;744.6388501576758;902.7652810269311;934.8177105464437;935.3779736475236;945.7871978804749;974.9951250243739;980.6959842448828;981.043482604462;983.2772911909741;984.1978787288338;755.890395892598;943.2925306020485;973.547300505265;978.6917121106865;978.9706308810747;728.0608015659792;729.1323325242944;729.2598013016792;729.8467321862415;729.8686236477432;14972.026818256778;15023.399064037425;15034.924825375803;15035.248237588186;15038.624034399118;12212.206206596542;12249.510019599205;12322.075844311623;12333.629991100192;12550.309732964683;9049.502277374742;11147.554097836077;11589.594364197214;11681.824772628417;11913.987311078568;4065.004074001859;4171.499420069581;4192.014876504019;4216.57834216579;4305.268104422255;90460;93837.49300055979;93919;94338;95023.52488237583;8456.577171141418;8982.01197868234;9112.908870911338;9276.536173191313;10026.799464010679;20827.375178744664;21027.474313142295;21053.894730526248;21072.157113715588;21093.78906210949;7198.7840364789145;7327.816804579928;7337.706491740378;7509.662065207079;7548.698052077855;4075.5313138989395;4107.871429993058;4133.772642504691;4176.331786914079;4267.253230684618;2799.8740056697497;3634.9273014539563;3637.2725454908596;3645.54430696163;3657.3233951719017;649.7985624456434;659.9505037122138;661.5270081891471;665.517499812632;668.4652278177535;472.83687127940806;548.5255254205164;548.6598309048416;552.9447055294438;573.9840482346224;6268.905966410507;7697.015838894055;7869.921300786918;8152.755417337489;8217.83564328722;460.1165761737472;541.1666034307123;550.4495504495504;558.9804356847491;588.294047143421;3776.7167462440425;3805.1438426353848;3819.694424446038;3820.56063552695;4019.4926902411594;105108;105830.47084764527;107873.46063269634;110712;111102;81386.3722725554;84372;90965.54517227504;93084.53457732667;94949;78989.60505197437;79646.60176699079;79681;79924.0018999518;80203.7969430459;5865.472107510344;7407.111146662435;7483.625818709152;7539.962300188464;7570.318671319607;40436.79781601073;42458;48396.27405588848;49136.508634913196;50236.748816257146;59003.409965899795;59400.40599594122;59628.70185649044;59717.402825971185;60378.396216037276;10946.39794811293;11095.944520277348;11311;11421.486033128695;11587.88412115868;9836.754081148028;11698.532058717556;12011.279323240633;12268.815967760313;12510.061745369316;11414.372209528223;11467.483963221508;11633.0111940486;11752.236104653512;11938.880611193777;11373.31760094397;11398.373089480157;11428.14288928317;11428.714282143013;11456.312621242752;11187.160962927976;11249.71875703114;11306.321620702784;11348.432578371214;11648.825267620823;7835.804104897423;9096.499692516974;9150.588223529823;9722.125008749246;9844.261680373857;8655.78818965358;9258.768530786785;9724.805503889742;9962.59792520565;10032.666798653616 18:46:41 INFO - PROCESS | 1682 | __end_tp_report 18:46:41 INFO - PROCESS | 1682 | __start_cc_report 18:46:41 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1530 18:46:41 INFO - PROCESS | 1682 | __end_cc_report 18:46:41 INFO - PROCESS | 1682 | __startTimestamp1463190401904__endTimestamp 18:46:41 INFO - PROCESS | 1682 | 18:46:41 INFO - PROCESS | 1682 | ------- Summary: start ------- 18:46:41 INFO - PROCESS | 1682 | Number of tests: 6 18:46:41 INFO - PROCESS | 1682 | 18:46:41 INFO - PROCESS | 1682 | [#0] dojo.html Cycles:165 Average:5656.69 Median:5869.53 stddev:663.42 (11.3%) stddev-sans-first:637.92 18:46:41 INFO - PROCESS | 1682 | Values: 3245.4 3451.9 3459.2 3466.3 3508.5 5567.9 5607.4 5639.5 5663.2 5687.1 4553.3 4742.9 4759.6 5652.8 5735.2 5033.6 5078.6 5144.1 5146.2 5277.6 5241.7 5257.7 5263.2 5269.6 5322.2 5090.3 5147.4 5147.6 5187.2 5216.7 4060.0 5167.0 5175.1 5179.6 5184.3 5071.4 5077.7 5108.2 5135.8 5162.5 4843.8 5551.3 5558.3 5589.0 5592.3 6174.1 6234.9 6235.4 6236.6 6257.7 5583.8 6148.9 6199.1 6300.4 6330.3 5747.6 5784.1 5784.7 5793.2 5824.4 6248.2 6300.7 6320.1 6327.6 6327.9 5945.7 6003.5 6005.5 6075.3 6132.9 5168.6 6111.7 6167.8 6182.0 6187.5 6090.9 6095.3 6101.2 6125.4 6135.1 6173.4 6211.8 6221.4 6225.7 6249.4 4866.5 4877.2 4880.7 4892.8 4914.7 4321.3 4362.2 4413.2 4443.5 4470.4 5732.2 5738.7 5744.1 5762.3 5776.0 6226.8 6302.7 6303.9 6318.6 6329.7 5632.3 5677.8 5697.5 5719.4 5760.8 5292.4 5325.0 5357.2 5370.8 5375.0 6329.5 6331.3 6339.9 6352.7 6358.6 6101.0 6233.1 6259.3 6271.2 6277.1 6230.7 6272.8 6286.4 6308.8 6363.9 5453.3 5834.3 6140.0 6151.6 6163.1 5144.7 6115.6 6217.9 6259.0 6269.7 4916.2 5987.1 6012.7 6024.7 6028.6 4970.5 6057.8 6063.2 6081.3 6137.4 5876.8 5902.3 5912.3 5947.9 5964.8 5932.9 5972.1 6033.5 6100.0 6111.9 5869.5 5895.6 5906.9 5946.6 5960.1 18:46:41 INFO - PROCESS | 1682 | 18:46:41 INFO - PROCESS | 1682 | [#1] ext.html Cycles:180 Average:70633.18 Median:19834.84 stddev:154961.41 (781.3%) stddev-sans-first:155306.65 18:46:41 INFO - PROCESS | 1682 | Values: 499.6 504.0 505.8 543.4 581.7 31193.0 42768.8 43788.9 43998.6 44230.3 45557.8 45998.0 46009.8 46017.5 46358.0 8617.1 9675.8 9876.5 9904.5 9914.2 8658.0 11523.3 11747.7 11757.1 11925.8 11756.9 11824.3 11847.9 11851.7 11863.5 7521.0 8922.0 8933.2 11794.4 11907.8 8760.3 11380.0 11701.8 11705.8 11830.3 34408.7 42637.0 42638.8 42674.0 42740.4 3106.8 3131.2 3307.9 3335.2 3482.0 29348.6 29576.0 29803.3 30200.1 30416.2 2700.7 2767.5 2778.0 2785.6 2837.7 508448.0 508504.5 509680.0 511609.0 513066.0 62342.4 62583.0 62642.1 62823.7 62833.0 36187.6 36842.4 36918.9 38118.0 38153.0 17283.9 17304.4 17471.0 17485.8 17507.3 10280.2 12082.5 12241.3 12463.8 13225.9 16775.2 17548.5 19307.9 19586.6 20380.4 7938.8 8026.8 8031.4 8059.7 8118.3 921.6 1017.7 1034.7 1095.5 1147.7 29877.0 30019.4 30092.1 30558.5 30855.5 1045.8 1070.7 1128.2 1138.7 1159.3 15188.0 15285.6 15518.4 15543.5 15859.4 662459.0 670813.0 673272.0 673335.0 673441.0 61796.7 62333.7 62370.0 62476.7 62515.7 1328.3 1446.2 1465.5 1487.4 1506.1 30575.7 30636.0 30869.9 31347.0 31397.7 52717.5 53038.5 53335.7 53401.5 53668.2 541973.0 545551.0 546972.0 547313.0 547775.0 22803.5 23739.9 26970.7 26981.2 27736.3 46595.3 46755.8 47220.3 47578.8 48126.6 37805.2 38116.6 38560.8 39401.6 39476.6 37714.4 38196.6 38764.8 39003.6 39056.2 37124.0 37927.9 38022.2 38993.4 39021.0 29484.9 32606.7 33146.8 34020.8 34038.5 23285.4 25099.9 25117.2 25368.9 25631.2 18:46:41 INFO - PROCESS | 1682 | 18:46:41 INFO - PROCESS | 1682 | [#2] jquery.html Cycles:180 Average:36552.90 Median:2133.29 stddev:104760.61 (4910.8%) stddev-sans-first:105019.30 18:46:41 INFO - PROCESS | 1682 | Values: 387.7 394.8 396.3 401.6 419.1 14708.3 20065.5 20905.1 21385.9 21392.9 15668.7 17731.1 20022.8 20497.5 20509.4 5808.3 6049.0 6074.3 6077.7 6081.6 5714.0 5923.7 5979.6 5987.5 5988.6 5886.3 5911.6 5927.0 5930.9 5931.3 5613.9 5869.6 5882.8 5883.4 5923.5 5127.0 5161.8 5162.1 5166.3 5177.1 24853.4 25740.2 26413.7 26838.9 27246.9 2089.6 2128.2 2138.3 2189.1 2206.0 14835.3 14900.6 15217.2 15286.5 15609.4 1565.8 1695.5 1749.7 1752.8 1762.3 126321.0 130609.0 131665.7 133141.0 133395.0 10043.3 11541.8 11629.7 11663.4 11751.4 14883.2 14947.7 14986.0 14997.9 15017.2 6463.3 6609.2 6765.4 7545.0 8849.9 8727.1 8749.7 8751.3 8856.5 8860.0 4525.2 4606.9 4609.3 4651.0 4681.1 1588.0 1947.4 1949.9 1951.7 1960.4 831.8 859.0 919.4 1018.8 1086.3 16222.4 17634.9 19218.0 20338.4 20421.0 880.7 971.4 976.3 979.8 981.9 10633.7 10746.1 10820.5 10886.6 11768.6 614930.1 622208.0 624781.0 625872.9 637323.0 134574.0 138629.3 138895.0 143247.3 149962.0 1033.1 1047.7 1069.3 1076.8 1080.4 18853.2 19111.9 19187.4 19208.4 19246.0 53741.9 54791.2 56103.0 56516.6 56995.7 71037.6 77330.0 78778.6 80223.0 84196.7 7775.0 7843.0 7929.2 7962.5 8215.3 11623.2 12238.0 12414.9 12639.1 12812.9 10583.6 10812.7 10813.7 10874.6 11092.3 5176.2 10261.4 10543.1 10897.7 11006.7 11032.9 11213.3 11462.8 11577.7 11623.2 8103.3 8194.3 8314.5 8324.2 8364.7 10623.9 10779.4 10799.9 10800.4 10958.5 18:46:41 INFO - PROCESS | 1682 | 18:46:41 INFO - PROCESS | 1682 | [#3] mootools.html Cycles:180 Average:5915.24 Median:479.84 stddev:3384.20 (705.3%) stddev-sans-first:3376.93 18:46:41 INFO - PROCESS | 1682 | Values: 1431.9 1601.0 1605.7 1886.5 2026.8 8314.0 8969.0 9231.0 9234.4 9252.4 8773.3 9083.0 9097.0 9229.2 9230.1 1973.3 2058.8 2385.1 2392.4 2444.6 2478.9 2502.6 2503.2 2503.8 2508.0 2359.9 2465.1 2467.2 2467.7 2468.5 2119.7 2331.6 2402.1 2428.9 2429.6 2436.2 2440.1 2471.8 2473.6 2474.3 9052.0 9220.4 9220.5 9240.5 9250.8 5484.1 5646.7 5647.6 5650.3 5659.1 3499.8 3602.7 3635.4 3724.0 3724.4 1030.3 1047.5 1047.9 1049.6 1051.8 10257.9 12608.9 12612.9 12613.8 12651.2 4891.6 4986.6 5092.5 5097.9 5124.2 10705.2 10719.7 10747.0 10748.6 10750.4 5656.5 5662.7 5663.1 5665.0 5668.4 4547.5 4704.6 4933.2 5086.2 5086.3 4110.8 4122.1 4215.5 4255.9 4256.2 1120.0 1363.7 1370.0 1375.1 1378.0 818.1 902.5 915.1 919.2 926.0 5250.0 5898.6 5925.4 6142.2 6164.7 453.4 466.0 475.5 478.1 481.6 3450.7 3973.9 4029.9 4035.0 4067.7 10882.8 11253.4 11434.7 11455.8 11464.4 10628.7 11293.8 11324.7 11429.5 11532.2 7487.7 8303.8 8515.3 8645.6 8701.7 6129.8 6139.9 6171.9 6172.6 6220.2 10362.7 10433.5 10452.3 10492.8 10508.7 10023.2 10434.9 10463.4 10666.9 10671.9 7141.8 7337.0 7370.7 7769.3 7966.1 7358.9 8067.7 8186.4 8671.3 8805.6 7775.3 7799.9 7807.5 7873.5 7906.6 7590.6 7616.8 7684.3 7738.2 7759.7 6226.3 7108.8 7396.4 7459.7 7479.3 4940.1 5013.4 5051.9 5103.7 5266.0 5002.1 5070.3 5110.0 5181.6 5346.3 18:46:41 INFO - PROCESS | 1682 | 18:46:41 INFO - PROCESS | 1682 | [#4] prototype.html Cycles:180 Average:4503.13 Median:5104.05 stddev:1394.12 (27.3%) stddev-sans-first:1362.01 18:46:41 INFO - PROCESS | 1682 | Values: 307.7 312.6 325.0 329.3 331.5 4784.8 5089.1 5264.2 5300.8 5350.5 4159.6 4985.8 4987.2 5112.6 5355.6 3578.7 3677.5 3835.4 3997.1 4017.7 3339.6 3687.9 3743.5 4016.6 4043.4 3130.5 3608.2 3726.1 3888.9 3987.5 3440.0 3785.6 3924.9 3972.9 4024.8 3232.8 3503.4 3508.4 3510.9 3533.2 4484.8 4759.6 4848.5 5103.3 5137.2 4097.0 4583.9 4627.9 4637.7 4665.8 5147.1 5463.6 5531.0 5562.5 5586.6 3868.4 3904.4 3910.0 3915.0 3933.6 4772.5 4928.8 5519.6 5697.3 5736.7 3916.5 3920.3 3921.6 3928.1 3945.6 3881.8 4012.0 4043.8 4046.7 4049.9 3203.9 4137.6 4485.4 4573.6 4669.7 4710.6 5087.5 5566.5 5602.7 5611.7 1190.4 1261.8 1304.5 1339.5 1349.7 844.6 857.6 883.0 905.6 918.7 5188.4 5197.4 5234.5 5235.3 5282.9 5299.4 5602.2 5623.6 5706.7 5735.3 4917.7 4969.1 5205.9 5297.2 5323.3 2987.1 3007.4 3023.9 3026.9 3028.0 5817.1 6002.9 6013.5 6022.5 6027.2 5421.7 5813.6 5834.5 5853.6 5879.6 5327.0 5938.0 5946.3 5947.7 5961.5 5111.8 5278.9 5327.5 5639.4 5759.8 5104.8 5352.7 5608.2 5634.0 5647.7 5573.8 5864.4 5883.4 5887.3 5922.7 3360.9 3435.3 3567.0 3620.9 3881.0 5762.2 5778.6 5798.9 5806.9 5821.2 5383.7 5579.5 5639.4 5673.8 5684.6 4983.9 5360.1 5499.3 5531.6 5596.8 4977.9 5098.2 5333.6 5406.8 5463.8 4925.3 5016.8 5470.5 5494.1 5530.1 4811.7 5234.9 5458.5 5462.2 5511.6 18:46:41 INFO - PROCESS | 1682 | 18:46:41 INFO - PROCESS | 1682 | [#5] yui.html Cycles:180 Average:19414.39 Median:551.70 stddev:28716.03 (5205.0%) stddev-sans-first:28762.04 18:46:41 INFO - PROCESS | 1682 | Values: 653.6 729.6 749.6 754.2 762.2 15005.3 15685.8 17099.8 17856.5 17907.7 16607.4 16752.4 16838.7 16857.9 16888.0 727.7 931.1 942.2 942.8 942.8 744.6 902.8 934.8 935.4 945.8 975.0 980.7 981.0 983.3 984.2 755.9 943.3 973.5 978.7 979.0 728.1 729.1 729.3 729.8 729.9 14972.0 15023.4 15034.9 15035.2 15038.6 12212.2 12249.5 12322.1 12333.6 12550.3 9049.5 11147.6 11589.6 11681.8 11914.0 4065.0 4171.5 4192.0 4216.6 4305.3 90460.0 93837.5 93919.0 94338.0 95023.5 8456.6 8982.0 9112.9 9276.5 10026.8 20827.4 21027.5 21053.9 21072.2 21093.8 7198.8 7327.8 7337.7 7509.7 7548.7 4075.5 4107.9 4133.8 4176.3 4267.3 2799.9 3634.9 3637.3 3645.5 3657.3 649.8 660.0 661.5 665.5 668.5 472.8 548.5 548.7 552.9 574.0 6268.9 7697.0 7869.9 8152.8 8217.8 460.1 541.2 550.4 559.0 588.3 3776.7 3805.1 3819.7 3820.6 4019.5 105108.0 105830.5 107873.5 110712.0 111102.0 81386.4 84372.0 90965.5 93084.5 94949.0 78989.6 79646.6 79681.0 79924.0 80203.8 5865.5 7407.1 7483.6 7540.0 7570.3 40436.8 42458.0 48396.3 49136.5 50236.7 59003.4 59400.4 59628.7 59717.4 60378.4 10946.4 11095.9 11311.0 11421.5 11587.9 9836.8 11698.5 12011.3 12268.8 12510.1 11414.4 11467.5 11633.0 11752.2 11938.9 11373.3 11398.4 11428.1 11428.7 11456.3 11187.2 11249.7 11306.3 11348.4 11648.8 7835.8 9096.5 9150.6 9722.1 9844.3 8655.8 9258.8 9724.8 9962.6 10032.7 18:46:41 INFO - PROCESS | 1682 | -------- Summary: end -------- 18:46:41 INFO - PROCESS | 1682 | 18:46:42 INFO - TEST-INFO | 1682: exit 0 18:46:42 INFO - TEST-OK | dromaeo_css | took 1116800ms 18:46:42 INFO - TEST-START | kraken 18:46:42 INFO - operating with platform_type : mac_ 18:46:42 INFO - Initialising browser for kraken test... 18:46:42 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmp4EcLP3/profile http://localhost:49274/getInfo.html) 18:46:44 INFO - PROCESS | 1690 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 18:46:44 INFO - PROCESS | 1690 | __metrics Screen width/height:1600/1200 18:46:44 INFO - PROCESS | 1690 | colorDepth:24 18:46:44 INFO - PROCESS | 1690 | Browser inner width/height: 1024/689 18:46:44 INFO - PROCESS | 1690 | __metrics 18:46:44 INFO - PROCESS | 1690 | 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" 18:46:44 ERROR - PROCESS | 1690 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:46:45 INFO - TEST-INFO | 1690: exit 0 18:46:45 INFO - Browser initialized. 18:46:45 INFO - Running cycle 1/1 for kraken test... 18:46:45 INFO - TEST-INFO | started process 1692 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmp4EcLP3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:46:57 INFO - PROCESS | 1692 | RSS: Main: 239128576 18:46:57 INFO - PROCESS | 1692 | 18:47:00 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 18:47:01 INFO - PROCESS | 1692 | RSS: Main: 287879168 18:47:01 INFO - PROCESS | 1692 | 18:47:03 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 18:47:04 INFO - PROCESS | 1692 | RSS: Main: 298016768 18:47:04 INFO - PROCESS | 1692 | 18:47:08 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 18:47:08 INFO - PROCESS | 1692 | RSS: Main: 385933312 18:47:08 INFO - PROCESS | 1692 | 18:47:10 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 18:47:11 INFO - PROCESS | 1692 | RSS: Main: 286969856 18:47:11 INFO - PROCESS | 1692 | 18:47:13 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:47:14 INFO - PROCESS | 1692 | RSS: Main: 290512896 18:47:14 INFO - PROCESS | 1692 | 18:47:17 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 18:47:17 INFO - PROCESS | 1692 | RSS: Main: 328343552 18:47:17 INFO - PROCESS | 1692 | 18:47:20 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 18:47:21 INFO - PROCESS | 1692 | RSS: Main: 331837440 18:47:21 INFO - PROCESS | 1692 | 18:47:24 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 18:47:25 INFO - PROCESS | 1692 | RSS: Main: 330072064 18:47:25 INFO - PROCESS | 1692 | 18:47:26 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:47:27 INFO - PROCESS | 1692 | RSS: Main: 316444672 18:47:27 INFO - PROCESS | 1692 | 18:47:29 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:47:30 INFO - PROCESS | 1692 | RSS: Main: 323616768 18:47:30 INFO - PROCESS | 1692 | 18:47:32 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:47:33 INFO - PROCESS | 1692 | RSS: Main: 324947968 18:47:33 INFO - PROCESS | 1692 | 18:47:36 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:47:36 INFO - PROCESS | 1692 | RSS: Main: 329674752 18:47:36 INFO - PROCESS | 1692 | 18:47:39 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:47:40 INFO - PROCESS | 1692 | RSS: Main: 327282688 18:47:40 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 18:47:42 INFO - PROCESS | 1692 | RSS: Main: 373628928 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | __start_tp_report 18:47:42 INFO - PROCESS | 1692 | _x_x_mozilla_page_load 18:47:42 INFO - PROCESS | 1692 | _x_x_mozilla_page_load_details 18:47:42 INFO - PROCESS | 1692 | |i|pagename|runs| 18:47:42 INFO - PROCESS | 1692 | |0;ai-astar;113;107;112;112;112;112;113;113;111;112 18:47:42 INFO - PROCESS | 1692 | |1;audio-beat-detection;156;119;140;199;121;137;135;127;201;141 18:47:42 INFO - PROCESS | 1692 | |2;audio-dft;186;185;184;183;184;185;181;200;182;182 18:47:42 INFO - PROCESS | 1692 | |3;audio-fft;71;75;56;126;57;60;58;72;136;76 18:47:42 INFO - PROCESS | 1692 | |4;audio-oscillator;93;92;133;79;79;76;101;77;90;100 18:47:42 INFO - PROCESS | 1692 | |5;imaging-gaussian-blur;86;83;86;84;86;83;87;84;86;89 18:47:42 INFO - PROCESS | 1692 | |6;imaging-darkroom;92;92;93;93;91;93;93;92;93;93 18:47:42 INFO - PROCESS | 1692 | |7;imaging-desaturate;85;85;87;86;84;85;80;84;69;135 18:47:42 INFO - PROCESS | 1692 | |8;json-parse-financial;57;57;59;59;58;56;56;114;57;54 18:47:42 INFO - PROCESS | 1692 | |9;json-stringify-tinderbox;63;62;74;78;73;70;67;66;69;68 18:47:42 INFO - PROCESS | 1692 | |10;stanford-crypto-aes;85;86;85;89;85;84;84;85;86;86 18:47:42 INFO - PROCESS | 1692 | |11;stanford-crypto-ccm;142;146;128;137;140;138;124;136;241;149 18:47:42 INFO - PROCESS | 1692 | |12;stanford-crypto-pbkdf2;165;164;140;180;223;160;165;174;218;222 18:47:42 INFO - PROCESS | 1692 | |13;stanford-crypto-sha256-iterative;71;77;83;76;66;66;66;67;65;63 18:47:42 INFO - PROCESS | 1692 | __end_tp_report 18:47:42 INFO - PROCESS | 1692 | __start_cc_report 18:47:42 INFO - PROCESS | 1692 | _x_x_mozilla_cycle_collect,2273 18:47:42 INFO - PROCESS | 1692 | __end_cc_report 18:47:42 INFO - PROCESS | 1692 | __startTimestamp1463190462452__endTimestamp 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | ------- Summary: start ------- 18:47:42 INFO - PROCESS | 1692 | Number of tests: 14 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.77 (1.6%) stddev-sans-first:1.81 18:47:42 INFO - PROCESS | 1692 | Values: 113.0 107.0 112.0 112.0 112.0 112.0 113.0 113.0 111.0 112.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#1] audio-beat-detection Cycles:10 Average:147.60 Median:140.50 stddev:29.60 (21.1%) stddev-sans-first:31.24 18:47:42 INFO - PROCESS | 1692 | Values: 156.0 119.0 140.0 199.0 121.0 137.0 135.0 127.0 201.0 141.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#2] audio-dft Cycles:10 Average:185.20 Median:184.50 stddev:5.43 (2.9%) stddev-sans-first:5.75 18:47:42 INFO - PROCESS | 1692 | Values: 186.0 185.0 184.0 183.0 184.0 185.0 181.0 200.0 182.0 182.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#3] audio-fft Cycles:10 Average:78.70 Median:65.50 stddev:28.69 (43.8%) stddev-sans-first:30.30 18:47:42 INFO - PROCESS | 1692 | Values: 71.0 75.0 56.0 126.0 57.0 60.0 58.0 72.0 136.0 76.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:79.00 stddev:17.16 (21.7%) stddev-sans-first:18.20 18:47:42 INFO - PROCESS | 1692 | Values: 93.0 92.0 133.0 79.0 79.0 76.0 101.0 77.0 90.0 100.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#5] imaging-gaussian-blur Cycles:10 Average:85.40 Median:86.00 stddev:1.90 (2.2%) stddev-sans-first:2.00 18:47:42 INFO - PROCESS | 1692 | Values: 86.0 83.0 86.0 84.0 86.0 83.0 87.0 84.0 86.0 89.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:0.71 (0.8%) stddev-sans-first:0.73 18:47:42 INFO - PROCESS | 1692 | Values: 92.0 92.0 93.0 93.0 91.0 93.0 93.0 92.0 93.0 93.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#7] imaging-desaturate Cycles:10 Average:88.00 Median:85.00 stddev:17.31 (20.4%) stddev-sans-first:18.33 18:47:42 INFO - PROCESS | 1692 | Values: 85.0 85.0 87.0 86.0 84.0 85.0 80.0 84.0 69.0 135.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:57.00 stddev:18.09 (31.7%) stddev-sans-first:19.07 18:47:42 INFO - PROCESS | 1692 | Values: 57.0 57.0 59.0 59.0 58.0 56.0 56.0 114.0 57.0 54.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:69.50 stddev:4.97 (7.1%) stddev-sans-first:4.77 18:47:42 INFO - PROCESS | 1692 | Values: 63.0 62.0 74.0 78.0 73.0 70.0 67.0 66.0 69.0 68.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:85.50 stddev:1.43 (1.7%) stddev-sans-first:1.51 18:47:42 INFO - PROCESS | 1692 | Values: 85.0 86.0 85.0 89.0 85.0 84.0 84.0 85.0 86.0 86.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:141.00 stddev:33.49 (23.8%) stddev-sans-first:35.45 18:47:42 INFO - PROCESS | 1692 | Values: 142.0 146.0 128.0 137.0 140.0 138.0 124.0 136.0 241.0 149.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.10 Median:177.00 stddev:29.41 (16.6%) stddev-sans-first:30.62 18:47:42 INFO - PROCESS | 1692 | Values: 165.0 164.0 140.0 180.0 223.0 160.0 165.0 174.0 218.0 222.0 18:47:42 INFO - PROCESS | 1692 | 18:47:42 INFO - PROCESS | 1692 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:69.00 stddev:6.55 (9.5%) stddev-sans-first:6.94 18:47:42 INFO - PROCESS | 1692 | Values: 71.0 77.0 83.0 76.0 66.0 66.0 66.0 67.0 65.0 63.0 18:47:42 INFO - PROCESS | 1692 | -------- Summary: end -------- 18:47:42 INFO - PROCESS | 1692 | 18:47:43 INFO - TEST-INFO | 1692: exit 0 18:47:43 INFO - TEST-OK | kraken | took 60804ms 18:47:43 INFO - SUITE-END | took 1177s 18:47:43 INFO - Completed test suite (00:19:37) 18:47:43 INFO - javascript benchmark 18:47:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3245.4320493913565, 3451.879184228551, 3459.1870910336083, 3466.324066806972, 3508.491268766029, 5567.916481252784, 5607.355154157273, 5639.520640745542, 5663.235463212465, 5687.090065589507, 4553.2942393928915, 4742.928856067162, 4759.643026772988, 5652.773889044443, 5735.225744524488, 5033.622478314122, 5078.64449488536, 5144.0740666679985, 5146.228065790124, 5277.604179686519, 5241.737913104349, 5257.684538927677, 5263.1578947368425, 5269.552088072518, 5322.2282768998475, 5090.287359769635, 5147.382314122329, 5147.639665223436, 5187.247849061884, 5216.66091704038, 4059.9797001014804, 5166.974165129188, 5175.068487672217, 5179.559737422323, 5184.27420161174, 5071.416787069497, 5077.746112694387, 5108.182690769496, 5135.84592462223, 5162.509561591681, 4843.782029808667, 5551.278333816618, 5558.333000039981, 5588.966041282376, 5592.328920529521, 6174.135621013062, 6234.937650623481, 6235.407636274546, 6236.594621349643, 6257.687115644245, 5583.804566840182, 6148.907766383508, 6199.101130336121, 6300.432961033529, 6330.303666596671, 5747.568932330092, 5784.1034639630925, 5784.681842498619, 5793.188953546507, 5824.417558244142, 6248.156498872594, 6300.684965751786, 6320.051992201114, 6327.557071005078, 6327.936720632827, 5945.672987985616, 6003.459688628044, 6005.459508644243, 6075.33171351151, 6132.938670613326, 5168.61235407346, 6111.713288451782, 6167.753289868445, 6181.96909015452, 6187.504999600112, 6090.878182436327, 6095.25698559494, 6101.186783078283, 6125.449118208347, 6135.07955838608, 6173.41352571505, 6211.782587609412, 6221.440070393686, 6225.657588832658, 6249.375062493714, 4866.46468888422, 4877.219644856806, 4880.682755620874, 4892.804287828518, 4914.680545764514, 4321.308590625485, 4362.236608593484, 4413.227685155085, 4443.511213766483, 4470.374147619271, 5732.197492351074, 5738.68437235956, 5744.080947048452, 5762.250907382014, 5776.0, 6226.750929962841, 6302.684865756693, 6303.873922521616, 6318.62088274705, 6329.7468101275435, 5632.267805185382, 5677.772889084473, 5697.515711164515, 5719.370869204384, 5760.769569217185, 5292.391374991848, 5325.0, 5357.223202635674, 5370.812021579226, 5374.973125134427, 6329.461995730322, 6331.335209803088, 6339.904901426482, 6352.745890164341, 6358.618482890949, 6101.005087479836, 6233.065040244, 6259.311475737756, 6271.153394291895, 6277.089821975696, 6230.657313847782, 6272.8431789203805, 6286.402791734778, 6308.810735677937, 6363.904541431974, 5453.263809385682, 5834.27071616048, 6140.017597184429, 6151.630902145886, 6163.075538669235, 5144.6913185209005, 6115.571909966259, 6217.880781459381, 6258.968705156445, 6269.7178626962805, 4916.237983112624, 5987.131865879336, 6012.729427175875, 6024.728887199999, 6028.57799953999, 4970.478099799469, 6057.757689692363, 6063.151158837678, 6081.27024757032, 6137.41694539018, 5876.794312199053, 5902.321233058254, 5912.349641539513, 5947.91078133837, 5964.791232307022, 5932.851678707894, 5972.074328479092, 6033.529525519089, 6100.023996160593, 6111.877762444815, 5869.53043756507, 5895.557833162616, 5906.852328691817, 5946.643201407929, 5960.105984102246], "value": 5616.4317291721345, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.60859007663663, 503.9521245481678, 505.81537738725365, 543.4212563619744, 581.7033313010367, 31193.0, 42768.78615606922, 43788.905277368045, 43998.560014399845, 44230.33654495185, 45557.7722111389, 45998.0, 46009.76995115028, 46017.53982460174, 46358.0, 8617.138286171365, 9675.758106047335, 9876.45679487628, 9904.455254960974, 9914.157296629795, 8658.004329502093, 11523.250988685708, 11747.7063073423, 11757.118216133782, 11925.821112683318, 11756.882431175707, 11824.349660768654, 11847.881521184807, 11851.703707407298, 11863.52545898163, 7521.0, 8922.01857795642, 8933.240674542672, 11794.410279486034, 11907.761844763056, 8760.342974276955, 11380.0, 11701.765964680744, 11705.824412633818, 11830.290182588986, 34408.655913440794, 42637.0, 42638.786806066084, 42674.0, 42740.358894616606, 3106.751459883204, 3131.2015436063884, 3307.851146698404, 3335.182880194368, 3482.0424383294544, 29348.559771603443, 29576.0, 29803.25491862699, 30200.09399718012, 30416.239594010327, 2700.743429374226, 2767.5433553463654, 2778.0, 2785.56823692328, 2837.7162283771663, 508447.9999999963, 508504.45747771394, 509680.0, 511609.0, 513066.0, 62342.376576234565, 62583.0, 62642.0603690945, 62823.6858815703, 62833.0, 36187.638123618955, 36842.447363289575, 36918.89243322705, 38118.047048824, 38153.0, 17283.913580432018, 17304.394346197823, 17471.0, 17485.825141748675, 17507.29970801154, 10280.177585793119, 12082.456289466994, 12241.32672702992, 12463.75072498545, 13225.867741322463, 16775.161241937854, 17548.473545793648, 19307.90346048261, 19586.57777155808, 20380.38858834237, 7938.801529961797, 8026.7591972240925, 8031.397645176635, 8059.677612895419, 8118.309943654737, 921.6037104045191, 1017.6794309792392, 1034.6895931220754, 1095.5179720922768, 1147.6744360263742, 29877.0, 30019.399612007637, 30092.097237082922, 30558.54670176303, 30855.537166942515, 1045.7921101128209, 1070.7323169207698, 1128.1651577832508, 1138.698244965085, 1159.2986243322837, 15188.01277916932, 15285.6178595536, 15518.379264829506, 15543.533693989199, 15859.444919427764, 662459.0, 670813.0, 673272.0, 673335.0, 673441.0, 61796.69101654463, 62333.68833155896, 62370.0, 62476.68761656163, 62515.74968500604, 1328.3424704771007, 1446.2334962469909, 1465.5383554180398, 1487.3530014443752, 1506.1414993453627, 30575.694243057285, 30636.0, 30869.919552816442, 31347.0, 31397.686023139475, 52717.47282527126, 53038.46961530335, 53335.733321333144, 53401.46598533965, 53668.19497707616, 541973.0, 545551.0, 546972.0, 547313.0, 547775.0, 22803.51777134481, 23739.881300593388, 26970.730292696822, 26981.190564283104, 27736.306592335353, 46595.301070484646, 46755.76622116868, 47220.291695623906, 47578.76210618925, 48126.55620331396, 37805.24389512249, 38116.618833811306, 38560.8431747048, 39401.60598394094, 39476.605233947295, 37714.381990179354, 38196.61803382042, 38764.80617596894, 39003.609963899995, 39056.21887562289, 37124.0, 37927.86216413512, 38022.23955520819, 38993.41509877411, 39021.0, 29484.852575736983, 32606.695732170447, 33146.83426582852, 34020.829895850366, 34038.48942265918, 23285.41786455352, 25099.87450062811, 25117.24648260555, 25368.873155634104, 25631.23106306814], "value": 20182.608209670474, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.67435354302387, 394.8223299515218, 396.3005295653173, 401.6064257028114, 419.1282133163021, 14708.338124784384, 20065.498362540926, 20905.059272332743, 21385.893070534647, 21392.930353482323, 15668.686626267468, 17731.113444327795, 20022.799772002276, 20497.487562810937, 20509.384718458434, 5808.3320418151925, 6049.0, 6074.301455332649, 6077.696115194245, 6081.604695694788, 5714.0, 5923.674197919113, 5979.581429299951, 5987.461128498434, 5988.580799344048, 5886.264216972879, 5911.556633252501, 5927.02204136317, 5930.88138237235, 5931.347551769323, 5613.887722245553, 5869.618474799125, 5882.764689412418, 5883.382244864313, 5923.466887980081, 5126.974365128151, 5161.8193363232485, 5162.070827251093, 5166.250893620441, 5177.145770947789, 24853.378665533324, 25740.227793166046, 26413.73586264132, 26838.86580567104, 27246.863765681243, 2089.5925294567564, 2128.2444732120184, 2138.32642717545, 2189.1462329691435, 2206.0, 14835.258237088103, 14900.62748431287, 15217.239138043164, 15286.464973725977, 15609.375624974988, 1565.81210254769, 1695.4574536148436, 1749.7200447928271, 1752.7972010669166, 1762.2862740589953, 126321.0, 130609.0, 131665.6833431663, 133141.0, 133395.0, 10043.342848429927, 11541.76916461666, 11629.709257268636, 11663.35851528157, 11751.412429378497, 14883.181425021512, 14947.70104597902, 14986.025908315925, 14997.850021499864, 15017.174055426838, 6463.321351258144, 6609.239937407164, 6765.357291057342, 7545.0, 8849.867251991225, 8727.123668411195, 8749.65001399937, 8751.343649226334, 8856.512891790884, 8859.955700221586, 4525.162844873709, 4606.907861842745, 4609.308603709471, 4651.046535460223, 4681.110588988081, 1587.9920600396924, 1947.4060411574324, 1949.9122539485475, 1951.6779731344452, 1960.402077366417, 831.7629475599425, 859.0464584311409, 919.4391421233042, 1018.8420794776821, 1086.277625379114, 16222.432214872422, 17634.91182544079, 19218.0, 20338.389848304574, 20420.978951052388, 880.7137680253943, 971.3589031239349, 976.3214565876648, 979.7746518300688, 981.8919918808925, 10633.680989570325, 10746.14982708435, 10820.485539706791, 10886.564537418573, 11768.646940591796, 614930.1041937498, 622208.0, 624781.0, 625872.870635644, 637323.0, 134574.0, 138629.3068534651, 138895.0, 143247.28376358052, 149962.0, 1033.0702367868828, 1047.6804574604819, 1069.337011053152, 1076.8169411200115, 1080.4381721504774, 18853.24587016504, 19111.90444047771, 19187.424377268704, 19208.423747287605, 19246.037698115317, 53741.92516149733, 54791.178132328845, 56103.0, 56516.5870853232, 56995.71502142463, 71037.64481177561, 77330.0, 78778.60610696909, 80223.0, 84196.73704894309, 7775.0, 7842.960785196037, 7929.167437418988, 7962.522248665098, 8215.34277257806, 11623.186376953532, 12238.0, 12414.937925310316, 12639.05207109452, 12812.935935320264, 10583.576656933636, 10812.729681758019, 10813.729656758644, 10874.64066991626, 11092.279001864854, 5176.223566465061, 10261.358182690734, 10543.103836174012, 10897.67306980792, 11006.669799906016, 11032.889671103187, 11213.271137376045, 11462.770744584896, 11577.6526704199, 11623.186376953532, 8103.311218546254, 8194.262516373556, 8314.459560128573, 8324.209200125979, 8364.707235246737, 10623.89376106229, 10779.407132607781, 10799.892001079888, 10800.399201596807, 10958.506867191156], "value": 9626.880908672436, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.9212443315614, 1600.9759853602197, 1605.7109720250355, 1886.5000774794682, 2026.8175864172222, 8314.0, 8968.968568614604, 9231.0, 9234.39976401535, 9252.352335336529, 8773.341999350057, 9083.000869904321, 9097.0, 9229.215516681073, 9230.076992300767, 1973.2600274896913, 2058.7838276980924, 2385.1294277588727, 2392.3779817247555, 2444.6455263986713, 2478.925632231036, 2502.5620516409645, 2503.249025292414, 2503.7996960243136, 2508.0, 2359.8938047787806, 2465.100238412975, 2467.2351571012955, 2467.7408872068445, 2468.5309791139716, 2119.6502577074843, 2331.615283478241, 2402.0631953538136, 2428.890699918508, 2429.6112621980574, 2436.1595249638845, 2440.060576677974, 2471.8146139039645, 2473.641322008317, 2474.2948259746067, 9051.954740226323, 9220.354575179741, 9220.538973051387, 9240.537973101385, 9250.768730781718, 5484.067708489526, 5646.717664116778, 5647.604667673265, 5650.29371328584, 5659.066254068115, 3499.772514786531, 3602.657747513982, 3635.3638113330326, 3724.0, 3724.3798947457944, 1030.294248439821, 1047.4919663963049, 1047.9214058945533, 1049.569676432659, 1051.7791263834604, 10257.897421025844, 12608.936955315256, 12612.873871261261, 12613.810792838023, 12651.177673451288, 4891.633127515451, 4986.601071914238, 5092.516210959953, 5097.8725531862, 5124.205748109049, 10705.197110216764, 10719.732006699895, 10747.032767051003, 10748.623798167027, 10750.354978701303, 5656.462636049569, 5662.66024038559, 5663.093904975184, 5665.036943719578, 5668.376478587352, 4547.454305483363, 4704.623630109583, 4933.1613625683, 5086.23706444029, 5086.338775959176, 4110.794460276974, 4122.093139509303, 4215.536291007987, 4255.851045213402, 4256.233877902007, 1119.972000699989, 1363.7272545490941, 1369.9726005479833, 1375.1199232491213, 1378.0147194755798, 818.108261994429, 902.5306840442933, 915.1397686174975, 919.1727445299284, 925.9767956408207, 5250.0, 5898.64608123514, 5925.4074592541265, 6142.170806941006, 6164.660943648053, 453.41509452805514, 465.96039336656764, 475.519725077674, 478.058225296166, 481.63155186282523, 3450.6894379505966, 3973.920521589552, 4029.8589549365634, 4035.0315924177985, 4067.6949228807957, 10882.782344353069, 11253.437328133561, 11434.714132146597, 11455.770884582262, 11464.36945967963, 10628.681139565826, 11293.830592541119, 11324.660260192208, 11429.485673144729, 11532.192746507832, 7487.737929172453, 8303.8339233215, 8515.276201522816, 8645.610947507377, 8701.738947831574, 6129.816105516842, 6139.938600614116, 6171.938280617136, 6172.598781079216, 6220.191375121205, 10362.689119326433, 10433.530491127765, 10452.268341216042, 10492.842607360742, 10508.737281567715, 10023.198144148304, 10434.947825261128, 10463.424511651932, 10666.946665266934, 10671.94664026675, 7141.82145446368, 7337.0, 7370.705171793283, 7769.261920117579, 7966.123726389977, 7358.926410735824, 8067.717629882926, 8186.386021048326, 8671.262942649693, 8805.647774088964, 7775.261350171724, 7799.883001755092, 7807.492512986638, 7873.488223265469, 7906.5651389174145, 7590.582517961565, 7616.781314989575, 7684.3084122429245, 7738.2261773822165, 7759.72840950564, 6226.346233645402, 7108.786736397917, 7396.371308438843, 7459.738909138154, 7479.252074792695, 4940.086084074458, 5013.373328333958, 5051.87370315745, 5103.693778373309, 5266.0, 5002.074616196015, 5070.3155074064525, 5109.974450127875, 5181.637285389987, 5346.304980352529], "value": 4647.768658648821, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.7445720052358, 312.63890206811135, 325.04436955351275, 329.3133815993653, 331.47130327128235, 4784.784684689189, 5089.10940585397, 5264.210368444736, 5300.840974770758, 5350.545203657684, 4159.606531811839, 4985.825496107637, 4987.1547527414905, 5112.568480825365, 5355.571554275658, 3578.731595130376, 3677.485152078711, 3835.405512145615, 3997.060690737674, 4017.6785857131504, 3339.5658564386595, 3687.907802304937, 3743.454450973337, 4016.618421249991, 4043.4137050127715, 3130.514770210621, 3608.1520842601967, 3726.0810682498536, 3888.863889763859, 3987.4816273884353, 3440.0023993041987, 3785.5614866350747, 3924.9018774530864, 3972.940405893914, 4024.8390064397536, 3232.8383580820864, 3503.4394496880377, 3508.3684936711384, 3510.9297814043575, 3533.1697051193205, 4484.798184081724, 4759.595434388081, 4848.539388758062, 5103.260027296058, 5137.229415587692, 4097.0, 4583.885402864922, 4627.930581041287, 4637.652176086807, 4665.836695715668, 5147.124988751922, 5463.644863083927, 5531.008836818742, 5562.4993750562235, 5586.581006424534, 3868.3617203161725, 3904.403571624866, 3910.0449775112443, 3915.0441416730187, 3933.6263055009726, 4772.451168115642, 4928.841722195281, 5519.641223320512, 5697.316322041382, 5736.680563470425, 3916.4908561886773, 3920.313945059603, 3921.568627450978, 3928.1161738609158, 3945.6054394560315, 3881.7865017423746, 4011.9970007498127, 4043.838246470108, 4046.7167298288837, 4049.8785036448953, 3203.871845126169, 4137.606927341898, 4485.416895803524, 4573.565511276423, 4669.7431641260055, 4710.599599034121, 5087.465816089258, 5566.4990150886615, 5602.663840169603, 5611.745187791072, 1190.422645017166, 1261.7886828644605, 1304.5193705144538, 1339.5445548513553, 1349.7030653256268, 844.6156998565638, 857.6355049104105, 883.0, 905.6377449020313, 918.6968300460832, 5188.429272779991, 5197.350331208599, 5234.476552344735, 5235.319408476874, 5282.920756188583, 5299.44355842631, 5602.187682785973, 5623.578231632644, 5706.657600543981, 5735.283089613798, 4917.713263332496, 4969.111737539784, 5205.9219111713355, 5297.231901374356, 5323.281357016831, 2987.14866263114, 3007.383486385286, 3023.894163704304, 3026.8789248429816, 3027.9848600756854, 5817.069268916953, 6002.879942401128, 6013.526685961967, 6022.5483088768515, 6027.156198132265, 5421.728913554307, 5813.633796057923, 5834.533237341003, 5853.64878107315, 5879.559033072536, 5326.97336513315, 5937.970310148422, 5946.305302988628, 5947.699027039947, 5961.463468287789, 5111.769970351269, 5278.865044015555, 5327.467253274796, 5639.377689374461, 5759.798407055733, 5104.846854594368, 5352.654590880699, 5608.186812912187, 5634.0, 5647.661140331593, 5573.773769770644, 5864.44287792659, 5883.352831188651, 5887.322957859901, 5922.703864806828, 3360.8655653773576, 3435.261418794971, 3567.0, 3620.8913732588067, 3881.0, 5762.222100016612, 5778.642019125587, 5798.94201057984, 5806.883862322646, 5821.243238379153, 5383.650062745909, 5579.497845193952, 5639.407862174577, 5673.8297851064535, 5684.63049901755, 4983.875403114951, 5360.0887849064975, 5499.340079190522, 5531.557475402039, 5596.824666820014, 4977.904860930735, 5098.235264710175, 5333.599980001593, 5406.810761623325, 5463.781448742165, 4925.285833554043, 5016.8210470540125, 5470.480304371078, 5494.066008778437, 5530.142827861688, 4811.663183577116, 5234.921476177937, 5458.4814442627885, 5462.180672899097, 5511.559075273888], "value": 4062.8097917612317, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.5719103986888, 729.6133049483772, 749.5764892835551, 754.1892465599481, 762.2111114995973, 15005.324760385782, 15685.84314156858, 17099.82900170998, 17856.464306070808, 17907.73138402925, 16607.418740344092, 16752.413665521708, 16838.7474187887, 16857.915710421432, 16888.0, 727.6652739739726, 931.1340453378356, 942.1803031362724, 942.7925856311634, 942.8020115775695, 744.6388501576758, 902.7652810269311, 934.8177105464437, 935.3779736475236, 945.7871978804749, 974.9951250243739, 980.6959842448828, 981.043482604462, 983.2772911909741, 984.1978787288338, 755.890395892598, 943.2925306020485, 973.547300505265, 978.6917121106865, 978.9706308810747, 728.0608015659792, 729.1323325242944, 729.2598013016792, 729.8467321862415, 729.8686236477432, 14972.026818256778, 15023.399064037425, 15034.924825375803, 15035.248237588186, 15038.624034399118, 12212.206206596542, 12249.510019599205, 12322.075844311623, 12333.629991100192, 12550.309732964683, 9049.502277374742, 11147.554097836077, 11589.594364197214, 11681.824772628417, 11913.987311078568, 4065.004074001859, 4171.499420069581, 4192.014876504019, 4216.57834216579, 4305.268104422255, 90460.0, 93837.49300055979, 93919.0, 94338.0, 95023.52488237583, 8456.577171141418, 8982.01197868234, 9112.908870911338, 9276.536173191313, 10026.799464010679, 20827.375178744664, 21027.474313142295, 21053.894730526248, 21072.157113715588, 21093.78906210949, 7198.7840364789145, 7327.816804579928, 7337.706491740378, 7509.662065207079, 7548.698052077855, 4075.5313138989395, 4107.871429993058, 4133.772642504691, 4176.331786914079, 4267.253230684618, 2799.8740056697497, 3634.9273014539563, 3637.2725454908596, 3645.54430696163, 3657.3233951719017, 649.7985624456434, 659.9505037122138, 661.5270081891471, 665.517499812632, 668.4652278177535, 472.83687127940806, 548.5255254205164, 548.6598309048416, 552.9447055294438, 573.9840482346224, 6268.905966410507, 7697.015838894055, 7869.921300786918, 8152.755417337489, 8217.83564328722, 460.1165761737472, 541.1666034307123, 550.4495504495504, 558.9804356847491, 588.294047143421, 3776.7167462440425, 3805.1438426353848, 3819.694424446038, 3820.56063552695, 4019.4926902411594, 105108.0, 105830.47084764527, 107873.46063269634, 110712.0, 111102.0, 81386.3722725554, 84372.0, 90965.54517227504, 93084.53457732667, 94949.0, 78989.60505197437, 79646.60176699079, 79681.0, 79924.0018999518, 80203.7969430459, 5865.472107510344, 7407.111146662435, 7483.625818709152, 7539.962300188464, 7570.318671319607, 40436.79781601073, 42458.0, 48396.27405588848, 49136.508634913196, 50236.748816257146, 59003.409965899795, 59400.40599594122, 59628.70185649044, 59717.402825971185, 60378.396216037276, 10946.39794811293, 11095.944520277348, 11311.0, 11421.486033128695, 11587.88412115868, 9836.754081148028, 11698.532058717556, 12011.279323240633, 12268.815967760313, 12510.061745369316, 11414.372209528223, 11467.483963221508, 11633.0111940486, 11752.236104653512, 11938.880611193777, 11373.31760094397, 11398.373089480157, 11428.14288928317, 11428.714282143013, 11456.312621242752, 11187.160962927976, 11249.71875703114, 11306.321620702784, 11348.432578371214, 11648.825267620823, 7835.804104897423, 9096.499692516974, 9150.588223529823, 9722.125008749246, 9844.261680373857, 8655.78818965358, 9258.768530786785, 9724.805503889742, 9962.59792520565, 10032.666798653616], "value": 7001.688753329152, "unit": "score"}], "name": "dromaeo_css", "value": 7242.257227773278, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 112.0, 112.0, 112.0, 112.0, 113.0, 113.0, 111.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 119.0, 140.0, 199.0, 121.0, 137.0, 135.0, 127.0, 201.0, 141.0], "value": 147.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 183.0, 184.0, 185.0, 181.0, 200.0, 182.0, 182.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 56.0, 126.0, 57.0, 60.0, 58.0, 72.0, 136.0, 76.0], "value": 78.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 133.0, 79.0, 79.0, 76.0, 101.0, 77.0, 90.0, 100.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 86.0, 83.0, 87.0, 84.0, 86.0, 89.0], "value": 85.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 91.0, 93.0, 93.0, 92.0, 93.0, 93.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 86.0, 84.0, 85.0, 80.0, 84.0, 69.0, 135.0], "value": 88.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 59.0, 59.0, 58.0, 56.0, 56.0, 114.0, 57.0, 54.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 74.0, 78.0, 73.0, 70.0, 67.0, 66.0, 69.0, 68.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 89.0, 85.0, 84.0, 84.0, 85.0, 86.0, 86.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 146.0, 128.0, 137.0, 140.0, 138.0, 124.0, 136.0, 241.0, 149.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 140.0, 180.0, 223.0, 160.0, 165.0, 174.0, 218.0, 222.0], "value": 181.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 83.0, 76.0, 66.0, 66.0, 66.0, 67.0, 65.0, 63.0], "value": 70.0, "unit": "score"}], "name": "kraken", "value": 1497.5, "alertThreshold": 2.0}]} 18:47:43 INFO - Return code: 0 18:47:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:47:43 ERROR - # TBPL SUCCESS # 18:47:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:47:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:47:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:47:43 INFO - 2016-05-13 18:47:43.788 screenresolution[1696:9761] starting screenresolution argv=screenresolution get 18:47:43 INFO - 2016-05-13 18:47:43.790 screenresolution[1696:9761] Display 0: 1600x1200x32@60 18:47:43 INFO - 2016-05-13 18:47:43.796 screenresolution[1697:9764] starting screenresolution argv=screenresolution list 18:47:43 INFO - Available Modes on Display 0 18:47:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:47:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:47:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:47:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:47:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:47:44 INFO - Intel Iris: 18:47:44 INFO - Chipset Model: Intel Iris 18:47:44 INFO - Type: GPU 18:47:44 INFO - Bus: Built-In 18:47:44 INFO - VRAM (Dynamic, Max): 1536 MB 18:47:44 INFO - Vendor: Intel (0x8086) 18:47:44 INFO - Device ID: 0x0a2e 18:47:44 INFO - Revision ID: 0x0009 18:47:44 INFO - Displays: 18:47:44 INFO - Display: 18:47:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:47:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:47:44 INFO - Display Serial Number: 1600x1200 60 18:47:44 INFO - Main Display: Yes 18:47:44 INFO - Mirror: Off 18:47:44 INFO - Online: Yes 18:47:44 INFO - Rotation: Supported 18:47:44 INFO - Return code: 0 18:47:44 INFO - Running post-action listener: _resource_record_post_action 18:47:44 INFO - ##### 18:47:44 INFO - ##### Finished run-tests step (success) 18:47:44 INFO - ##### 18:47:44 INFO - Running post-run listener: _resource_record_post_run 18:47:44 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 36754944; Write bytes: 697867776; Read time: 242; Write time: 1905 18:47:44 INFO - TinderboxPrint: CPU usage
53.6% 18:47:44 INFO - TinderboxPrint: I/O read bytes / time
36,754,944 / 242 18:47:44 INFO - TinderboxPrint: I/O write bytes / time
697,867,776 / 1,905 18:47:44 INFO - TinderboxPrint: CPU idle
2,251.4 (46.4%) 18:47:44 INFO - TinderboxPrint: CPU system
959.8 (19.8%) 18:47:44 INFO - TinderboxPrint: CPU user
1,642.5 (33.8%) 18:47:44 INFO - TinderboxPrint: Swap in / out
431,136,768 / 0 18:47:44 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 379521536; Write bytes: 372502016; Read time: 28594; Write time: 758 18:47:44 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30416384; Write bytes: 296325120; Read time: 185; Write time: 1078 18:47:44 INFO - Running post-run listener: _upload_blobber_files 18:47:44 INFO - Blob upload gear active. 18:47:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:47:44 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 18:47:44 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'] 18:47:44 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 18:47:44 INFO - (blobuploader) - INFO - Open directory for files ... 18:47:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:47:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:47:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:47:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:46 INFO - (blobuploader) - INFO - Done attempting. 18:47:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:47:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:47:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:47:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:47 INFO - (blobuploader) - INFO - Done attempting. 18:47:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:47:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:47:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:47:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:49 INFO - (blobuploader) - INFO - Done attempting. 18:47:49 INFO - (blobuploader) - INFO - Iteration through files over. 18:47:49 INFO - Return code: 0 18:47:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:47:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:47:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c66c43ab7ac515585f51f3d2f8f0c8fe44f23903a79e78b2632b7162a73f94f70ab671845eac78636e768b0589b33ff7ef0403e94e13da1dc2971d2dc8c99955", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23378194c69c5a88cdb07db560181a0525219f680b87b1fd879d1c0a44cad1fbd30f7ee8a6934a8b7a987bf456bad2cc45c92b7e2585cb267800014a0fa0c3b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe1bb5624655f1897162ac6abf2b4fa6fd2aeb3b9aa3eac5bf8a4209ebbcd9b2af9aaa195675c991b7352b517d6a4e23603f9beec5fa0182ab28b09414a122fb"} 18:47:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:47:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:47:49 INFO - Contents: 18:47:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c66c43ab7ac515585f51f3d2f8f0c8fe44f23903a79e78b2632b7162a73f94f70ab671845eac78636e768b0589b33ff7ef0403e94e13da1dc2971d2dc8c99955", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23378194c69c5a88cdb07db560181a0525219f680b87b1fd879d1c0a44cad1fbd30f7ee8a6934a8b7a987bf456bad2cc45c92b7e2585cb267800014a0fa0c3b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe1bb5624655f1897162ac6abf2b4fa6fd2aeb3b9aa3eac5bf8a4209ebbcd9b2af9aaa195675c991b7352b517d6a4e23603f9beec5fa0182ab28b09414a122fb"} 18:47:49 INFO - Running post-run listener: copy_logs_to_upload_dir 18:47:49 INFO - Copying logs to upload dir... 18:47:49 INFO - mkdir: /builds/slave/test/build/upload/logs 18:47:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.504638 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-13 18:47:49.518319) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 18:47:49.522129) ========= 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.eClUXEWP97/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.hCjMaiHxGP/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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/c66c43ab7ac515585f51f3d2f8f0c8fe44f23903a79e78b2632b7162a73f94f70ab671845eac78636e768b0589b33ff7ef0403e94e13da1dc2971d2dc8c99955", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23378194c69c5a88cdb07db560181a0525219f680b87b1fd879d1c0a44cad1fbd30f7ee8a6934a8b7a987bf456bad2cc45c92b7e2585cb267800014a0fa0c3b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe1bb5624655f1897162ac6abf2b4fa6fd2aeb3b9aa3eac5bf8a4209ebbcd9b2af9aaa195675c991b7352b517d6a4e23603f9beec5fa0182ab28b09414a122fb"} build_url:https://queue.taskcluster.net/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011312 build_url: 'https://queue.taskcluster.net/v1/task/GmHPEf4TQ6W1NGwBbBxG3g/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/c66c43ab7ac515585f51f3d2f8f0c8fe44f23903a79e78b2632b7162a73f94f70ab671845eac78636e768b0589b33ff7ef0403e94e13da1dc2971d2dc8c99955", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23378194c69c5a88cdb07db560181a0525219f680b87b1fd879d1c0a44cad1fbd30f7ee8a6934a8b7a987bf456bad2cc45c92b7e2585cb267800014a0fa0c3b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe1bb5624655f1897162ac6abf2b4fa6fd2aeb3b9aa3eac5bf8a4209ebbcd9b2af9aaa195675c991b7352b517d6a4e23603f9beec5fa0182ab28b09414a122fb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 18:47:49.550033) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:47:49.550352) ========= 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.eClUXEWP97/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.hCjMaiHxGP/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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.005455 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:47:49.599530) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 18:47:49.599853) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 18:47:49.600186) ========= ========= Total master_lag: 0.30 =========