builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0166 starttime: 1464095735.63 results: success (0) buildid: 20160524033112 builduid: dbb3adbf12d046bfbde072ecc4d2c5d0 revision: 312c94f907b3c9c031d36c3522c9837d41f8d934 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:35.630409) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:35.631130) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:35.631457) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:35.652724) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:35.653142) ========= 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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-24 06:15:35-- 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% 8.42M=0.001s 2016-05-24 06:15:35 (8.42 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105662 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:35.777803) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:35.778157) ========= 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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.020763 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:35.828252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:35.828859) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 312c94f907b3c9c031d36c3522c9837d41f8d934 --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 312c94f907b3c9c031d36c3522c9837d41f8d934 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K78Mr5ToRn/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.mzTZUVLZG0/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-24 06:15:35,897 truncating revision to first 12 chars 2016-05-24 06:15:35,897 Setting DEBUG logging. 2016-05-24 06:15:35,897 attempt 1/10 2016-05-24 06:15:35,897 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/312c94f907b3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 06:15:36,141 unpacking tar archive at: fx-team-312c94f907b3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.493060 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:36.341209) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:36.341547) ========= script_repo_revision: 312c94f907b3c9c031d36c3522c9837d41f8d934 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:36.341984) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:36.342268) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 06:15:36.358154) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-24 06:15:36.358578) ========= /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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:15:36 INFO - MultiFileLogger online at 20160524 06:15:36 in /builds/slave/test 06:15:36 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 06:15:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:15:36 INFO - {'append_to_log': False, 06:15:36 INFO - 'base_work_dir': '/builds/slave/test', 06:15:36 INFO - 'blob_upload_branch': 'Fx-Team', 06:15:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:15:36 INFO - 'branch': 'Fx-Team', 06:15:36 INFO - 'buildbot_json_path': 'buildprops.json', 06:15:36 INFO - 'config_files': ('talos/mac_config.py',), 06:15:36 INFO - 'default_actions': ('clobber', 06:15:36 INFO - 'read-buildbot-config', 06:15:36 INFO - 'download-and-extract', 06:15:36 INFO - 'populate-webroot', 06:15:36 INFO - 'create-virtualenv', 06:15:36 INFO - 'install', 06:15:36 INFO - 'run-tests'), 06:15:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:15:36 INFO - 'download_minidump_stackwalk': True, 06:15:36 INFO - 'download_symbols': 'ondemand', 06:15:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:15:36 INFO - 'tooltool.py': '/tools/tooltool.py', 06:15:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:15:36 INFO - '/tools/misc-python/virtualenv.py')}, 06:15:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:15:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:15:36 INFO - 'installer_path': 'installer.exe', 06:15:36 INFO - 'log_level': 'info', 06:15:36 INFO - 'log_name': 'talos', 06:15:36 INFO - 'log_to_console': True, 06:15:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:15:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:15:36 INFO - 'opt_config_files': (), 06:15:36 INFO - 'pip_index': False, 06:15:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:15:36 INFO - 'cmd': ('bash', 06:15:36 INFO - '-c', 06:15:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:15:36 INFO - 'enabled': True, 06:15:36 INFO - 'halt_on_failure': False, 06:15:36 INFO - 'name': 'check_screen_resolution'},), 06:15:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:15:36 INFO - 'cmd': ('bash', 06:15:36 INFO - '-c', 06:15:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:15:36 INFO - 'enabled': True, 06:15:36 INFO - 'halt_on_failure': False, 06:15:36 INFO - 'name': 'check_screen_resolution'},), 06:15:36 INFO - 'run_cmd_checks_enabled': True, 06:15:36 INFO - 'sps_profile': False, 06:15:36 INFO - 'sps_profile_interval': 0, 06:15:36 INFO - 'suite': 'dromaeojs', 06:15:36 INFO - 'system_bits': '32', 06:15:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:15:36 INFO - 'title': 't-yosemite-r7-0166', 06:15:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:15:36 INFO - 'use_talos_json': True, 06:15:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:15:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:15:36 INFO - 'work_dir': 'build'} 06:15:36 INFO - ##### 06:15:36 INFO - ##### Running clobber step. 06:15:36 INFO - ##### 06:15:36 INFO - Running pre-action listener: _resource_record_pre_action 06:15:36 INFO - Running main action method: clobber 06:15:36 INFO - rmtree: /builds/slave/test/build 06:15:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:15:37 INFO - Running post-action listener: _resource_record_post_action 06:15:37 INFO - ##### 06:15:37 INFO - ##### Finished clobber step (success) 06:15:37 INFO - ##### 06:15:37 INFO - ##### 06:15:37 INFO - ##### Running read-buildbot-config step. 06:15:37 INFO - ##### 06:15:37 INFO - Running pre-action listener: _resource_record_pre_action 06:15:38 INFO - Running main action method: read_buildbot_config 06:15:38 INFO - Using buildbot properties: 06:15:38 INFO - { 06:15:38 INFO - "project": "", 06:15:38 INFO - "product": "firefox", 06:15:38 INFO - "script_repo_revision": "production", 06:15:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 06:15:38 INFO - "repository": "", 06:15:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 06:15:38 INFO - "buildid": "20160524033112", 06:15:38 INFO - "pgo_build": "False", 06:15:38 INFO - "basedir": "/builds/slave/test", 06:15:38 INFO - "buildnumber": 825, 06:15:38 INFO - "slavename": "t-yosemite-r7-0166", 06:15:38 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 06:15:38 INFO - "platform": "yosemite_r7", 06:15:38 INFO - "scheduler": "tests-fx-team-macosx64-talos", 06:15:38 INFO - "branch": "fx-team", 06:15:38 INFO - "repo_path": "integration/fx-team", 06:15:38 INFO - "revision": "312c94f907b3c9c031d36c3522c9837d41f8d934", 06:15:38 INFO - "stage_platform": "macosx64", 06:15:38 INFO - "builduid": "dbb3adbf12d046bfbde072ecc4d2c5d0", 06:15:38 INFO - "slavebuilddir": "test" 06:15:38 INFO - } 06:15:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 06:15:38 INFO - Running post-action listener: _resource_record_post_action 06:15:38 INFO - ##### 06:15:38 INFO - ##### Finished read-buildbot-config step (success) 06:15:38 INFO - ##### 06:15:38 INFO - ##### 06:15:38 INFO - ##### Running download-and-extract step. 06:15:38 INFO - ##### 06:15:38 INFO - Running pre-action listener: _resource_record_pre_action 06:15:38 INFO - Running main action method: download_and_extract 06:15:38 INFO - mkdir: /builds/slave/test/build/tests 06:15: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')]} 06:15:38 INFO - https://queue.taskcluster.net/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:15:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:15:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:15:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/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 06:15:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/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 06:15:39 INFO - Downloaded 1199 bytes. 06:15:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 06:15:39 INFO - Using the following test package requirements: 06:15:39 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:15:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:15:39 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 06:15:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:15:39 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 06:15:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:15:39 INFO - u'jsshell-mac.zip'], 06:15:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:15:39 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 06:15:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:15:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:15:39 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 06:15:39 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:15:39 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 06:15:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:15:39 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 06:15:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:15:39 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 06:15:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:15:39 INFO - https://queue.taskcluster.net/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:15:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:15:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:15:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/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 06:15:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/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 06:15:40 INFO - Downloaded 18630479 bytes. 06:15:40 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'] 06:15:40 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 06:15:43 INFO - Return code: 0 06:15:43 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 06:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:15:43 INFO - https://queue.taskcluster.net/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:15:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:15:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:15:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/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 06:15:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/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 06:15:44 INFO - Downloaded 18630479 bytes. 06:15:44 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'] 06:15:44 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 06:15:48 INFO - Return code: 0 06:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:15:48 INFO - https://queue.taskcluster.net/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:15:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:15:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:15:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/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 06:15:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/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 06:15:49 INFO - Downloaded 10840359 bytes. 06:15:49 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'] 06:15:49 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 06:15:49 INFO - Return code: 0 06:15:49 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')]} 06:15:49 INFO - https://queue.taskcluster.net/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:15:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:15:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:15:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 06:15:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EMGsX6SYSi-yjDuBJovMfw/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 06:15:54 INFO - Downloaded 110141377 bytes. 06:15:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:15:54 INFO - mkdir: /builds/slave/test/properties 06:15:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:15:54 INFO - Writing to file /builds/slave/test/properties/build_url 06:15:54 INFO - Contents: 06:15:54 INFO - build_url:https://queue.taskcluster.net/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:15:56 INFO - Running post-action listener: _resource_record_post_action 06:15:56 INFO - Running post-action listener: set_extra_try_arguments 06:15:56 INFO - ##### 06:15:56 INFO - ##### Finished download-and-extract step (success) 06:15:56 INFO - ##### 06:15:56 INFO - ##### 06:15:56 INFO - ##### Running populate-webroot step. 06:15:56 INFO - ##### 06:15:56 INFO - Running pre-action listener: _resource_record_pre_action 06:15:56 INFO - Running main action method: populate_webroot 06:15:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:15:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:15:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:15:56 INFO - '--tppagecycles', 06:15:56 INFO - '7'], 06:15:56 INFO - 'tests': ['tsvgm']}}, 06:15:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:15:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:15:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:15:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:15:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:15:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:15:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:56 INFO - 'talos_options': ['--disable-e10s'], 06:15:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:15:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:15:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:56 INFO - 'talos_options': ['--disable-e10s'], 06:15:56 INFO - 'tests': ['damp', 'tps']}, 06:15:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:56 INFO - 'tests': ['damp', 'tps']}, 06:15:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:15:56 INFO - 'tests': ['dromaeo_dom']}, 06:15:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:15:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:15:56 INFO - 'tests': ['a11yr', 06:15:56 INFO - 'ts_paint', 06:15:56 INFO - 'tpaint', 06:15:56 INFO - 'sessionrestore', 06:15:56 INFO - 'sessionrestore_no_auto_restore', 06:15:56 INFO - 'tabpaint']}, 06:15:56 INFO - 'other-e10s': {'tests': ['a11yr', 06:15:56 INFO - 'ts_paint', 06:15:56 INFO - 'tpaint', 06:15:56 INFO - 'sessionrestore', 06:15:56 INFO - 'sessionrestore_no_auto_restore', 06:15:56 INFO - 'tabpaint']}, 06:15:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:15:56 INFO - 'ts_paint', 06:15:56 INFO - 'tpaint', 06:15:56 INFO - 'sessionrestore', 06:15:56 INFO - 'sessionrestore_no_auto_restore', 06:15:56 INFO - 'tabpaint']}, 06:15:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:15:56 INFO - 'ts_paint', 06:15:56 INFO - 'tpaint', 06:15:56 INFO - 'sessionrestore', 06:15:56 INFO - 'sessionrestore_no_auto_restore', 06:15:56 INFO - 'tabpaint']}, 06:15:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:15:56 INFO - 'tests': ['a11yr', 06:15:56 INFO - 'ts_paint', 06:15:56 INFO - 'tpaint', 06:15:56 INFO - 'sessionrestore', 06:15:56 INFO - 'sessionrestore_no_auto_restore', 06:15:56 INFO - 'tabpaint']}, 06:15:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:15:56 INFO - 'tests': ['a11yr', 06:15:56 INFO - 'ts_paint', 06:15:56 INFO - 'tpaint', 06:15:56 INFO - 'sessionrestore', 06:15:56 INFO - 'sessionrestore_no_auto_restore', 06:15:56 INFO - 'tabpaint']}, 06:15:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:15:56 INFO - 'tests': ['tsvgx', 06:15:56 INFO - 'tsvgr_opacity', 06:15:56 INFO - 'tart', 06:15:56 INFO - 'tscrollx', 06:15:56 INFO - 'cart']}, 06:15:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:15:56 INFO - 'tsvgr_opacity', 06:15:56 INFO - 'tart', 06:15:56 INFO - 'tscrollx', 06:15:56 INFO - 'cart']}, 06:15:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:56 INFO - 'talos_options': ['--disable-e10s'], 06:15:56 INFO - 'tests': ['tp5o']}, 06:15:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:56 INFO - 'tests': ['tp5o']}, 06:15:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:56 INFO - 'talos_options': ['--disable-e10s', 06:15:56 INFO - '--xperf_path', 06:15:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:15:56 INFO - 'tests': ['tp5n']}, 06:15:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:56 INFO - 'talos_options': ['--xperf_path', 06:15:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:15:56 INFO - 'tests': ['tp5n']}}, 06:15:56 INFO - 'talos.zip': {'path': '', 06:15:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:15:56 INFO - Running post-action listener: _resource_record_post_action 06:15:56 INFO - ##### 06:15:56 INFO - ##### Finished populate-webroot step (success) 06:15:56 INFO - ##### 06:15:56 INFO - ##### 06:15:56 INFO - ##### Running create-virtualenv step. 06:15:56 INFO - ##### 06:15:56 INFO - Running pre-action listener: _resource_record_pre_action 06:15:56 INFO - Running main action method: create_virtualenv 06:15:56 INFO - Creating virtualenv /builds/slave/test/build/venv 06:15: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 06:15:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:15:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:15:56 INFO - Using real prefix '/tools/python27' 06:15:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:15:56 INFO - Installing distribute.............................................................................................................................................................................................done. 06:15:59 INFO - Installing pip.................done. 06:15:59 INFO - Return code: 0 06:15:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:15: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')]} 06:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15: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')]} 06:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15: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 0x102af2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102af8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6a85ac4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102996d30>, '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 0x1027badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10298db20>, '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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:15: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 06:15: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 06:15:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K78Mr5ToRn/Render', 06:15:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:59 INFO - 'HOME': '/Users/cltbld', 06:15:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:59 INFO - 'LOGNAME': 'cltbld', 06:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:59 INFO - 'MOZ_NO_REMOTE': '1', 06:15:59 INFO - 'NO_EM_RESTART': '1', 06:15:59 INFO - 'PAGER': '/bin/cat', 06:15:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:59 INFO - 'PWD': '/builds/slave/test', 06:15:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:59 INFO - 'SHELL': '/bin/bash', 06:15:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mzTZUVLZG0/Listeners', 06:15:59 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:15:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:59 INFO - 'USER': 'cltbld', 06:15:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:59 INFO - 'XPC_FLAGS': '0x0', 06:15:59 INFO - 'XPC_SERVICE_NAME': '0', 06:15:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16: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 06:16:00 INFO - Cleaning up... 06:16:00 INFO - Return code: 0 06:16:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:16: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')]} 06:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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')]} 06:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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 0x102af2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102af8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6a85ac4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102996d30>, '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 0x1027badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10298db20>, '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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:16: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 06:16: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 06:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K78Mr5ToRn/Render', 06:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:00 INFO - 'HOME': '/Users/cltbld', 06:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:00 INFO - 'LOGNAME': 'cltbld', 06:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:00 INFO - 'MOZ_NO_REMOTE': '1', 06:16:00 INFO - 'NO_EM_RESTART': '1', 06:16:00 INFO - 'PAGER': '/bin/cat', 06:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:00 INFO - 'PWD': '/builds/slave/test', 06:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:00 INFO - 'SHELL': '/bin/bash', 06:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mzTZUVLZG0/Listeners', 06:16:00 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:00 INFO - 'USER': 'cltbld', 06:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:00 INFO - 'XPC_FLAGS': '0x0', 06:16:00 INFO - 'XPC_SERVICE_NAME': '0', 06:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:00 INFO - Downloading/unpacking psutil>=3.1.1 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:16:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:16:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:16:03 INFO - Installing collected packages: psutil 06:16:03 INFO - Running setup.py install for psutil 06:16:03 INFO - building 'psutil._psutil_osx' extension 06:16: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 06:16: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:16: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:16:03 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 06:16:03 INFO - building 'psutil._psutil_posix' extension 06:16:03 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 06:16:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:16:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:16:03 INFO - ^ 06:16:04 INFO - 1 warning generated. 06:16: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 06:16:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:16:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:16:04 INFO - Successfully installed psutil 06:16:04 INFO - Cleaning up... 06:16:04 INFO - Return code: 0 06:16:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:16: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')]} 06:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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')]} 06:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102af2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102af8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6a85ac4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102996d30>, '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 0x1027badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10298db20>, '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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:16: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.3'] in /builds/slave/test/build 06:16: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.3 06:16:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K78Mr5ToRn/Render', 06:16:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:04 INFO - 'HOME': '/Users/cltbld', 06:16:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:04 INFO - 'LOGNAME': 'cltbld', 06:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:04 INFO - 'MOZ_NO_REMOTE': '1', 06:16:04 INFO - 'NO_EM_RESTART': '1', 06:16:04 INFO - 'PAGER': '/bin/cat', 06:16:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:04 INFO - 'PWD': '/builds/slave/test', 06:16:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:04 INFO - 'SHELL': '/bin/bash', 06:16:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mzTZUVLZG0/Listeners', 06:16:04 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:16:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:04 INFO - 'USER': 'cltbld', 06:16:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:04 INFO - 'XPC_FLAGS': '0x0', 06:16:04 INFO - 'XPC_SERVICE_NAME': '0', 06:16:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:16:08 INFO - Installing collected packages: mozsystemmonitor 06:16:08 INFO - Running setup.py install for mozsystemmonitor 06:16:08 INFO - Successfully installed mozsystemmonitor 06:16:08 INFO - Cleaning up... 06:16:08 INFO - Return code: 0 06:16:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102af2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102af8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6a85ac4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102996d30>, '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 0x1027badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10298db20>, '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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:16:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K78Mr5ToRn/Render', 06:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:08 INFO - 'HOME': '/Users/cltbld', 06:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:08 INFO - 'LOGNAME': 'cltbld', 06:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:08 INFO - 'MOZ_NO_REMOTE': '1', 06:16:08 INFO - 'NO_EM_RESTART': '1', 06:16:08 INFO - 'PAGER': '/bin/cat', 06:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:08 INFO - 'PWD': '/builds/slave/test', 06:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:08 INFO - 'SHELL': '/bin/bash', 06:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mzTZUVLZG0/Listeners', 06:16:08 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:08 INFO - 'USER': 'cltbld', 06:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:08 INFO - 'XPC_FLAGS': '0x0', 06:16:08 INFO - 'XPC_SERVICE_NAME': '0', 06:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:08 INFO - Downloading/unpacking blobuploader==1.2.4 06:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:16:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:10 INFO - Downloading blobuploader-1.2.4.tar.gz 06:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:16:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:16:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:12 INFO - Downloading docopt-0.6.1.tar.gz 06:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:16:12 INFO - Installing collected packages: blobuploader, requests, docopt 06:16:12 INFO - Running setup.py install for blobuploader 06:16:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:16:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:16:12 INFO - Running setup.py install for requests 06:16:12 INFO - Running setup.py install for docopt 06:16:12 INFO - Successfully installed blobuploader requests docopt 06:16:12 INFO - Cleaning up... 06:16:13 INFO - Return code: 0 06:16:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:16:13 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')]} 06:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:13 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')]} 06:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:13 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 0x102af2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102af8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6a85ac4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102996d30>, '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 0x1027badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10298db20>, '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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:16:13 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 06:16:13 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 06:16:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K78Mr5ToRn/Render', 06:16:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:13 INFO - 'HOME': '/Users/cltbld', 06:16:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:13 INFO - 'LOGNAME': 'cltbld', 06:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:13 INFO - 'MOZ_NO_REMOTE': '1', 06:16:13 INFO - 'NO_EM_RESTART': '1', 06:16:13 INFO - 'PAGER': '/bin/cat', 06:16:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:13 INFO - 'PWD': '/builds/slave/test', 06:16:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:13 INFO - 'SHELL': '/bin/bash', 06:16:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mzTZUVLZG0/Listeners', 06:16:13 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:16:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:13 INFO - 'USER': 'cltbld', 06:16:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:13 INFO - 'XPC_FLAGS': '0x0', 06:16:13 INFO - 'XPC_SERVICE_NAME': '0', 06:16:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:16:13 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-3KK6yX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:16:13 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-iCCvIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:16:13 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-OFaVAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:16:13 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-_iQntw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:16:13 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-EE3z2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:16:13 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-0QZx4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:16:13 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-0n2hFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:16:14 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-yZnd4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:16:14 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-myyzXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:16:14 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-_fwZWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:16:14 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-NVp9OH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:16:14 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-2LkP0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:16:14 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-L9KziU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:16:14 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-RNk986-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:14 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-qIM2zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:16:14 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-EHSEaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:16:15 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-BUJsOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:16:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:16:15 INFO - Running setup.py install for manifestparser 06:16:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:16:15 INFO - Running setup.py install for mozcrash 06:16:15 INFO - Running setup.py install for mozdebug 06:16:15 INFO - Running setup.py install for mozdevice 06:16:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:16:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:16:15 INFO - Running setup.py install for mozfile 06:16:15 INFO - Running setup.py install for mozhttpd 06:16:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:16:16 INFO - Running setup.py install for mozinfo 06:16:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:16:16 INFO - Running setup.py install for mozInstall 06:16:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:16:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:16:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:16:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:16:16 INFO - Running setup.py install for mozleak 06:16:16 INFO - Running setup.py install for mozlog 06:16:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:16:16 INFO - Running setup.py install for moznetwork 06:16:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:16:16 INFO - Running setup.py install for mozprocess 06:16:16 INFO - Running setup.py install for mozprofile 06:16:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:16:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:16:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:16:17 INFO - Running setup.py install for mozrunner 06:16:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:16:17 INFO - Running setup.py install for mozscreenshot 06:16:17 INFO - Running setup.py install for moztest 06:16:17 INFO - Running setup.py install for mozversion 06:16:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:16:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:16:17 INFO - Cleaning up... 06:16:17 INFO - Return code: 0 06:16:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:16: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')]} 06:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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')]} 06:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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 0x102af2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102af8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6a85ac4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102996d30>, '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 0x1027badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10298db20>, '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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:16: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 06:16: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 06:16:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K78Mr5ToRn/Render', 06:16:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:17 INFO - 'HOME': '/Users/cltbld', 06:16:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:17 INFO - 'LOGNAME': 'cltbld', 06:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:17 INFO - 'MOZ_NO_REMOTE': '1', 06:16:17 INFO - 'NO_EM_RESTART': '1', 06:16:17 INFO - 'PAGER': '/bin/cat', 06:16:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:17 INFO - 'PWD': '/builds/slave/test', 06:16:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:17 INFO - 'SHELL': '/bin/bash', 06:16:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mzTZUVLZG0/Listeners', 06:16:17 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:16:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:17 INFO - 'USER': 'cltbld', 06:16:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:17 INFO - 'XPC_FLAGS': '0x0', 06:16:17 INFO - 'XPC_SERVICE_NAME': '0', 06:16:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:16:17 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-MLE8nK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:16:18 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)) 06:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:16:18 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-gHqeXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:16:18 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)) 06:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:16:18 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-xDy7N_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:16:18 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)) 06:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:16:18 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-XcI5_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:16:18 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)) 06:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:16:18 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-k6wsFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:16:18 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)) 06:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:16:18 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-qgtwiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:16:18 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)) 06:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:16:18 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-w1X4yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:16: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)) 06:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:16:18 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-aVXv3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:16: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)) 06:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:16:18 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-DQS5d0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:16: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)) 06:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:16:18 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-IXjebM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:16:19 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)) 06:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:16:19 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-XeDrMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:16:19 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)) 06:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:16:19 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-iILcns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:16:19 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)) 06:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:16:19 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-1Isabr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:16:19 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)) 06:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:16:19 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-JOoem3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:16:19 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)) 06:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:19 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-2XXswT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:19 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)) 06:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:16:19 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-WXxsKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:16:19 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)) 06:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:16:19 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-s0kp2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:16: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)) 06:16: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)) 06:16: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)) 06:16: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)) 06:16: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)) 06:16: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)) 06:16:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:22 INFO - Downloading blessings-1.6.tar.gz 06:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:16:22 INFO - Installing collected packages: blessings 06:16:22 INFO - Running setup.py install for blessings 06:16:22 INFO - Successfully installed blessings 06:16:22 INFO - Cleaning up... 06:16:22 INFO - Return code: 0 06:16:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:16:22 INFO - Reading from file tmpfile_stdout 06:16:22 INFO - Current package versions: 06:16:22 INFO - blessings == 1.6 06:16:22 INFO - blobuploader == 1.2.4 06:16:22 INFO - docopt == 0.6.1 06:16:22 INFO - manifestparser == 1.1 06:16:22 INFO - mozInstall == 1.12 06:16:22 INFO - mozcrash == 0.17 06:16:22 INFO - mozdebug == 0.1 06:16:22 INFO - mozdevice == 0.48 06:16:22 INFO - mozfile == 1.2 06:16:22 INFO - mozhttpd == 0.7 06:16:22 INFO - mozinfo == 0.9 06:16:22 INFO - mozleak == 0.1 06:16:22 INFO - mozlog == 3.2 06:16:22 INFO - moznetwork == 0.27 06:16:22 INFO - mozprocess == 0.22 06:16:22 INFO - mozprofile == 0.28 06:16:22 INFO - mozrunner == 6.11 06:16:22 INFO - mozscreenshot == 0.1 06:16:22 INFO - mozsystemmonitor == 0.3 06:16:22 INFO - moztest == 0.7 06:16:22 INFO - mozversion == 1.4 06:16:22 INFO - psutil == 3.1.1 06:16:22 INFO - requests == 1.2.3 06:16:22 INFO - wsgiref == 0.1.2 06:16:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:16: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')]} 06:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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')]} 06:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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 0x102af2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102af8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6a85ac4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102996d30>, '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 0x1027badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10298db20>, '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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:16: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 06:16: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 06:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K78Mr5ToRn/Render', 06:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:22 INFO - 'HOME': '/Users/cltbld', 06:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:22 INFO - 'LOGNAME': 'cltbld', 06:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:22 INFO - 'MOZ_NO_REMOTE': '1', 06:16:22 INFO - 'NO_EM_RESTART': '1', 06:16:22 INFO - 'PAGER': '/bin/cat', 06:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:22 INFO - 'PWD': '/builds/slave/test', 06:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:22 INFO - 'SHELL': '/bin/bash', 06:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mzTZUVLZG0/Listeners', 06:16:22 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:22 INFO - 'USER': 'cltbld', 06:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:22 INFO - 'XPC_FLAGS': '0x0', 06:16:22 INFO - 'XPC_SERVICE_NAME': '0', 06:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:23 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)) 06:16:23 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)) 06:16:23 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)) 06:16:23 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)) 06:16:23 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)) 06:16:23 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)) 06:16:23 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)) 06:16:23 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)) 06:16:23 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)) 06:16:23 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)) 06:16:23 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)) 06:16:23 INFO - Cleaning up... 06:16:23 INFO - Return code: 0 06:16:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:16:23 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')]} 06:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:23 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')]} 06:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:23 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 0x102af2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102af8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6a85ac4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102996d30>, '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 0x1027badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10298db20>, '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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:16:23 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 06:16:23 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 06:16:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K78Mr5ToRn/Render', 06:16:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:23 INFO - 'HOME': '/Users/cltbld', 06:16:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:23 INFO - 'LOGNAME': 'cltbld', 06:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:23 INFO - 'MOZ_NO_REMOTE': '1', 06:16:23 INFO - 'NO_EM_RESTART': '1', 06:16:23 INFO - 'PAGER': '/bin/cat', 06:16:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:23 INFO - 'PWD': '/builds/slave/test', 06:16:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:23 INFO - 'SHELL': '/bin/bash', 06:16:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mzTZUVLZG0/Listeners', 06:16:23 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:16:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:23 INFO - 'USER': 'cltbld', 06:16:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:23 INFO - 'XPC_FLAGS': '0x0', 06:16:23 INFO - 'XPC_SERVICE_NAME': '0', 06:16:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:23 INFO - Downloading/unpacking jsonschema 06:16:23 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 06:16:23 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 06:16:23 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 06:16:23 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 06:16:23 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 06:16:23 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 06:16:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:16:25 INFO - Downloading/unpacking functools32 (from jsonschema) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:26 INFO - Downloading functools32-3.2.3-2.tar.gz 06:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:16:26 INFO - warning: no files found matching '*.txt' 06:16:26 INFO - no previously-included directories found matching 'build' 06:16:26 INFO - no previously-included directories found matching 'dist' 06:16:26 INFO - no previously-included directories found matching '.git*' 06:16:26 INFO - Installing collected packages: jsonschema, functools32 06:16:26 INFO - Running setup.py install for functools32 06:16:26 INFO - warning: no files found matching '*.txt' 06:16:26 INFO - no previously-included directories found matching 'build' 06:16:26 INFO - no previously-included directories found matching 'dist' 06:16:26 INFO - no previously-included directories found matching '.git*' 06:16:26 INFO - Successfully installed jsonschema functools32 06:16:26 INFO - Cleaning up... 06:16:26 INFO - Return code: 0 06:16:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102af2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102af8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6a85ac4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102996d30>, '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 0x1027badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10298db20>, '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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 06:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 06:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K78Mr5ToRn/Render', 06:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:26 INFO - 'HOME': '/Users/cltbld', 06:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:26 INFO - 'LOGNAME': 'cltbld', 06:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:26 INFO - 'MOZ_NO_REMOTE': '1', 06:16:26 INFO - 'NO_EM_RESTART': '1', 06:16:26 INFO - 'PAGER': '/bin/cat', 06:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:26 INFO - 'PWD': '/builds/slave/test', 06:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:26 INFO - 'SHELL': '/bin/bash', 06:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mzTZUVLZG0/Listeners', 06:16:26 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:26 INFO - 'USER': 'cltbld', 06:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:26 INFO - 'XPC_FLAGS': '0x0', 06:16:26 INFO - 'XPC_SERVICE_NAME': '0', 06:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:26 INFO - Downloading/unpacking flake8 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:16:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:16:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:16:30 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 06:16:30 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 06:16:30 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 06:16:30 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 06:16:30 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 06:16:30 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 06:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:16:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:16:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:16:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:16:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:16:30 INFO - no previously-included directories found matching 'docs/_build' 06:16:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:16:30 INFO - Running setup.py install for pep8 06:16:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:16:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:16:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:16:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:16:30 INFO - no previously-included directories found matching 'docs/_build' 06:16:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:16:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:16:30 INFO - Cleaning up... 06:16:31 INFO - Return code: 0 06:16:31 INFO - Running post-action listener: _resource_record_post_action 06:16:31 INFO - Running post-action listener: _start_resource_monitoring 06:16:31 INFO - Starting resource monitoring. 06:16:31 INFO - ##### 06:16:31 INFO - ##### Finished create-virtualenv step (success) 06:16:31 INFO - ##### 06:16:31 INFO - ##### 06:16:31 INFO - ##### Running install step. 06:16:31 INFO - ##### 06:16:31 INFO - Running pre-action listener: _resource_record_pre_action 06:16:31 INFO - Running main action method: install 06:16:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:16:31 INFO - Reading from file tmpfile_stdout 06:16:31 INFO - Detecting whether we're running mozinstall >=1.0... 06:16:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:16:31 INFO - Reading from file tmpfile_stdout 06:16:31 INFO - Output received: 06:16:31 INFO - Usage: mozinstall [options] installer 06:16:31 INFO - Options: 06:16:31 INFO - -h, --help show this help message and exit 06:16:31 INFO - -d DEST, --destination=DEST 06:16:31 INFO - Directory to install application into. [default: 06:16:31 INFO - "/builds/slave/test"] 06:16:31 INFO - --app=APP Application being installed. [default: firefox] 06:16:31 INFO - mkdir: /builds/slave/test/build/application 06:16:31 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'] 06:16:31 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 06:17:05 INFO - Reading from file tmpfile_stdout 06:17:05 INFO - Output received: 06:17:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:17:05 INFO - Running post-action listener: _resource_record_post_action 06:17:05 INFO - ##### 06:17:05 INFO - ##### Finished install step (success) 06:17:05 INFO - ##### 06:17:05 INFO - ##### 06:17:05 INFO - ##### Running run-tests step. 06:17:05 INFO - ##### 06:17:05 INFO - Running pre-action listener: _resource_record_pre_action 06:17:05 INFO - Running main action method: run_tests 06:17:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:17:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:17:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:17:05 INFO - 2016-05-24 06:17:05.069 screenresolution[2508:92277] starting screenresolution argv=screenresolution get 06:17:05 INFO - 2016-05-24 06:17:05.072 screenresolution[2508:92277] Display 0: 1600x1200x32@60 06:17:05 INFO - 2016-05-24 06:17:05.078 screenresolution[2509:92279] starting screenresolution argv=screenresolution list 06:17:05 INFO - Available Modes on Display 0 06:17:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:17:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:17:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:17:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:17:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:17:05 INFO - Intel Iris: 06:17:05 INFO - Chipset Model: Intel Iris 06:17:05 INFO - Type: GPU 06:17:05 INFO - Bus: Built-In 06:17:05 INFO - VRAM (Dynamic, Max): 1536 MB 06:17:05 INFO - Vendor: Intel (0x8086) 06:17:05 INFO - Device ID: 0x0a2e 06:17:05 INFO - Revision ID: 0x0009 06:17:05 INFO - Displays: 06:17:05 INFO - Display: 06:17:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:17:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:17:05 INFO - Display Serial Number: 1600x1200 60 06:17:05 INFO - Main Display: Yes 06:17:05 INFO - Mirror: Off 06:17:05 INFO - Online: Yes 06:17:05 INFO - Rotation: Supported 06:17:05 INFO - Return code: 0 06:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:17:05 INFO - Python 2.7.3 06:17:05 INFO - Return code: 0 06:17:05 INFO - grabbing minidump binary from tooltool 06:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:17:05 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 0x102996d30>, '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 0x1027badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10298db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:17:05 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 06:17:05 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 06:17:05 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 06:17:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:17:05 INFO - Return code: 0 06:17:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:17:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:17:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:17:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:17:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:17:06 INFO - Return code: 0 06:17:06 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/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--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 06:17:06 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/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0166 --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 06:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K78Mr5ToRn/Render', 06:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:06 INFO - 'HOME': '/Users/cltbld', 06:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:06 INFO - 'LOGNAME': 'cltbld', 06:17:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:17:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:06 INFO - 'MOZ_NO_REMOTE': '1', 06:17:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:17:06 INFO - 'NO_EM_RESTART': '1', 06:17:06 INFO - 'PAGER': '/bin/cat', 06:17:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:06 INFO - 'PWD': '/builds/slave/test', 06:17:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:06 INFO - 'SHELL': '/bin/bash', 06:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mzTZUVLZG0/Listeners', 06:17:06 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:06 INFO - 'USER': 'cltbld', 06:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:06 INFO - 'XPC_FLAGS': '0x0', 06:17:06 INFO - 'XPC_SERVICE_NAME': '0', 06:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:06 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/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--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 06:17:06 INFO - mozversion application_buildid: 20160524033112 06:17:06 INFO - mozversion application_changeset: 312c94f907b3c9c031d36c3522c9837d41f8d934 06:17:06 INFO - mozversion application_display_name: Nightly 06:17:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:17:06 INFO - mozversion application_name: Firefox 06:17:06 INFO - mozversion application_remotingname: firefox 06:17:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:17:06 INFO - mozversion application_vendor: Mozilla 06:17:06 INFO - mozversion application_version: 49.0a1 06:17:06 INFO - mozversion platform_buildid: 20160524033112 06:17:06 INFO - mozversion platform_changeset: 312c94f907b3c9c031d36c3522c9837d41f8d934 06:17:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:17:06 INFO - mozversion platform_version: 49.0a1 06:17:06 INFO - using testdate: 1464095826 06:17:06 INFO - actual date: 1464095826 06:17:06 INFO - starting webserver on 'localhost:62095' 06:17:06 INFO - SUITE-START | Running 2 tests 06:17:06 INFO - TEST-START | dromaeo_css 06:17:06 INFO - operating with platform_type : mac_ 06:17:06 INFO - Initialising browser for dromaeo_css test... 06:17:06 INFO - TEST-INFO | started process 2527 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpaerNvt/profile http://localhost:62095/getInfo.html) 06:17:09 INFO - PROCESS | 2527 | __metrics Screen width/height:1600/1200 06:17:09 INFO - PROCESS | 2527 | colorDepth:24 06:17:09 INFO - PROCESS | 2527 | Browser inner width/height: 1024/689 06:17:09 INFO - PROCESS | 2527 | __metrics 06:17:09 INFO - PROCESS | 2527 | 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" 06:17:09 ERROR - PROCESS | 2527 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:17:09 INFO - TEST-INFO | 2527: exit 0 06:17:09 INFO - Browser initialized. 06:17:09 INFO - Running cycle 1/1 for dromaeo_css test... 06:17:09 INFO - TEST-INFO | started process 2529 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpaerNvt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:17:21 INFO - PROCESS | 2529 | RSS: Main: 238854144 06:17:21 INFO - PROCESS | 2529 | 06:18:12 INFO - PROCESS | 2529 | 1464095892908 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] 06:20:23 INFO - PROCESS | 2529 | Cycle 1(1): loaded http://localhost:62095/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62095/tests/dromaeo/cssquery-ext.html) 06:20:25 INFO - PROCESS | 2529 | RSS: Main: 371347456 06:20:25 INFO - PROCESS | 2529 | 06:23:28 INFO - PROCESS | 2529 | Cycle 1(1): loaded http://localhost:62095/tests/dromaeo/cssquery-ext.html (next: http://localhost:62095/tests/dromaeo/cssquery-jquery.html) 06:23:28 INFO - PROCESS | 2529 | RSS: Main: 374431744 06:23:28 INFO - PROCESS | 2529 | 06:26:30 INFO - PROCESS | 2529 | Cycle 1(1): loaded http://localhost:62095/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62095/tests/dromaeo/cssquery-mootools.html) 06:26:31 INFO - PROCESS | 2529 | RSS: Main: 372682752 06:26:31 INFO - PROCESS | 2529 | 06:29:33 INFO - PROCESS | 2529 | Cycle 1(1): loaded http://localhost:62095/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62095/tests/dromaeo/cssquery-prototype.html) 06:29:34 INFO - PROCESS | 2529 | RSS: Main: 369287168 06:29:34 INFO - PROCESS | 2529 | 06:32:37 INFO - PROCESS | 2529 | Cycle 1(1): loaded http://localhost:62095/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62095/tests/dromaeo/cssquery-yui.html) 06:32:38 INFO - PROCESS | 2529 | RSS: Main: 342970368 06:32:38 INFO - PROCESS | 2529 | 06:35:40 INFO - PROCESS | 2529 | Cycle 1(1): loaded http://localhost:62095/tests/dromaeo/cssquery-yui.html (next: http://localhost:62095/tests/dromaeo/cssquery-dojo.html) 06:35:40 INFO - PROCESS | 2529 | RSS: Main: 428187648 06:35:40 INFO - PROCESS | 2529 | 06:35:40 INFO - PROCESS | 2529 | __start_tp_report 06:35:40 INFO - PROCESS | 2529 | _x_x_mozilla_page_load 06:35:40 INFO - PROCESS | 2529 | _x_x_mozilla_page_load_details 06:35:40 INFO - PROCESS | 2529 | |i|pagename|runs| 06:35:40 INFO - PROCESS | 2529 | |0;dojo.html;3439.3981053315665;3455.7926524408554;3484.756067075304;3491.807950562721;3519.7184225261967;5514.09017512111;5562.499375056244;5717.828465146047;5794.420557944209;5838.503727183194;5578.63738856974;5637.4644408781205;5640.323161220659;5784.652920824754;5888.322842873071;4802.807887684488;5263.552598029172;5281.207818827187;5422.620416570841;5439.728013599324;5353.03645343838;5418.91871621926;5419.512243898049;5420.756065977041;5430.456954304577;5291.867703307429;5410.053240682865;5489.34127904652;5489.615726899118;5494.615376923617;5243.68537887728;5292.206169074631;5300.098983172831;5316.813911513079;5328.46715328468;5248.580113590903;5305.549028332558;5306.602004849613;5319.973400132974;5338.3060202173465;5580.107182850725;5592.049351610195;5643.830685079413;5667.603267771257;5699.116636921284;6260.561760676755;6263.812085637438;6273.184486016834;6286.402791734778;6320;6317.4946004319545;6360.395762402564;6369.172007639024;6387;6387.93612063878;5741.770329186827;5798.362180160137;5818.7672493100645;5849.88300233993;5878.5297176225795;6331.778387756499;6349.650769207646;6354.936450635527;6362.586431882006;6363.395477429729;6188.81433556994;6222.968885155636;6226.034964580497;6230.00319948806;6235.469985051322;6099.279518885317;6197.628142311476;6274.05889116627;6279.152314437584;6358.491320694333;6187.3503282154725;6220.688965551704;6247.500199983991;6266.937330626635;6275.466585340206;6106.145139680449;6307.463865571478;6325;6333.334999825044;6358.23701155855;4922.753862306942;4942.332785074041;4950.034743225036;5000.224965130411;5042.823501177441;4405.801738921757;4453.665975051884;4454.599086082203;4465.955340446619;4494.52807455219;5403.567714582824;5796.884062318814;5804.79683211094;5808.099744539603;5854.356020837705;6173.9073913891325;6253.405926436982;6312.084747711556;6330.778422755181;6374.075759014917;5789.88420231593;5795.681237531976;5800.216970708901;5817.476427121579;5854.473097421167;5340.332458442695;5454.372747134051;5463.672179669233;5463.808766693147;5473.616846820684;6291.496680265568;6293.937060629518;6342.016987366781;6349.365063493614;6355.968220158869;5298.523132918056;6352.460040896391;6362.554621176473;6371.267304260069;6391.201099862517;6237.407446292595;6361.491080713625;6375.298717141203;6381.8723625528155;6383.042543618309;6107.249501910372;6114.084479019511;6171.814845554641;6221.346758590283;6234.781782637586;6336.809895703136;6336.841578960563;6339.144215530844;6343.365663433619;6344.333844946399;5855.180274761452;6045.395460453919;6073.514118870568;6088.360722124113;6142.785002525069;5987.251593550806;6114.206128133634;6141.126956278199;6145.170401995852;6148.44663980244;6024.578279520391;6031.215941927521;6034.879302414015;6050.7277172526465;6094.42103000232;5977.462028417463;6058.848528786815;6076.058210977306;6138.294096178996;6140.662263575547;5985.042393217064;6015.729292181775;6018.3680713524445;6028.668423236764;6052.425019623129 06:35:40 INFO - PROCESS | 2529 | |1;ext.html;482.8985912958283;483.9467658557558;488.46757034832035;494.38696016938985;499.9050180465711;32199.19502012451;41100.38349424761;41983;42021.57978420215;44501.332480012745;43281.78359108208;43634.12731745371;43776.90557736058;43786.56213437865;43848.780756096174;8508.489490630582;9207.217386522138;9375.718728438158;9376.296777741663;9377.76555586109;8541.743747687548;11484.942575287112;11754.941225293862;11758.118141139448;11770.058395328353;11520.71198220047;11582.768344633145;11594;11610.6516804496;11618.941905290461;8560;11345.716357091056;11511.712207194803;11531.538738450452;11627.244229125165;11504.65486035412;11605.535778568847;11636.069114470822;11647.010004149657;11759.823602645967;39946.40080398796;40273.395899061536;40279.3958090626;40335.7983210085;40380.788576342464;3124.7968882022824;3256.6092068951875;3300.9009729708146;3729.4965179700666;3809.0286976820735;28829.135125946254;29004.41991160165;29284.975025874206;29828.955986540368;29984;2659.5611724065507;2716.497447972112;2723.72762723728;2726.986365068182;2795.5107856125096;496724;496757;497125;497512;497903;49443.055694430266;61193.38806611877;61235.38764612297;61240.38759612436;61256;36360.81819590938;36694.449583256275;36729;36779.448308275394;64238.357616423236;16480;16682.749758753627;16683.582910427092;16878.74681879773;16897.746533802;11589.420528973518;11770.529178832921;12493.500259989498;12654.240745555297;12795.680107997374;19639.214431422584;19668.311609093613;19677.016149192485;19798.40604781859;19800.50498737514;6501.772437964649;6585.835354116185;7570.242975702386;7613.771586852293;7877.803054923558;904.2810965282484;979.8775153105862;994.433173091331;1009.5608410341525;1029.9279050466396;26979;29408.117756466912;29467.705322946924;30154.69845301519;30193.547096793565;1055.2349546578823;1098.5990113608475;1132.5300000499865;1166.1079274355125;1220.9145359824904;14518.491852785102;14776;14860.03409778361;15016.948813583163;15061.535846755307;687331;687717;696343;697456;697841;59089;59513.999999999134;59578.404215957285;59690;59859.700701496215;1278.39276343736;1398.5314919501877;1422.054333867987;1445.38571107279;1454.4763885001603;29853.276494497524;29979;30055.156209553388;30360.089197324116;30467.695323046486;57018;58522.53693657693;58537.70731146317;61898.45253868689;62275.377246226955;617292;633581.8320908366;633627;633866;634745.8262708657;34533.82733086419;39810;40395.1920961585;40847.38728919128;42966;45790;45852.31221531746;46021.309680355494;47609.285860711425;47640.761796190796;37597.62402376051;37685;37820;38291;38364.61635383722;31716.841415793697;37101;37306.440403393426;37519.24961500809;38327.42508862314;33476.33047339088;34569.48145777866;36869.63130368662;37491.43762843505;37690.811545942095;32707.34585308328;32913.83543082269;33358.33283334368;33440.163995899326;33532;24251.51496970086;24339.75660243375;24753;24964.875175624005;25335.98656053737 06:35:40 INFO - PROCESS | 2529 | |2;jquery.html;383.065320617693;388.81336958260033;393.5867339293741;394.81246407956206;406.9104796944673;19402.320918767848;20910.372688819323;21103.256576645013;21152.89423552882;21158;18579.81420185798;18678.53303667409;19280.3251886184;19301.420957371265;19585.314513992013;5185.974070129663;5777.537796976242;5979.282486101674;5981.162637230791;6039.365866584011;5794.304683437993;5961.731722072495;5985.401459854023;5999.85000374992;6000.33996260411;5735.885282294352;5873.647581145145;5896.911546326808;5928.584999050068;5939.851503712399;5587.608867379285;5818.5345172386105;5836.416358364172;5861.531077513789;5874.76500939964;5080;5097.7960881564695;5115.258287548322;5143.691378517301;5148.742562871841;24610;24687;24772.752272477224;24807.751922480726;25135;2031.3093548193685;2097.3603051069417;2307.976920230793;2733.4669739400824;2767.2805070681566;14145.222012789292;14623;14652.267386630732;15091.849081509265;15275.770863437057;1666.2585149608428;1712.922918468672;1727.5594723345466;1741.2947756158776;1779.226036674038;101052.49473752585;119506;120346;120928;122038.16942745866;10196.4391958443;10566.52450639723;10641.5211315491;10673.252872298865;10893.782124357469;13236.669083272802;13302.26837523926;13365.599032029055;13378.531751388655;13392.933035334761;7003;8324.375671824637;8422.326213902874;8448.197421244971;8520.105388934073;8486.19381158789;8490.660373585111;8492.065872753992;8508.404411691123;8686.391952563386;5526.392096869341;5629.986602411524;6136.2636483622255;6492.67536623167;6496;1851.261725985367;1855.6381505606548;1856.5637075287298;1860.9720854187199;1863.2733234038735;777.7511196417205;896.9282457403392;928.3501548915785;971.4948226921961;1068.823644098715;18236.361727339743;18545.072746362628;20548.486287842923;20758.74806569173;20941.790582094287;944.9291303152291;1003.4481035430484;1006.6828948881079;1016.7051555049101;1068.5245065945735;9701.07839755222;10181.389116652876;10482.754484433817;11211;11216.214864959538;573581;579779.2022079809;580639;597803;604960;91767;101612;110839;115590;118524;993.1161266472845;1005.607812952949;1017.6082208349839;1026.7484466305802;1056.424248784399;18032;18254.908725456287;18289.725654114933;18481.630367392307;18625.813741862406;48782;48873.511264886896;50016.749916250184;51044;51497.88016958709;69620.3037969634;70863.29136708567;71148;72155;77122.6143869277;7507.587082710504;7709.3447057000785;7749.535027898119;7842.372610191285;8095.71664991746;12279.570215042431;12407.31759753186;12592.937035314764;12758.74482510326;12801.871981280068;10500.317479363817;10698.89301106979;10805.297655652357;10815.891841081488;10939.890601093886;10276.948615257175;10445.11216546602;10521;10653.786924261316;10918.12654987615;10881;11059.68767728632;11094.611688590861;11174.72063198394;11238.66284011481;7606.163322034682;7687.769366919001;7700.576468294297;7836.725714599962;8035.116137224783;9624.75938101525;10693.304935179189;10694.304870183412;10818.729531761768;11044.889551104387 06:35:40 INFO - PROCESS | 2529 | |3;mootools.html;1400.2018849255928;1571.5599632103008;1574.9763753543698;1807.6926922423186;1943.036868775117;7820.648070836812;8629.913700862997;8841.867371989418;8920.643174273038;9033.503157326344;8558.27254683351;8830.249428798545;8831.602577883994;8853.11468853116;8857.734267971971;2002.7883130705932;2355.493568882694;2365.3495288795634;2391.964120538193;2398.388410955204;2328.976710232893;2346.636271377931;2374.5963186258377;2387.5583017141885;2391.294568102414;2342.906283748648;2359.5988681924027;2376.013954209001;2390.880455977203;2391.5575618510543;1990.2934458267252;2336.3925379401308;2354.2701762453607;2371.27676058802;2372.2445390411144;2336.3575016870327;2343.1447521654645;2344.695189625355;2348.2955113466064;2350.189184731265;8548.658053677846;8705.042445331008;8795.648174073029;8795.956020219923;8799.560021998874;5183.637145399824;5386.1920711893135;5387.084195686703;5392.487713667196;5396.325459317585;3398.558187435642;3483.2336885885065;3549;3555.4489054196642;3595.083253770298;975.1954637424153;1013.5084484025242;1017.4048181813648;1023.4268809466722;1026.7741096958657;11608.419579021016;11729.472173752116;11777.705557361049;11779.764404711943;11817.290962542274;4437.64498840092;4596.517365676623;4606.608438282717;4692.483826779052;4715.245560710338;9195.494247816434;9217.124373184537;9220.985691573922;9222.40054396462;9231.307651926132;5265.605079619044;5269.288646032813;5273.419923808378;5274.129768588216;5282.418933917266;4358.389825424443;4400.471943366816;4559.749213793273;4783.976080119577;4832.806687732522;3796.677282430969;3797.9430308545393;3830.3105441020325;3864.30442520349;3906.0625449891822;1006.9597216111273;1121.4579952565223;1145.8109411947103;1162.95900694281;1164.6040346282305;793.9682412703543;864.822711344173;892.7857314244535;936.9786932243887;940.1867384712175;5518.91721624176;5690.459406356389;5837.824865254049;5868.618539794899;5953.613015154001;878.3939082033377;919.1360121485782;927.8422668146432;936.2229349639782;993.4933184076173;3765.3410653135593;3853.2486165197515;3867.9806600966817;3938.3895496198134;3941.073847645801;10488.947555262175;10669.733256668645;10736.141108711285;10883.945580272206;10897.182711296684;10813.837792432962;10823.837642435212;10827.566897324019;10833.566657333618;10844.72888177786;7868.488548244346;8128.796780080545;8288.295494882881;8288.792780180424;8325.209105135002;5484.91772623403;5493.533049690821;5597.692126932895;5656.604037717485;5837.970810145922;9806.705798826046;9838.704838854846;10111.292209545261;10113.64602238918;10122.595096196364;9881.357711748713;10221.43782091992;10233.590656373959;10252.282340236112;10289.9485502572;6731.259561448139;6920.238773734784;7109.431245500451;7299.306565876233;7332.23011583776;8416.789580260298;8435.367347449088;8441.11368306319;8539.701110461352;8549.444286121383;7196.784096477114;7367.26327367259;7465.477416580788;7510.474266801271;7662.578558179353;7251.56490610565;7312.561246325237;7329.890051649123;7471.701131954878;7528.4353673473615;6890.552114112566;6975.337342952412;6978.825529361807;6983.6857341418745;6999.405050570758;4255.468066491689;4680.859574212779;4792.400949881265;4941.456439791548;5089.312942752681;4929.802807887644;4995.575376092927;5010.799568017384;5021.096202683552;5033.874153146201 06:35:40 INFO - PROCESS | 2529 | |4;prototype.html;284.5816649525199;310.77934666386864;316.8024866006495;327.4907518808252;327.6134161689206;4606.677532572721;5438.320209973754;5439.292891924049;5460.31746031746;5485.807996720116;5352.277442545255;5358.866028349294;5391.78432862685;5402.702851343174;5427.538659213971;3885.5725870154333;3956.9604303957026;3984.3625019996807;3991.381335892941;4044.6966477514156;3487.4071407860597;4025.5571887092397;4082.4896887889013;4099.462701486942;4137.710360274782;3742.569604495477;4049.6107296694945;4063.8214917673986;4068.1456894052285;4104.42538044674;3547.8935631931085;3790.886273411802;3852.7653960702864;4125.566815484391;4126.174765047002;3553.680168784822;3559.501669766209;3572.5534308211472;3576.5708115026105;3579.8031108289033;5062.7468626568525;5112.693238405707;5121.436641969381;5128;5166.43169251382;4162.4796900387455;4262.870339360072;4288.785560721952;4458.305843779343;4527.845399423126;4693.389859318301;4850.563449289581;5568.888622227573;5609.1305847593685;5666.348369937468;3949.308870947573;3963.4451176835264;3972.3117674988016;3976.403539469074;4010.5387880393837;5246.868828279324;5289.3917199521775;5504.2018907258225;5583.274174357348;5960.959910445704;3526.1713497328105;3978.5822488638855;3984.103576695278;3991.7604943703473;4003.4386589230226;3447.7069449097107;3834.9041273967814;4032.536258330271;4052.858149964796;4112.568180341081;3966.940495892564;4039.6768258539246;4656.998745269783;4663.27719203524;4758.500357462486;4988.804925832622;5279.8152064678325;5657.264555607827;5675.956655603979;5694.06048002075;1358.8844948179515;1387.993704564203;1396.6717821311984;1400.3138462153472;1404.3399602186955;815.4943934760487;851.586980314547;859.6604341285157;865.8831057807241;884.3588398411229;4936.185529387682;5017.738469994532;5096.923546146811;5222.268882356473;5246.475352464723;5074.467180945948;5498.5876059295715;5739.163467690382;5783.248177736868;5813.82558523245;4185.58144185579;4477.462704475483;5141.177411614199;5217.765200565984;5275.683458992473;3029.3184033592265;3029.909102726922;3032.3632037271977;3043.269615292314;3054.40439114375;5202.635815492879;5746.942530574641;5977.581569290108;5988;6034.280230134478;4963.726995015309;5472.480114389126;5526.58550608706;5673.716314184274;5930;5601.411851755507;5943.524518038546;5978.880422391528;5990.970045149834;6006.969965150146;5215.713135777569;5534.584906131976;5643.153526970823;5788.768449262151;5818.9127163093435;4989.975050124872;5334.653247538898;5601.29983752031;5700.489370316786;5924.081767326072;5102.515261050195;5424.810131645373;5547.639403438763;6032.728527216374;6037.486813620892;3564.7326450516834;3799.221159662318;3959.604039596017;3979.164375481181;3979.9801000994808;4845.030993801183;5304.310439642822;5667;5704.771809127588;5717.42825717425;4609.677322587387;4987.775550100182;5544.556435485071;5566.443355664401;5579.19101730255;4219.810108545062;4834.685745426536;4897.804087836446;5482.122860342485;5611.186377975089;4765.499622539722;5294.602904782079;5495.313085864267;5543.667379957054;5581.441855814386;4561.794719237576;5265.552428043506;5361.571074313967;5672.319321681424;5728;4953.811085339565;5358.785648574013;5555.111262170882;5564.693941833076;5577.163425486047 06:35:40 INFO - PROCESS | 2529 | |5;yui.html;650.261952683704;653.9476841852652;709.4430871765665;733.9926600733991;740.8518296340732;14674.192919389443;14873.776893346594;15058.247087645601;15069.171195584238;15143.167125808077;13145.079844410895;13375.799363009563;13430.462781488754;13441.05912586117;13947.721045579083;887.8224355128967;909.5497728624324;926.4996901673088;927.3369540778342;930.6649606141783;910.6038873090226;914.8764916736222;919.3334832246635;920.2775820980531;921.9996304010157;967.6032826541119;969.8254314223437;969.849673300636;971.480258061937;976.3360914578084;920.4615300049479;928.7724507495673;964.8022155458115;966.1546147121269;968.3463662028103;703.2580627438102;718.2314923032358;721.0698199322867;722.5484072454716;727.7052793618593;13035.217886926815;13040.087193896432;13049.86950130496;13068.673283167902;13099.738005239937;11705.648830535098;11763.7059073523;11780;11896.167268291223;12090.516379344816;10568.841467377995;10931.836022459669;11103.333799972028;11180.440977951152;11449.713757156138;4019.2966230909474;4025.798710064485;4061.512618485771;4075.449814275094;4256.59562341577;63529.364706352804;69459.61080778357;75348.62325688337;81745.59127204385;82151.17848821495;9066.909330906738;9079.773005674911;9085.500297483703;9189.127032931861;9225.907740922505;20610.89694551518;20685.379438616867;20700.275490358068;20722.274720384714;20741.79258207429;7080.114985626797;7082.504224704324;7120.857582848417;7267.7819665410125;7322.487425880244;3816.1222918728295;3885.048163200034;3904.7657140571655;3923.2545816294814;3940.271049855786;3388.5933687957604;3483.7213022958103;3492.4109530163714;3492.89521314361;3509.754317197772;611.211537117114;613.1783410230313;613.6502193749609;633.8066889598624;639.3702203329716;556.6799090522966;581.16022347708;588.2852334413708;600.2916558461058;602.0938487576258;7791.805204869924;7977.401694872908;7991.160928102582;8228.21831925978;8239.505629662239;578.1934201788481;582.3652219081138;583.1398686936734;586.3667239381458;609.844489655144;3272.4927636216426;3731.962680373216;3817.9618203818163;3853.7880416576795;4029.5567487576345;85771.28457430817;86330.13669863346;87694;88761.66857497142;90670.54664726766;78488.60755696186;78535;78628.60685696534;80612;82093;54122.72938635282;55204.51579678924;71796.64101679562;72982.27017729754;74969.62515187389;7630.8092297693;7835;8153.714619988272;8173.536915962481;8248.092709801796;42318.57681423269;42472.57527424686;42810.78594607007;43097.569024309356;43613.56386436095;58073.128903067336;60300.69849650723;60602.09096863462;60678;60830;11353.716157096138;11516.712082198012;11666.88333116658;11707.648770536898;11800.52797888075;10217.92711765254;11535.480903359537;11992.940035299767;12022.819657704966;12429.937850310691;11286.097112230838;11777.646670599896;11798.233114847508;11802.52789888395;11831.290122592673;9327.673531426368;11454.656360309204;11619.269471674319;11701.824472633087;12410.069244806857;10344.741381465523;10861.185411094042;10882.836757448804;11049;11171.162162837982;9160.31297652692;9324.766880828034;9393.859092113487;9455.479948602893;9551.665691700757;8678.82642347137;8822.602982865657;8926.955365223392;9012.954935225544;9597.616095356108 06:35:40 INFO - PROCESS | 2529 | __end_tp_report 06:35:40 INFO - PROCESS | 2529 | __start_cc_report 06:35:40 INFO - PROCESS | 2529 | _x_x_mozilla_cycle_collect,1428 06:35:40 INFO - PROCESS | 2529 | __end_cc_report 06:35:40 INFO - PROCESS | 2529 | __startTimestamp1464096940959__endTimestamp 06:35:40 INFO - PROCESS | 2529 | 06:35:40 INFO - PROCESS | 2529 | ------- Summary: start ------- 06:35:40 INFO - PROCESS | 2529 | Number of tests: 6 06:35:40 INFO - PROCESS | 2529 | 06:35:40 INFO - PROCESS | 2529 | [#0] dojo.html Cycles:165 Average:5797.82 Median:6024.58 stddev:627.02 (10.4%) stddev-sans-first:601.02 06:35:40 INFO - PROCESS | 2529 | Values: 3439.4 3455.8 3484.8 3491.8 3519.7 5514.1 5562.5 5717.8 5794.4 5838.5 5578.6 5637.5 5640.3 5784.7 5888.3 4802.8 5263.6 5281.2 5422.6 5439.7 5353.0 5418.9 5419.5 5420.8 5430.5 5291.9 5410.1 5489.3 5489.6 5494.6 5243.7 5292.2 5300.1 5316.8 5328.5 5248.6 5305.5 5306.6 5320.0 5338.3 5580.1 5592.0 5643.8 5667.6 5699.1 6260.6 6263.8 6273.2 6286.4 6320.0 6317.5 6360.4 6369.2 6387.0 6387.9 5741.8 5798.4 5818.8 5849.9 5878.5 6331.8 6349.7 6354.9 6362.6 6363.4 6188.8 6223.0 6226.0 6230.0 6235.5 6099.3 6197.6 6274.1 6279.2 6358.5 6187.4 6220.7 6247.5 6266.9 6275.5 6106.1 6307.5 6325.0 6333.3 6358.2 4922.8 4942.3 4950.0 5000.2 5042.8 4405.8 4453.7 4454.6 4466.0 4494.5 5403.6 5796.9 5804.8 5808.1 5854.4 6173.9 6253.4 6312.1 6330.8 6374.1 5789.9 5795.7 5800.2 5817.5 5854.5 5340.3 5454.4 5463.7 5463.8 5473.6 6291.5 6293.9 6342.0 6349.4 6356.0 5298.5 6352.5 6362.6 6371.3 6391.2 6237.4 6361.5 6375.3 6381.9 6383.0 6107.2 6114.1 6171.8 6221.3 6234.8 6336.8 6336.8 6339.1 6343.4 6344.3 5855.2 6045.4 6073.5 6088.4 6142.8 5987.3 6114.2 6141.1 6145.2 6148.4 6024.6 6031.2 6034.9 6050.7 6094.4 5977.5 6058.8 6076.1 6138.3 6140.7 5985.0 6015.7 6018.4 6028.7 6052.4 06:35:40 INFO - PROCESS | 2529 | 06:35:40 INFO - PROCESS | 2529 | [#1] ext.html Cycles:180 Average:73453.62 Median:36754.22 stddev:164010.29 (446.2%) stddev-sans-first:164378.87 06:35:40 INFO - PROCESS | 2529 | Values: 482.9 483.9 488.5 494.4 499.9 32199.2 41100.4 41983.0 42021.6 44501.3 43281.8 43634.1 43776.9 43786.6 43848.8 8508.5 9207.2 9375.7 9376.3 9377.8 8541.7 11484.9 11754.9 11758.1 11770.1 11520.7 11582.8 11594.0 11610.7 11618.9 8560.0 11345.7 11511.7 11531.5 11627.2 11504.7 11605.5 11636.1 11647.0 11759.8 39946.4 40273.4 40279.4 40335.8 40380.8 3124.8 3256.6 3300.9 3729.5 3809.0 28829.1 29004.4 29285.0 29829.0 29984.0 2659.6 2716.5 2723.7 2727.0 2795.5 496724.0 496757.0 497125.0 497512.0 497903.0 49443.1 61193.4 61235.4 61240.4 61256.0 36360.8 36694.4 36729.0 36779.4 64238.4 16480.0 16682.7 16683.6 16878.7 16897.7 11589.4 11770.5 12493.5 12654.2 12795.7 19639.2 19668.3 19677.0 19798.4 19800.5 6501.8 6585.8 7570.2 7613.8 7877.8 904.3 979.9 994.4 1009.6 1029.9 26979.0 29408.1 29467.7 30154.7 30193.5 1055.2 1098.6 1132.5 1166.1 1220.9 14518.5 14776.0 14860.0 15016.9 15061.5 687331.0 687717.0 696343.0 697456.0 697841.0 59089.0 59514.0 59578.4 59690.0 59859.7 1278.4 1398.5 1422.1 1445.4 1454.5 29853.3 29979.0 30055.2 30360.1 30467.7 57018.0 58522.5 58537.7 61898.5 62275.4 617292.0 633581.8 633627.0 633866.0 634745.8 34533.8 39810.0 40395.2 40847.4 42966.0 45790.0 45852.3 46021.3 47609.3 47640.8 37597.6 37685.0 37820.0 38291.0 38364.6 31716.8 37101.0 37306.4 37519.2 38327.4 33476.3 34569.5 36869.6 37491.4 37690.8 32707.3 32913.8 33358.3 33440.2 33532.0 24251.5 24339.8 24753.0 24964.9 25336.0 06:35:40 INFO - PROCESS | 2529 | 06:35:40 INFO - PROCESS | 2529 | [#2] jquery.html Cycles:180 Average:33825.65 Median:21128.08 stddev:97582.61 (461.9%) stddev-sans-first:97824.04 06:35:40 INFO - PROCESS | 2529 | Values: 383.1 388.8 393.6 394.8 406.9 19402.3 20910.4 21103.3 21152.9 21158.0 18579.8 18678.5 19280.3 19301.4 19585.3 5186.0 5777.5 5979.3 5981.2 6039.4 5794.3 5961.7 5985.4 5999.9 6000.3 5735.9 5873.6 5896.9 5928.6 5939.9 5587.6 5818.5 5836.4 5861.5 5874.8 5080.0 5097.8 5115.3 5143.7 5148.7 24610.0 24687.0 24772.8 24807.8 25135.0 2031.3 2097.4 2308.0 2733.5 2767.3 14145.2 14623.0 14652.3 15091.8 15275.8 1666.3 1712.9 1727.6 1741.3 1779.2 101052.5 119506.0 120346.0 120928.0 122038.2 10196.4 10566.5 10641.5 10673.3 10893.8 13236.7 13302.3 13365.6 13378.5 13392.9 7003.0 8324.4 8422.3 8448.2 8520.1 8486.2 8490.7 8492.1 8508.4 8686.4 5526.4 5630.0 6136.3 6492.7 6496.0 1851.3 1855.6 1856.6 1861.0 1863.3 777.8 896.9 928.4 971.5 1068.8 18236.4 18545.1 20548.5 20758.7 20941.8 944.9 1003.4 1006.7 1016.7 1068.5 9701.1 10181.4 10482.8 11211.0 11216.2 573581.0 579779.2 580639.0 597803.0 604960.0 91767.0 101612.0 110839.0 115590.0 118524.0 993.1 1005.6 1017.6 1026.7 1056.4 18032.0 18254.9 18289.7 18481.6 18625.8 48782.0 48873.5 50016.7 51044.0 51497.9 69620.3 70863.3 71148.0 72155.0 77122.6 7507.6 7709.3 7749.5 7842.4 8095.7 12279.6 12407.3 12592.9 12758.7 12801.9 10500.3 10698.9 10805.3 10815.9 10939.9 10276.9 10445.1 10521.0 10653.8 10918.1 10881.0 11059.7 11094.6 11174.7 11238.7 7606.2 7687.8 7700.6 7836.7 8035.1 9624.8 10693.3 10694.3 10818.7 11044.9 06:35:40 INFO - PROCESS | 2529 | 06:35:40 INFO - PROCESS | 2529 | [#3] mootools.html Cycles:180 Average:5662.39 Median:4968.52 stddev:3205.49 (64.5%) stddev-sans-first:3198.48 06:35:40 INFO - PROCESS | 2529 | Values: 1400.2 1571.6 1575.0 1807.7 1943.0 7820.6 8629.9 8841.9 8920.6 9033.5 8558.3 8830.2 8831.6 8853.1 8857.7 2002.8 2355.5 2365.3 2392.0 2398.4 2329.0 2346.6 2374.6 2387.6 2391.3 2342.9 2359.6 2376.0 2390.9 2391.6 1990.3 2336.4 2354.3 2371.3 2372.2 2336.4 2343.1 2344.7 2348.3 2350.2 8548.7 8705.0 8795.6 8796.0 8799.6 5183.6 5386.2 5387.1 5392.5 5396.3 3398.6 3483.2 3549.0 3555.4 3595.1 975.2 1013.5 1017.4 1023.4 1026.8 11608.4 11729.5 11777.7 11779.8 11817.3 4437.6 4596.5 4606.6 4692.5 4715.2 9195.5 9217.1 9221.0 9222.4 9231.3 5265.6 5269.3 5273.4 5274.1 5282.4 4358.4 4400.5 4559.7 4784.0 4832.8 3796.7 3797.9 3830.3 3864.3 3906.1 1007.0 1121.5 1145.8 1163.0 1164.6 794.0 864.8 892.8 937.0 940.2 5518.9 5690.5 5837.8 5868.6 5953.6 878.4 919.1 927.8 936.2 993.5 3765.3 3853.2 3868.0 3938.4 3941.1 10488.9 10669.7 10736.1 10883.9 10897.2 10813.8 10823.8 10827.6 10833.6 10844.7 7868.5 8128.8 8288.3 8288.8 8325.2 5484.9 5493.5 5597.7 5656.6 5838.0 9806.7 9838.7 10111.3 10113.6 10122.6 9881.4 10221.4 10233.6 10252.3 10289.9 6731.3 6920.2 7109.4 7299.3 7332.2 8416.8 8435.4 8441.1 8539.7 8549.4 7196.8 7367.3 7465.5 7510.5 7662.6 7251.6 7312.6 7329.9 7471.7 7528.4 6890.6 6975.3 6978.8 6983.7 6999.4 4255.5 4680.9 4792.4 4941.5 5089.3 4929.8 4995.6 5010.8 5021.1 5033.9 06:35:40 INFO - PROCESS | 2529 | 06:35:40 INFO - PROCESS | 2529 | [#4] prototype.html Cycles:180 Average:4519.07 Median:5085.70 stddev:1364.43 (26.8%) stddev-sans-first:1330.72 06:35:40 INFO - PROCESS | 2529 | Values: 284.6 310.8 316.8 327.5 327.6 4606.7 5438.3 5439.3 5460.3 5485.8 5352.3 5358.9 5391.8 5402.7 5427.5 3885.6 3957.0 3984.4 3991.4 4044.7 3487.4 4025.6 4082.5 4099.5 4137.7 3742.6 4049.6 4063.8 4068.1 4104.4 3547.9 3790.9 3852.8 4125.6 4126.2 3553.7 3559.5 3572.6 3576.6 3579.8 5062.7 5112.7 5121.4 5128.0 5166.4 4162.5 4262.9 4288.8 4458.3 4527.8 4693.4 4850.6 5568.9 5609.1 5666.3 3949.3 3963.4 3972.3 3976.4 4010.5 5246.9 5289.4 5504.2 5583.3 5961.0 3526.2 3978.6 3984.1 3991.8 4003.4 3447.7 3834.9 4032.5 4052.9 4112.6 3966.9 4039.7 4657.0 4663.3 4758.5 4988.8 5279.8 5657.3 5676.0 5694.1 1358.9 1388.0 1396.7 1400.3 1404.3 815.5 851.6 859.7 865.9 884.4 4936.2 5017.7 5096.9 5222.3 5246.5 5074.5 5498.6 5739.2 5783.2 5813.8 4185.6 4477.5 5141.2 5217.8 5275.7 3029.3 3029.9 3032.4 3043.3 3054.4 5202.6 5746.9 5977.6 5988.0 6034.3 4963.7 5472.5 5526.6 5673.7 5930.0 5601.4 5943.5 5978.9 5991.0 6007.0 5215.7 5534.6 5643.2 5788.8 5818.9 4990.0 5334.7 5601.3 5700.5 5924.1 5102.5 5424.8 5547.6 6032.7 6037.5 3564.7 3799.2 3959.6 3979.2 3980.0 4845.0 5304.3 5667.0 5704.8 5717.4 4609.7 4987.8 5544.6 5566.4 5579.2 4219.8 4834.7 4897.8 5482.1 5611.2 4765.5 5294.6 5495.3 5543.7 5581.4 4561.8 5265.6 5361.6 5672.3 5728.0 4953.8 5358.8 5555.1 5564.7 5577.2 06:35:40 INFO - PROCESS | 2529 | 06:35:40 INFO - PROCESS | 2529 | [#5] yui.html Cycles:180 Average:17384.27 Median:30451.40 stddev:24258.49 (79.7%) stddev-sans-first:24294.00 06:35:40 INFO - PROCESS | 2529 | Values: 650.3 653.9 709.4 734.0 740.9 14674.2 14873.8 15058.2 15069.2 15143.2 13145.1 13375.8 13430.5 13441.1 13947.7 887.8 909.5 926.5 927.3 930.7 910.6 914.9 919.3 920.3 922.0 967.6 969.8 969.8 971.5 976.3 920.5 928.8 964.8 966.2 968.3 703.3 718.2 721.1 722.5 727.7 13035.2 13040.1 13049.9 13068.7 13099.7 11705.6 11763.7 11780.0 11896.2 12090.5 10568.8 10931.8 11103.3 11180.4 11449.7 4019.3 4025.8 4061.5 4075.4 4256.6 63529.4 69459.6 75348.6 81745.6 82151.2 9066.9 9079.8 9085.5 9189.1 9225.9 20610.9 20685.4 20700.3 20722.3 20741.8 7080.1 7082.5 7120.9 7267.8 7322.5 3816.1 3885.0 3904.8 3923.3 3940.3 3388.6 3483.7 3492.4 3492.9 3509.8 611.2 613.2 613.7 633.8 639.4 556.7 581.2 588.3 600.3 602.1 7791.8 7977.4 7991.2 8228.2 8239.5 578.2 582.4 583.1 586.4 609.8 3272.5 3732.0 3818.0 3853.8 4029.6 85771.3 86330.1 87694.0 88761.7 90670.5 78488.6 78535.0 78628.6 80612.0 82093.0 54122.7 55204.5 71796.6 72982.3 74969.6 7630.8 7835.0 8153.7 8173.5 8248.1 42318.6 42472.6 42810.8 43097.6 43613.6 58073.1 60300.7 60602.1 60678.0 60830.0 11353.7 11516.7 11666.9 11707.6 11800.5 10217.9 11535.5 11992.9 12022.8 12429.9 11286.1 11777.6 11798.2 11802.5 11831.3 9327.7 11454.7 11619.3 11701.8 12410.1 10344.7 10861.2 10882.8 11049.0 11171.2 9160.3 9324.8 9393.9 9455.5 9551.7 8678.8 8822.6 8927.0 9013.0 9597.6 06:35:40 INFO - PROCESS | 2529 | -------- Summary: end -------- 06:35:40 INFO - PROCESS | 2529 | 06:35:41 INFO - TEST-INFO | 2529: exit 0 06:35:41 INFO - TEST-OK | dromaeo_css | took 1114805ms 06:35:41 INFO - TEST-START | kraken 06:35:41 INFO - operating with platform_type : mac_ 06:35:41 INFO - Initialising browser for kraken test... 06:35:41 INFO - TEST-INFO | started process 2535 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpPxs2SP/profile http://localhost:62095/getInfo.html) 06:35:43 INFO - PROCESS | 2535 | __metrics Screen width/height:1600/1200 06:35:43 INFO - PROCESS | 2535 | colorDepth:24 06:35:43 INFO - PROCESS | 2535 | Browser inner width/height: 1024/689 06:35:43 INFO - PROCESS | 2535 | __metrics 06:35:43 INFO - PROCESS | 2535 | 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" 06:35:43 ERROR - PROCESS | 2535 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:35:44 INFO - TEST-INFO | 2535: exit 0 06:35:44 INFO - Browser initialized. 06:35:44 INFO - Running cycle 1/1 for kraken test... 06:35:44 INFO - TEST-INFO | started process 2537 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpPxs2SP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:35:56 INFO - PROCESS | 2537 | RSS: Main: 238804992 06:35:56 INFO - PROCESS | 2537 | 06:35:59 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62095/tests/kraken/driver.html?testName=audio-beat-detection) 06:36:00 INFO - PROCESS | 2537 | RSS: Main: 286932992 06:36:00 INFO - PROCESS | 2537 | 06:36:03 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62095/tests/kraken/driver.html?testName=audio-dft) 06:36:04 INFO - PROCESS | 2537 | RSS: Main: 296075264 06:36:04 INFO - PROCESS | 2537 | 06:36:07 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62095/tests/kraken/driver.html?testName=audio-fft) 06:36:08 INFO - PROCESS | 2537 | RSS: Main: 385146880 06:36:08 INFO - PROCESS | 2537 | 06:36:10 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62095/tests/kraken/driver.html?testName=audio-oscillator) 06:36:11 INFO - PROCESS | 2537 | RSS: Main: 284995584 06:36:11 INFO - PROCESS | 2537 | 06:36:13 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62095/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:36:14 INFO - PROCESS | 2537 | RSS: Main: 289394688 06:36:14 INFO - PROCESS | 2537 | 06:36:17 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62095/tests/kraken/driver.html?testName=imaging-darkroom) 06:36:17 INFO - PROCESS | 2537 | RSS: Main: 328396800 06:36:17 INFO - PROCESS | 2537 | 06:36:20 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62095/tests/kraken/driver.html?testName=imaging-desaturate) 06:36:21 INFO - PROCESS | 2537 | RSS: Main: 331603968 06:36:21 INFO - PROCESS | 2537 | 06:36:24 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62095/tests/kraken/driver.html?testName=json-parse-financial) 06:36:25 INFO - PROCESS | 2537 | RSS: Main: 328298496 06:36:25 INFO - PROCESS | 2537 | 06:36:26 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62095/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:36:27 INFO - PROCESS | 2537 | RSS: Main: 316522496 06:36:27 INFO - PROCESS | 2537 | 06:36:29 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62095/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:36:30 INFO - PROCESS | 2537 | RSS: Main: 323174400 06:36:30 INFO - PROCESS | 2537 | 06:36:32 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62095/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:36:33 INFO - PROCESS | 2537 | RSS: Main: 325861376 06:36:33 INFO - PROCESS | 2537 | 06:36:35 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62095/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:36:36 INFO - PROCESS | 2537 | RSS: Main: 330862592 06:36:36 INFO - PROCESS | 2537 | 06:36:39 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62095/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:36:40 INFO - PROCESS | 2537 | RSS: Main: 328450048 06:36:40 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:62095/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62095/tests/kraken/driver.html?testName=ai-astar) 06:36:42 INFO - PROCESS | 2537 | RSS: Main: 375832576 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | __start_tp_report 06:36:42 INFO - PROCESS | 2537 | _x_x_mozilla_page_load 06:36:42 INFO - PROCESS | 2537 | _x_x_mozilla_page_load_details 06:36:42 INFO - PROCESS | 2537 | |i|pagename|runs| 06:36:42 INFO - PROCESS | 2537 | |0;ai-astar;151;166;151;113;112;113;115;163;167;111 06:36:42 INFO - PROCESS | 2537 | |1;audio-beat-detection;161;134;121;258;145;136;140;115;151;118 06:36:42 INFO - PROCESS | 2537 | |2;audio-dft;188;197;212;185;185;184;229;252;181;186 06:36:42 INFO - PROCESS | 2537 | |3;audio-fft;82;56;59;143;57;57;60;65;82;55 06:36:42 INFO - PROCESS | 2537 | |4;audio-oscillator;97;77;185;111;107;76;103;78;77;109 06:36:42 INFO - PROCESS | 2537 | |5;imaging-gaussian-blur;86;84;87;85;128;85;88;85;88;86 06:36:42 INFO - PROCESS | 2537 | |6;imaging-darkroom;93;93;97;92;92;92;93;93;93;93 06:36:42 INFO - PROCESS | 2537 | |7;imaging-desaturate;85;85;94;84;84;85;85;85;93;131 06:36:42 INFO - PROCESS | 2537 | |8;json-parse-financial;54;57;56;55;56;56;57;118;55;53 06:36:42 INFO - PROCESS | 2537 | |9;json-stringify-tinderbox;67;77;65;74;74;70;70;70;70;72 06:36:42 INFO - PROCESS | 2537 | |10;stanford-crypto-aes;85;83;84;85;84;84;85;85;85;85 06:36:42 INFO - PROCESS | 2537 | |11;stanford-crypto-ccm;139;152;145;143;139;134;138;138;216;139 06:36:42 INFO - PROCESS | 2537 | |12;stanford-crypto-pbkdf2;168;164;217;221;165;165;164;165;164;210 06:36:42 INFO - PROCESS | 2537 | |13;stanford-crypto-sha256-iterative;66;66;67;68;61;57;69;80;83;79 06:36:42 INFO - PROCESS | 2537 | __end_tp_report 06:36:42 INFO - PROCESS | 2537 | __start_cc_report 06:36:42 INFO - PROCESS | 2537 | _x_x_mozilla_cycle_collect,2258 06:36:42 INFO - PROCESS | 2537 | __end_cc_report 06:36:42 INFO - PROCESS | 2537 | __startTimestamp1464097002381__endTimestamp 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | ------- Summary: start ------- 06:36:42 INFO - PROCESS | 2537 | Number of tests: 14 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#0] ai-astar Cycles:10 Average:136.20 Median:151.00 stddev:25.25 (16.7%) stddev-sans-first:26.21 06:36:42 INFO - PROCESS | 2537 | Values: 151.0 166.0 151.0 113.0 112.0 113.0 115.0 163.0 167.0 111.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#1] audio-beat-detection Cycles:10 Average:147.90 Median:142.50 stddev:41.38 (29.0%) stddev-sans-first:43.62 06:36:42 INFO - PROCESS | 2537 | Values: 161.0 134.0 121.0 258.0 145.0 136.0 140.0 115.0 151.0 118.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#2] audio-dft Cycles:10 Average:199.90 Median:192.50 stddev:23.82 (12.4%) stddev-sans-first:24.87 06:36:42 INFO - PROCESS | 2537 | Values: 188.0 197.0 212.0 185.0 185.0 184.0 229.0 252.0 181.0 186.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#3] audio-fft Cycles:10 Average:71.60 Median:59.50 stddev:27.07 (45.5%) stddev-sans-first:28.45 06:36:42 INFO - PROCESS | 2537 | Values: 82.0 56.0 59.0 143.0 57.0 57.0 60.0 65.0 82.0 55.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#4] audio-oscillator Cycles:10 Average:102.00 Median:76.50 stddev:32.61 (42.6%) stddev-sans-first:34.54 06:36:42 INFO - PROCESS | 2537 | Values: 97.0 77.0 185.0 111.0 107.0 76.0 103.0 78.0 77.0 109.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:86.00 stddev:13.35 (15.5%) stddev-sans-first:14.07 06:36:42 INFO - PROCESS | 2537 | Values: 86.0 84.0 87.0 85.0 128.0 85.0 88.0 85.0 88.0 86.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:1.45 (1.6%) stddev-sans-first:1.54 06:36:42 INFO - PROCESS | 2537 | Values: 93.0 93.0 97.0 92.0 92.0 92.0 93.0 93.0 93.0 93.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:85.00 stddev:14.49 (17.1%) stddev-sans-first:15.21 06:36:42 INFO - PROCESS | 2537 | Values: 85.0 85.0 94.0 84.0 84.0 85.0 85.0 85.0 93.0 131.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:56.00 stddev:19.82 (35.4%) stddev-sans-first:20.83 06:36:42 INFO - PROCESS | 2537 | Values: 54.0 57.0 56.0 55.0 56.0 56.0 57.0 118.0 55.0 53.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:3.51 (4.9%) stddev-sans-first:3.43 06:36:42 INFO - PROCESS | 2537 | Values: 67.0 77.0 65.0 74.0 74.0 70.0 70.0 70.0 70.0 72.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:0.71 (0.8%) stddev-sans-first:0.73 06:36:42 INFO - PROCESS | 2537 | Values: 85.0 83.0 84.0 85.0 84.0 84.0 85.0 85.0 85.0 85.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:141.00 stddev:24.29 (17.2%) stddev-sans-first:25.53 06:36:42 INFO - PROCESS | 2537 | Values: 139.0 152.0 145.0 143.0 139.0 134.0 138.0 138.0 216.0 139.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.30 Median:166.50 stddev:24.80 (14.9%) stddev-sans-first:25.90 06:36:42 INFO - PROCESS | 2537 | Values: 168.0 164.0 217.0 221.0 165.0 165.0 164.0 165.0 164.0 210.0 06:36:42 INFO - PROCESS | 2537 | 06:36:42 INFO - PROCESS | 2537 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:68.50 stddev:8.46 (12.4%) stddev-sans-first:8.87 06:36:42 INFO - PROCESS | 2537 | Values: 66.0 66.0 67.0 68.0 61.0 57.0 69.0 80.0 83.0 79.0 06:36:42 INFO - PROCESS | 2537 | -------- Summary: end -------- 06:36:42 INFO - PROCESS | 2537 | 06:36:43 INFO - TEST-INFO | 2537: exit 0 06:36:43 INFO - TEST-OK | kraken | took 61665ms 06:36:43 INFO - SUITE-END | took 1176s 06:36:43 INFO - Completed test suite (00:19:36) 06:36:43 INFO - javascript benchmark 06:36:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3439.3981053315665, 3455.7926524408554, 3484.756067075304, 3491.807950562721, 3519.7184225261967, 5514.09017512111, 5562.499375056244, 5717.828465146047, 5794.420557944209, 5838.503727183194, 5578.63738856974, 5637.4644408781205, 5640.323161220659, 5784.652920824754, 5888.322842873071, 4802.807887684488, 5263.552598029172, 5281.207818827187, 5422.620416570841, 5439.728013599324, 5353.03645343838, 5418.91871621926, 5419.512243898049, 5420.756065977041, 5430.456954304577, 5291.867703307429, 5410.053240682865, 5489.34127904652, 5489.615726899118, 5494.615376923617, 5243.68537887728, 5292.206169074631, 5300.098983172831, 5316.813911513079, 5328.46715328468, 5248.580113590903, 5305.549028332558, 5306.602004849613, 5319.973400132974, 5338.3060202173465, 5580.107182850725, 5592.049351610195, 5643.830685079413, 5667.603267771257, 5699.116636921284, 6260.561760676755, 6263.812085637438, 6273.184486016834, 6286.402791734778, 6320.0, 6317.4946004319545, 6360.395762402564, 6369.172007639024, 6387.0, 6387.93612063878, 5741.770329186827, 5798.362180160137, 5818.7672493100645, 5849.88300233993, 5878.5297176225795, 6331.778387756499, 6349.650769207646, 6354.936450635527, 6362.586431882006, 6363.395477429729, 6188.81433556994, 6222.968885155636, 6226.034964580497, 6230.00319948806, 6235.469985051322, 6099.279518885317, 6197.628142311476, 6274.05889116627, 6279.152314437584, 6358.491320694333, 6187.3503282154725, 6220.688965551704, 6247.500199983991, 6266.937330626635, 6275.466585340206, 6106.145139680449, 6307.463865571478, 6325.0, 6333.334999825044, 6358.23701155855, 4922.753862306942, 4942.332785074041, 4950.034743225036, 5000.224965130411, 5042.823501177441, 4405.801738921757, 4453.665975051884, 4454.599086082203, 4465.955340446619, 4494.52807455219, 5403.567714582824, 5796.884062318814, 5804.79683211094, 5808.099744539603, 5854.356020837705, 6173.9073913891325, 6253.405926436982, 6312.084747711556, 6330.778422755181, 6374.075759014917, 5789.88420231593, 5795.681237531976, 5800.216970708901, 5817.476427121579, 5854.473097421167, 5340.332458442695, 5454.372747134051, 5463.672179669233, 5463.808766693147, 5473.616846820684, 6291.496680265568, 6293.937060629518, 6342.016987366781, 6349.365063493614, 6355.968220158869, 5298.523132918056, 6352.460040896391, 6362.554621176473, 6371.267304260069, 6391.201099862517, 6237.407446292595, 6361.491080713625, 6375.298717141203, 6381.8723625528155, 6383.042543618309, 6107.249501910372, 6114.084479019511, 6171.814845554641, 6221.346758590283, 6234.781782637586, 6336.809895703136, 6336.841578960563, 6339.144215530844, 6343.365663433619, 6344.333844946399, 5855.180274761452, 6045.395460453919, 6073.514118870568, 6088.360722124113, 6142.785002525069, 5987.251593550806, 6114.206128133634, 6141.126956278199, 6145.170401995852, 6148.44663980244, 6024.578279520391, 6031.215941927521, 6034.879302414015, 6050.7277172526465, 6094.42103000232, 5977.462028417463, 6058.848528786815, 6076.058210977306, 6138.294096178996, 6140.662263575547, 5985.042393217064, 6015.729292181775, 6018.3680713524445, 6028.668423236764, 6052.425019623129], "value": 5758.977978566317, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.8985912958283, 483.9467658557558, 488.46757034832035, 494.38696016938985, 499.9050180465711, 32199.19502012451, 41100.38349424761, 41983.0, 42021.57978420215, 44501.332480012745, 43281.78359108208, 43634.12731745371, 43776.90557736058, 43786.56213437865, 43848.780756096174, 8508.489490630582, 9207.217386522138, 9375.718728438158, 9376.296777741663, 9377.76555586109, 8541.743747687548, 11484.942575287112, 11754.941225293862, 11758.118141139448, 11770.058395328353, 11520.71198220047, 11582.768344633145, 11594.0, 11610.6516804496, 11618.941905290461, 8560.0, 11345.716357091056, 11511.712207194803, 11531.538738450452, 11627.244229125165, 11504.65486035412, 11605.535778568847, 11636.069114470822, 11647.010004149657, 11759.823602645967, 39946.40080398796, 40273.395899061536, 40279.3958090626, 40335.7983210085, 40380.788576342464, 3124.7968882022824, 3256.6092068951875, 3300.9009729708146, 3729.4965179700666, 3809.0286976820735, 28829.135125946254, 29004.41991160165, 29284.975025874206, 29828.955986540368, 29984.0, 2659.5611724065507, 2716.497447972112, 2723.72762723728, 2726.986365068182, 2795.5107856125096, 496724.0, 496757.0, 497125.0, 497512.0, 497903.0, 49443.055694430266, 61193.38806611877, 61235.38764612297, 61240.38759612436, 61256.0, 36360.81819590938, 36694.449583256275, 36729.0, 36779.448308275394, 64238.357616423236, 16480.0, 16682.749758753627, 16683.582910427092, 16878.74681879773, 16897.746533802, 11589.420528973518, 11770.529178832921, 12493.500259989498, 12654.240745555297, 12795.680107997374, 19639.214431422584, 19668.311609093613, 19677.016149192485, 19798.40604781859, 19800.50498737514, 6501.772437964649, 6585.835354116185, 7570.242975702386, 7613.771586852293, 7877.803054923558, 904.2810965282484, 979.8775153105862, 994.433173091331, 1009.5608410341525, 1029.9279050466396, 26979.0, 29408.117756466912, 29467.705322946924, 30154.69845301519, 30193.547096793565, 1055.2349546578823, 1098.5990113608475, 1132.5300000499865, 1166.1079274355125, 1220.9145359824904, 14518.491852785102, 14776.0, 14860.03409778361, 15016.948813583163, 15061.535846755307, 687331.0, 687717.0, 696343.0, 697456.0, 697841.0, 59089.0, 59513.999999999134, 59578.404215957285, 59690.0, 59859.700701496215, 1278.39276343736, 1398.5314919501877, 1422.054333867987, 1445.38571107279, 1454.4763885001603, 29853.276494497524, 29979.0, 30055.156209553388, 30360.089197324116, 30467.695323046486, 57018.0, 58522.53693657693, 58537.70731146317, 61898.45253868689, 62275.377246226955, 617292.0, 633581.8320908366, 633627.0, 633866.0, 634745.8262708657, 34533.82733086419, 39810.0, 40395.1920961585, 40847.38728919128, 42966.0, 45790.0, 45852.31221531746, 46021.309680355494, 47609.285860711425, 47640.761796190796, 37597.62402376051, 37685.0, 37820.0, 38291.0, 38364.61635383722, 31716.841415793697, 37101.0, 37306.440403393426, 37519.24961500809, 38327.42508862314, 33476.33047339088, 34569.48145777866, 36869.63130368662, 37491.43762843505, 37690.811545942095, 32707.34585308328, 32913.83543082269, 33358.33283334368, 33440.163995899326, 33532.0, 24251.51496970086, 24339.75660243375, 24753.0, 24964.875175624005, 25335.98656053737], "value": 20331.79246484922, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.065320617693, 388.81336958260033, 393.5867339293741, 394.81246407956206, 406.9104796944673, 19402.320918767848, 20910.372688819323, 21103.256576645013, 21152.89423552882, 21158.0, 18579.81420185798, 18678.53303667409, 19280.3251886184, 19301.420957371265, 19585.314513992013, 5185.974070129663, 5777.537796976242, 5979.282486101674, 5981.162637230791, 6039.365866584011, 5794.304683437993, 5961.731722072495, 5985.401459854023, 5999.85000374992, 6000.33996260411, 5735.885282294352, 5873.647581145145, 5896.911546326808, 5928.584999050068, 5939.851503712399, 5587.608867379285, 5818.5345172386105, 5836.416358364172, 5861.531077513789, 5874.76500939964, 5080.0, 5097.7960881564695, 5115.258287548322, 5143.691378517301, 5148.742562871841, 24610.0, 24687.0, 24772.752272477224, 24807.751922480726, 25135.0, 2031.3093548193685, 2097.3603051069417, 2307.976920230793, 2733.4669739400824, 2767.2805070681566, 14145.222012789292, 14623.0, 14652.267386630732, 15091.849081509265, 15275.770863437057, 1666.2585149608428, 1712.922918468672, 1727.5594723345466, 1741.2947756158776, 1779.226036674038, 101052.49473752585, 119506.0, 120346.0, 120928.0, 122038.16942745866, 10196.4391958443, 10566.52450639723, 10641.5211315491, 10673.252872298865, 10893.782124357469, 13236.669083272802, 13302.26837523926, 13365.599032029055, 13378.531751388655, 13392.933035334761, 7003.0, 8324.375671824637, 8422.326213902874, 8448.197421244971, 8520.105388934073, 8486.19381158789, 8490.660373585111, 8492.065872753992, 8508.404411691123, 8686.391952563386, 5526.392096869341, 5629.986602411524, 6136.2636483622255, 6492.67536623167, 6496.0, 1851.261725985367, 1855.6381505606548, 1856.5637075287298, 1860.9720854187199, 1863.2733234038735, 777.7511196417205, 896.9282457403392, 928.3501548915785, 971.4948226921961, 1068.823644098715, 18236.361727339743, 18545.072746362628, 20548.486287842923, 20758.74806569173, 20941.790582094287, 944.9291303152291, 1003.4481035430484, 1006.6828948881079, 1016.7051555049101, 1068.5245065945735, 9701.07839755222, 10181.389116652876, 10482.754484433817, 11211.0, 11216.214864959538, 573581.0, 579779.2022079809, 580639.0, 597803.0, 604960.0, 91767.0, 101612.0, 110839.0, 115590.0, 118524.0, 993.1161266472845, 1005.607812952949, 1017.6082208349839, 1026.7484466305802, 1056.424248784399, 18032.0, 18254.908725456287, 18289.725654114933, 18481.630367392307, 18625.813741862406, 48782.0, 48873.511264886896, 50016.749916250184, 51044.0, 51497.88016958709, 69620.3037969634, 70863.29136708567, 71148.0, 72155.0, 77122.6143869277, 7507.587082710504, 7709.3447057000785, 7749.535027898119, 7842.372610191285, 8095.71664991746, 12279.570215042431, 12407.31759753186, 12592.937035314764, 12758.74482510326, 12801.871981280068, 10500.317479363817, 10698.89301106979, 10805.297655652357, 10815.891841081488, 10939.890601093886, 10276.948615257175, 10445.11216546602, 10521.0, 10653.786924261316, 10918.12654987615, 10881.0, 11059.68767728632, 11094.611688590861, 11174.72063198394, 11238.66284011481, 7606.163322034682, 7687.769366919001, 7700.576468294297, 7836.725714599962, 8035.116137224783, 9624.75938101525, 10693.304935179189, 10694.304870183412, 10818.729531761768, 11044.889551104387], "value": 9495.16040987303, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.2018849255928, 1571.5599632103008, 1574.9763753543698, 1807.6926922423186, 1943.036868775117, 7820.648070836812, 8629.913700862997, 8841.867371989418, 8920.643174273038, 9033.503157326344, 8558.27254683351, 8830.249428798545, 8831.602577883994, 8853.11468853116, 8857.734267971971, 2002.7883130705932, 2355.493568882694, 2365.3495288795634, 2391.964120538193, 2398.388410955204, 2328.976710232893, 2346.636271377931, 2374.5963186258377, 2387.5583017141885, 2391.294568102414, 2342.906283748648, 2359.5988681924027, 2376.013954209001, 2390.880455977203, 2391.5575618510543, 1990.2934458267252, 2336.3925379401308, 2354.2701762453607, 2371.27676058802, 2372.2445390411144, 2336.3575016870327, 2343.1447521654645, 2344.695189625355, 2348.2955113466064, 2350.189184731265, 8548.658053677846, 8705.042445331008, 8795.648174073029, 8795.956020219923, 8799.560021998874, 5183.637145399824, 5386.1920711893135, 5387.084195686703, 5392.487713667196, 5396.325459317585, 3398.558187435642, 3483.2336885885065, 3549.0, 3555.4489054196642, 3595.083253770298, 975.1954637424153, 1013.5084484025242, 1017.4048181813648, 1023.4268809466722, 1026.7741096958657, 11608.419579021016, 11729.472173752116, 11777.705557361049, 11779.764404711943, 11817.290962542274, 4437.64498840092, 4596.517365676623, 4606.608438282717, 4692.483826779052, 4715.245560710338, 9195.494247816434, 9217.124373184537, 9220.985691573922, 9222.40054396462, 9231.307651926132, 5265.605079619044, 5269.288646032813, 5273.419923808378, 5274.129768588216, 5282.418933917266, 4358.389825424443, 4400.471943366816, 4559.749213793273, 4783.976080119577, 4832.806687732522, 3796.677282430969, 3797.9430308545393, 3830.3105441020325, 3864.30442520349, 3906.0625449891822, 1006.9597216111273, 1121.4579952565223, 1145.8109411947103, 1162.95900694281, 1164.6040346282305, 793.9682412703543, 864.822711344173, 892.7857314244535, 936.9786932243887, 940.1867384712175, 5518.91721624176, 5690.459406356389, 5837.824865254049, 5868.618539794899, 5953.613015154001, 878.3939082033377, 919.1360121485782, 927.8422668146432, 936.2229349639782, 993.4933184076173, 3765.3410653135593, 3853.2486165197515, 3867.9806600966817, 3938.3895496198134, 3941.073847645801, 10488.947555262175, 10669.733256668645, 10736.141108711285, 10883.945580272206, 10897.182711296684, 10813.837792432962, 10823.837642435212, 10827.566897324019, 10833.566657333618, 10844.72888177786, 7868.488548244346, 8128.796780080545, 8288.295494882881, 8288.792780180424, 8325.209105135002, 5484.91772623403, 5493.533049690821, 5597.692126932895, 5656.604037717485, 5837.970810145922, 9806.705798826046, 9838.704838854846, 10111.292209545261, 10113.64602238918, 10122.595096196364, 9881.357711748713, 10221.43782091992, 10233.590656373959, 10252.282340236112, 10289.9485502572, 6731.259561448139, 6920.238773734784, 7109.431245500451, 7299.306565876233, 7332.23011583776, 8416.789580260298, 8435.367347449088, 8441.11368306319, 8539.701110461352, 8549.444286121383, 7196.784096477114, 7367.26327367259, 7465.477416580788, 7510.474266801271, 7662.578558179353, 7251.56490610565, 7312.561246325237, 7329.890051649123, 7471.701131954878, 7528.4353673473615, 6890.552114112566, 6975.337342952412, 6978.825529361807, 6983.6857341418745, 6999.405050570758, 4255.468066491689, 4680.859574212779, 4792.400949881265, 4941.456439791548, 5089.312942752681, 4929.802807887644, 4995.575376092927, 5010.799568017384, 5021.096202683552, 5033.874153146201], "value": 4528.6531319674, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.5816649525199, 310.77934666386864, 316.8024866006495, 327.4907518808252, 327.6134161689206, 4606.677532572721, 5438.320209973754, 5439.292891924049, 5460.31746031746, 5485.807996720116, 5352.277442545255, 5358.866028349294, 5391.78432862685, 5402.702851343174, 5427.538659213971, 3885.5725870154333, 3956.9604303957026, 3984.3625019996807, 3991.381335892941, 4044.6966477514156, 3487.4071407860597, 4025.5571887092397, 4082.4896887889013, 4099.462701486942, 4137.710360274782, 3742.569604495477, 4049.6107296694945, 4063.8214917673986, 4068.1456894052285, 4104.42538044674, 3547.8935631931085, 3790.886273411802, 3852.7653960702864, 4125.566815484391, 4126.174765047002, 3553.680168784822, 3559.501669766209, 3572.5534308211472, 3576.5708115026105, 3579.8031108289033, 5062.7468626568525, 5112.693238405707, 5121.436641969381, 5128.0, 5166.43169251382, 4162.4796900387455, 4262.870339360072, 4288.785560721952, 4458.305843779343, 4527.845399423126, 4693.389859318301, 4850.563449289581, 5568.888622227573, 5609.1305847593685, 5666.348369937468, 3949.308870947573, 3963.4451176835264, 3972.3117674988016, 3976.403539469074, 4010.5387880393837, 5246.868828279324, 5289.3917199521775, 5504.2018907258225, 5583.274174357348, 5960.959910445704, 3526.1713497328105, 3978.5822488638855, 3984.103576695278, 3991.7604943703473, 4003.4386589230226, 3447.7069449097107, 3834.9041273967814, 4032.536258330271, 4052.858149964796, 4112.568180341081, 3966.940495892564, 4039.6768258539246, 4656.998745269783, 4663.27719203524, 4758.500357462486, 4988.804925832622, 5279.8152064678325, 5657.264555607827, 5675.956655603979, 5694.06048002075, 1358.8844948179515, 1387.993704564203, 1396.6717821311984, 1400.3138462153472, 1404.3399602186955, 815.4943934760487, 851.586980314547, 859.6604341285157, 865.8831057807241, 884.3588398411229, 4936.185529387682, 5017.738469994532, 5096.923546146811, 5222.268882356473, 5246.475352464723, 5074.467180945948, 5498.5876059295715, 5739.163467690382, 5783.248177736868, 5813.82558523245, 4185.58144185579, 4477.462704475483, 5141.177411614199, 5217.765200565984, 5275.683458992473, 3029.3184033592265, 3029.909102726922, 3032.3632037271977, 3043.269615292314, 3054.40439114375, 5202.635815492879, 5746.942530574641, 5977.581569290108, 5988.0, 6034.280230134478, 4963.726995015309, 5472.480114389126, 5526.58550608706, 5673.716314184274, 5930.0, 5601.411851755507, 5943.524518038546, 5978.880422391528, 5990.970045149834, 6006.969965150146, 5215.713135777569, 5534.584906131976, 5643.153526970823, 5788.768449262151, 5818.9127163093435, 4989.975050124872, 5334.653247538898, 5601.29983752031, 5700.489370316786, 5924.081767326072, 5102.515261050195, 5424.810131645373, 5547.639403438763, 6032.728527216374, 6037.486813620892, 3564.7326450516834, 3799.221159662318, 3959.604039596017, 3979.164375481181, 3979.9801000994808, 4845.030993801183, 5304.310439642822, 5667.0, 5704.771809127588, 5717.42825717425, 4609.677322587387, 4987.775550100182, 5544.556435485071, 5566.443355664401, 5579.19101730255, 4219.810108545062, 4834.685745426536, 4897.804087836446, 5482.122860342485, 5611.186377975089, 4765.499622539722, 5294.602904782079, 5495.313085864267, 5543.667379957054, 5581.441855814386, 4561.794719237576, 5265.552428043506, 5361.571074313967, 5672.319321681424, 5728.0, 4953.811085339565, 5358.785648574013, 5555.111262170882, 5564.693941833076, 5577.163425486047], "value": 4086.9947361753884, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.261952683704, 653.9476841852652, 709.4430871765665, 733.9926600733991, 740.8518296340732, 14674.192919389443, 14873.776893346594, 15058.247087645601, 15069.171195584238, 15143.167125808077, 13145.079844410895, 13375.799363009563, 13430.462781488754, 13441.05912586117, 13947.721045579083, 887.8224355128967, 909.5497728624324, 926.4996901673088, 927.3369540778342, 930.6649606141783, 910.6038873090226, 914.8764916736222, 919.3334832246635, 920.2775820980531, 921.9996304010157, 967.6032826541119, 969.8254314223437, 969.849673300636, 971.480258061937, 976.3360914578084, 920.4615300049479, 928.7724507495673, 964.8022155458115, 966.1546147121269, 968.3463662028103, 703.2580627438102, 718.2314923032358, 721.0698199322867, 722.5484072454716, 727.7052793618593, 13035.217886926815, 13040.087193896432, 13049.86950130496, 13068.673283167902, 13099.738005239937, 11705.648830535098, 11763.7059073523, 11780.0, 11896.167268291223, 12090.516379344816, 10568.841467377995, 10931.836022459669, 11103.333799972028, 11180.440977951152, 11449.713757156138, 4019.2966230909474, 4025.798710064485, 4061.512618485771, 4075.449814275094, 4256.59562341577, 63529.364706352804, 69459.61080778357, 75348.62325688337, 81745.59127204385, 82151.17848821495, 9066.909330906738, 9079.773005674911, 9085.500297483703, 9189.127032931861, 9225.907740922505, 20610.89694551518, 20685.379438616867, 20700.275490358068, 20722.274720384714, 20741.79258207429, 7080.114985626797, 7082.504224704324, 7120.857582848417, 7267.7819665410125, 7322.487425880244, 3816.1222918728295, 3885.048163200034, 3904.7657140571655, 3923.2545816294814, 3940.271049855786, 3388.5933687957604, 3483.7213022958103, 3492.4109530163714, 3492.89521314361, 3509.754317197772, 611.211537117114, 613.1783410230313, 613.6502193749609, 633.8066889598624, 639.3702203329716, 556.6799090522966, 581.16022347708, 588.2852334413708, 600.2916558461058, 602.0938487576258, 7791.805204869924, 7977.401694872908, 7991.160928102582, 8228.21831925978, 8239.505629662239, 578.1934201788481, 582.3652219081138, 583.1398686936734, 586.3667239381458, 609.844489655144, 3272.4927636216426, 3731.962680373216, 3817.9618203818163, 3853.7880416576795, 4029.5567487576345, 85771.28457430817, 86330.13669863346, 87694.0, 88761.66857497142, 90670.54664726766, 78488.60755696186, 78535.0, 78628.60685696534, 80612.0, 82093.0, 54122.72938635282, 55204.51579678924, 71796.64101679562, 72982.27017729754, 74969.62515187389, 7630.8092297693, 7835.0, 8153.714619988272, 8173.536915962481, 8248.092709801796, 42318.57681423269, 42472.57527424686, 42810.78594607007, 43097.569024309356, 43613.56386436095, 58073.128903067336, 60300.69849650723, 60602.09096863462, 60678.0, 60830.0, 11353.716157096138, 11516.712082198012, 11666.88333116658, 11707.648770536898, 11800.52797888075, 10217.92711765254, 11535.480903359537, 11992.940035299767, 12022.819657704966, 12429.937850310691, 11286.097112230838, 11777.646670599896, 11798.233114847508, 11802.52789888395, 11831.290122592673, 9327.673531426368, 11454.656360309204, 11619.269471674319, 11701.824472633087, 12410.069244806857, 10344.741381465523, 10861.185411094042, 10882.836757448804, 11049.0, 11171.162162837982, 9160.31297652692, 9324.766880828034, 9393.859092113487, 9455.479948602893, 9551.665691700757, 8678.82642347137, 8822.602982865657, 8926.955365223392, 9012.954935225544, 9597.616095356108], "value": 6760.574383469655, "unit": "score"}], "name": "dromaeo_css", "value": 7198.430874997665, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 166.0, 151.0, 113.0, 112.0, 113.0, 115.0, 163.0, 167.0, 111.0], "value": 136.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 134.0, 121.0, 258.0, 145.0, 136.0, 140.0, 115.0, 151.0, 118.0], "value": 147.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 197.0, 212.0, 185.0, 185.0, 184.0, 229.0, 252.0, 181.0, 186.0], "value": 199.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 56.0, 59.0, 143.0, 57.0, 57.0, 60.0, 65.0, 82.0, 55.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 77.0, 185.0, 111.0, 107.0, 76.0, 103.0, 78.0, 77.0, 109.0], "value": 102.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 85.0, 128.0, 85.0, 88.0, 85.0, 88.0, 86.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 97.0, 92.0, 92.0, 92.0, 93.0, 93.0, 93.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 94.0, 84.0, 84.0, 85.0, 85.0, 85.0, 93.0, 131.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 56.0, 55.0, 56.0, 56.0, 57.0, 118.0, 55.0, 53.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 77.0, 65.0, 74.0, 74.0, 70.0, 70.0, 70.0, 70.0, 72.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 85.0, 84.0, 84.0, 85.0, 85.0, 85.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 152.0, 145.0, 143.0, 139.0, 134.0, 138.0, 138.0, 216.0, 139.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 164.0, 217.0, 221.0, 165.0, 165.0, 164.0, 165.0, 164.0, 210.0], "value": 180.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 68.0, 61.0, 57.0, 69.0, 80.0, 83.0, 79.0], "value": 69.6, "unit": "score"}], "name": "kraken", "value": 1547.3, "alertThreshold": 2.0}]} 06:36:43 INFO - Return code: 0 06:36:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:36:43 ERROR - # TBPL SUCCESS # 06:36:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:36:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:36:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:36:43 INFO - 2016-05-24 06:36:43.669 screenresolution[2541:94481] starting screenresolution argv=screenresolution get 06:36:43 INFO - 2016-05-24 06:36:43.672 screenresolution[2541:94481] Display 0: 1600x1200x32@60 06:36:43 INFO - 2016-05-24 06:36:43.678 screenresolution[2542:94484] starting screenresolution argv=screenresolution list 06:36:43 INFO - Available Modes on Display 0 06:36:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:36:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:36:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:36:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:36:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:36:43 INFO - Intel Iris: 06:36:43 INFO - Chipset Model: Intel Iris 06:36:43 INFO - Type: GPU 06:36:43 INFO - Bus: Built-In 06:36:43 INFO - VRAM (Dynamic, Max): 1536 MB 06:36:43 INFO - Vendor: Intel (0x8086) 06:36:43 INFO - Device ID: 0x0a2e 06:36:43 INFO - Revision ID: 0x0009 06:36:43 INFO - Displays: 06:36:43 INFO - Display: 06:36:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:36:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:36:43 INFO - Display Serial Number: 1600x1200 60 06:36:43 INFO - Main Display: Yes 06:36:43 INFO - Mirror: Off 06:36:43 INFO - Online: Yes 06:36:43 INFO - Rotation: Supported 06:36:43 INFO - Return code: 0 06:36:43 INFO - Running post-action listener: _resource_record_post_action 06:36:43 INFO - ##### 06:36:43 INFO - ##### Finished run-tests step (success) 06:36:43 INFO - ##### 06:36:43 INFO - Running post-run listener: _resource_record_post_run 06:36:43 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 720896; Write bytes: 696046080; Read time: 9; Write time: 1804 06:36:43 INFO - TinderboxPrint: CPU usage
54.0% 06:36:43 INFO - TinderboxPrint: I/O read bytes / time
720,896 / 9 06:36:43 INFO - TinderboxPrint: I/O write bytes / time
696,046,080 / 1,804 06:36:43 INFO - TinderboxPrint: CPU idle
2,230.4 (46.0%) 06:36:43 INFO - TinderboxPrint: CPU system
974.6 (20.1%) 06:36:43 INFO - TinderboxPrint: CPU user
1,644.7 (33.9%) 06:36:43 INFO - TinderboxPrint: Swap in / out
396,120,064 / 0 06:36:43 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375300608; Write bytes: 369021440; Read time: 28392; Write time: 640 06:36:43 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 720896; Write bytes: 296796160; Read time: 9; Write time: 1104 06:36:44 INFO - Running post-run listener: _upload_blobber_files 06:36:44 INFO - Blob upload gear active. 06:36:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:36: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 06:36: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'] 06:36: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 06:36:44 INFO - (blobuploader) - INFO - Open directory for files ... 06:36:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:36:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:36:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:36:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:36:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:36:45 INFO - (blobuploader) - INFO - Done attempting. 06:36:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:36:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:36:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:36:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:36:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:36:47 INFO - (blobuploader) - INFO - Done attempting. 06:36:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:36:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:36:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:36:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:36:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:36:49 INFO - (blobuploader) - INFO - Done attempting. 06:36:49 INFO - (blobuploader) - INFO - Iteration through files over. 06:36:49 INFO - Return code: 0 06:36:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:36:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:36:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8b7c59b42e206202b308dfdc5a520b3365492c3bf0b2baad24a39a2d7fd0999110683c2981e5580f5ed9d1444bd096c0ff1639e0307e3c37c59c4436ad9ed00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9461110b2c21fc93f2b0b78d529f08e6faae3a9248df8ac3e1c2b957c5b0ba9bbcac4af17e170bbfabc327d38879a312fead6f7efe1e3e21bcd40a2e7f504de1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ef6c6e19bcd8cd241aaf4fcf93ed1b2e9eb31c47930f884389129f0d4b82d6a35f8ffd3998f06e5b108e949f6508d7cbe1981eaf6960941bab5e18d8ece9158"} 06:36:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:36:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:36:49 INFO - Contents: 06:36:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8b7c59b42e206202b308dfdc5a520b3365492c3bf0b2baad24a39a2d7fd0999110683c2981e5580f5ed9d1444bd096c0ff1639e0307e3c37c59c4436ad9ed00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9461110b2c21fc93f2b0b78d529f08e6faae3a9248df8ac3e1c2b957c5b0ba9bbcac4af17e170bbfabc327d38879a312fead6f7efe1e3e21bcd40a2e7f504de1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ef6c6e19bcd8cd241aaf4fcf93ed1b2e9eb31c47930f884389129f0d4b82d6a35f8ffd3998f06e5b108e949f6508d7cbe1981eaf6960941bab5e18d8ece9158"} 06:36:49 INFO - Running post-run listener: copy_logs_to_upload_dir 06:36:49 INFO - Copying logs to upload dir... 06:36:49 INFO - mkdir: /builds/slave/test/build/upload/logs 06:36:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.210775 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-24 06:36:49.606267) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 06:36:49.609778) ========= 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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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/c8b7c59b42e206202b308dfdc5a520b3365492c3bf0b2baad24a39a2d7fd0999110683c2981e5580f5ed9d1444bd096c0ff1639e0307e3c37c59c4436ad9ed00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9461110b2c21fc93f2b0b78d529f08e6faae3a9248df8ac3e1c2b957c5b0ba9bbcac4af17e170bbfabc327d38879a312fead6f7efe1e3e21bcd40a2e7f504de1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ef6c6e19bcd8cd241aaf4fcf93ed1b2e9eb31c47930f884389129f0d4b82d6a35f8ffd3998f06e5b108e949f6508d7cbe1981eaf6960941bab5e18d8ece9158"} build_url:https://queue.taskcluster.net/v1/task/EMGsX6SYSi-yjDuBJovMfw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011147 build_url: 'https://queue.taskcluster.net/v1/task/EMGsX6SYSi-yjDuBJovMfw/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/c8b7c59b42e206202b308dfdc5a520b3365492c3bf0b2baad24a39a2d7fd0999110683c2981e5580f5ed9d1444bd096c0ff1639e0307e3c37c59c4436ad9ed00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9461110b2c21fc93f2b0b78d529f08e6faae3a9248df8ac3e1c2b957c5b0ba9bbcac4af17e170bbfabc327d38879a312fead6f7efe1e3e21bcd40a2e7f504de1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ef6c6e19bcd8cd241aaf4fcf93ed1b2e9eb31c47930f884389129f0d4b82d6a35f8ffd3998f06e5b108e949f6508d7cbe1981eaf6960941bab5e18d8ece9158"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 06:36:49.639513) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:36:49.639857) ========= 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.K78Mr5ToRn/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.mzTZUVLZG0/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.005261 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:36:49.688318) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 06:36:49.688639) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 06:36:49.688999) ========= ========= Total master_lag: 0.17 =========