builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0131 starttime: 1462983991.54 results: success (0) buildid: 20160511074307 builduid: 0802d0f220214b1d99a4d5ce9355ffa3 revision: 07e3e85b5ce655c2a67ec0da1f76c66435c68ecc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:31.541497) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:31.541979) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:31.542277) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:31.561907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:31.562321) ========= 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.FpyQHxTj68/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.eZE4rAUVD1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-11 09:26:31-- 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% 66.2M=0s 2016-05-11 09:26:31 (66.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130670 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:31.710551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:31.710868) ========= 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.FpyQHxTj68/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.eZE4rAUVD1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.030567 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:31.760867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 09:26:31.761249) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 07e3e85b5ce655c2a67ec0da1f76c66435c68ecc --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 07e3e85b5ce655c2a67ec0da1f76c66435c68ecc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FpyQHxTj68/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.eZE4rAUVD1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-11 09:26:31,836 truncating revision to first 12 chars 2016-05-11 09:26:31,836 Setting DEBUG logging. 2016-05-11 09:26:31,836 attempt 1/10 2016-05-11 09:26:31,836 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/07e3e85b5ce6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 09:26:32,859 unpacking tar archive at: fx-team-07e3e85b5ce6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.267185 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 09:26:33.044870) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:33.045363) ========= script_repo_revision: 07e3e85b5ce655c2a67ec0da1f76c66435c68ecc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:33.045819) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:33.046133) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 09:26:33.060227) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-11 09:26:33.060675) ========= /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.FpyQHxTj68/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.eZE4rAUVD1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:26:33 INFO - MultiFileLogger online at 20160511 09:26:33 in /builds/slave/test 09:26:33 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 09:26:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:33 INFO - {'append_to_log': False, 09:26:33 INFO - 'base_work_dir': '/builds/slave/test', 09:26:33 INFO - 'blob_upload_branch': 'Fx-Team', 09:26:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:33 INFO - 'branch': 'Fx-Team', 09:26:33 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:33 INFO - 'config_files': ('talos/mac_config.py',), 09:26:33 INFO - 'default_actions': ('clobber', 09:26:33 INFO - 'read-buildbot-config', 09:26:33 INFO - 'download-and-extract', 09:26:33 INFO - 'populate-webroot', 09:26:33 INFO - 'create-virtualenv', 09:26:33 INFO - 'install', 09:26:33 INFO - 'run-tests'), 09:26:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:33 INFO - 'download_minidump_stackwalk': True, 09:26:33 INFO - 'download_symbols': 'ondemand', 09:26:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:33 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:33 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:33 INFO - 'installer_path': 'installer.exe', 09:26:33 INFO - 'log_level': 'info', 09:26:33 INFO - 'log_name': 'talos', 09:26:33 INFO - 'log_to_console': True, 09:26:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:26:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:26:33 INFO - 'opt_config_files': (), 09:26:33 INFO - 'pip_index': False, 09:26:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:33 INFO - 'cmd': ('bash', 09:26:33 INFO - '-c', 09:26:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:33 INFO - 'enabled': True, 09:26:33 INFO - 'halt_on_failure': False, 09:26:33 INFO - 'name': 'check_screen_resolution'},), 09:26:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:33 INFO - 'cmd': ('bash', 09:26:33 INFO - '-c', 09:26:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:33 INFO - 'enabled': True, 09:26:33 INFO - 'halt_on_failure': False, 09:26:33 INFO - 'name': 'check_screen_resolution'},), 09:26:33 INFO - 'run_cmd_checks_enabled': True, 09:26:33 INFO - 'sps_profile': False, 09:26:33 INFO - 'sps_profile_interval': 0, 09:26:33 INFO - 'suite': 'dromaeojs', 09:26:33 INFO - 'system_bits': '32', 09:26:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:33 INFO - 'title': 't-yosemite-r7-0131', 09:26:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:33 INFO - 'use_talos_json': True, 09:26:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:33 INFO - 'work_dir': 'build'} 09:26:33 INFO - ##### 09:26:33 INFO - ##### Running clobber step. 09:26:33 INFO - ##### 09:26:33 INFO - Running pre-action listener: _resource_record_pre_action 09:26:33 INFO - Running main action method: clobber 09:26:33 INFO - rmtree: /builds/slave/test/build 09:26:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:35 INFO - Running post-action listener: _resource_record_post_action 09:26:35 INFO - ##### 09:26:35 INFO - ##### Finished clobber step (success) 09:26:35 INFO - ##### 09:26:35 INFO - ##### 09:26:35 INFO - ##### Running read-buildbot-config step. 09:26:35 INFO - ##### 09:26:35 INFO - Running pre-action listener: _resource_record_pre_action 09:26:35 INFO - Running main action method: read_buildbot_config 09:26:35 INFO - Using buildbot properties: 09:26:35 INFO - { 09:26:35 INFO - "project": "", 09:26:35 INFO - "product": "firefox", 09:26:35 INFO - "script_repo_revision": "production", 09:26:35 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:26:35 INFO - "repository": "", 09:26:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:26:35 INFO - "buildid": "20160511074307", 09:26:35 INFO - "pgo_build": "False", 09:26:35 INFO - "basedir": "/builds/slave/test", 09:26:35 INFO - "buildnumber": 11, 09:26:35 INFO - "slavename": "t-yosemite-r7-0131", 09:26:35 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:26:35 INFO - "platform": "yosemite_r7", 09:26:35 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:26:35 INFO - "branch": "fx-team", 09:26:35 INFO - "repo_path": "integration/fx-team", 09:26:35 INFO - "revision": "07e3e85b5ce655c2a67ec0da1f76c66435c68ecc", 09:26:35 INFO - "stage_platform": "macosx64", 09:26:35 INFO - "builduid": "0802d0f220214b1d99a4d5ce9355ffa3", 09:26:35 INFO - "slavebuilddir": "test" 09:26:35 INFO - } 09:26:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:26:35 INFO - Running post-action listener: _resource_record_post_action 09:26:35 INFO - ##### 09:26:35 INFO - ##### Finished read-buildbot-config step (success) 09:26:35 INFO - ##### 09:26:35 INFO - ##### 09:26:35 INFO - ##### Running download-and-extract step. 09:26:35 INFO - ##### 09:26:35 INFO - Running pre-action listener: _resource_record_pre_action 09:26:35 INFO - Running main action method: download_and_extract 09:26:35 INFO - mkdir: /builds/slave/test/build/tests 09:26:35 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')]} 09:26:35 INFO - https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:26:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:26:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:26:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/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 09:26:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/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 09:26:36 INFO - Downloaded 1199 bytes. 09:26:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:26:36 INFO - Using the following test package requirements: 09:26:36 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:26:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:26:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:26:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:26:36 INFO - u'jsshell-mac.zip'], 09:26:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:26:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:26:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:26:36 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:26:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:26:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:26:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:36 INFO - https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:26:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:26:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:26:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/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 09:26:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/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 09:26:37 INFO - Downloaded 18150553 bytes. 09:26:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:26:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:26:41 INFO - Return code: 0 09:26:41 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 09:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:41 INFO - https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:26:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:26:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:26:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/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 09:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/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 09:26:42 INFO - Downloaded 18150553 bytes. 09:26:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:26:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:26:45 INFO - Return code: 0 09:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:45 INFO - https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:26:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:26:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:26:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/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 09:26:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/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 09:26:46 INFO - Downloaded 10840152 bytes. 09:26:46 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'] 09:26:46 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 09:26:46 INFO - Return code: 0 09:26:46 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')]} 09:26:46 INFO - https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:26:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:26:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:26:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:26:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lz-dRho6RWu1R1wEBwnjLg/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 09:26:49 INFO - Downloaded 109797628 bytes. 09:26:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:26:49 INFO - mkdir: /builds/slave/test/properties 09:26:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:26:49 INFO - Writing to file /builds/slave/test/properties/build_url 09:26:49 INFO - Contents: 09:26:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:26:50 INFO - Running post-action listener: _resource_record_post_action 09:26:50 INFO - Running post-action listener: set_extra_try_arguments 09:26:50 INFO - ##### 09:26:50 INFO - ##### Finished download-and-extract step (success) 09:26:50 INFO - ##### 09:26:50 INFO - ##### 09:26:50 INFO - ##### Running populate-webroot step. 09:26:50 INFO - ##### 09:26:50 INFO - Running pre-action listener: _resource_record_pre_action 09:26:50 INFO - Running main action method: populate_webroot 09:26:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:26:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:26:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:26:50 INFO - '--tppagecycles', 09:26:50 INFO - '7'], 09:26:50 INFO - 'tests': ['tsvgm']}}, 09:26:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:26:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:26:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:26:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:26:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:26:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:26:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:50 INFO - 'talos_options': ['--disable-e10s'], 09:26:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:50 INFO - 'talos_options': ['--disable-e10s'], 09:26:50 INFO - 'tests': ['damp', 'tps']}, 09:26:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:50 INFO - 'tests': ['damp', 'tps']}, 09:26:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:26:50 INFO - 'tests': ['dromaeo_dom']}, 09:26:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:26:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:26:50 INFO - 'tests': ['a11yr', 09:26:50 INFO - 'ts_paint', 09:26:50 INFO - 'tpaint', 09:26:50 INFO - 'sessionrestore', 09:26:50 INFO - 'sessionrestore_no_auto_restore', 09:26:50 INFO - 'tabpaint']}, 09:26:50 INFO - 'other-e10s': {'tests': ['a11yr', 09:26:50 INFO - 'ts_paint', 09:26:50 INFO - 'tpaint', 09:26:50 INFO - 'sessionrestore', 09:26:50 INFO - 'sessionrestore_no_auto_restore', 09:26:50 INFO - 'tabpaint']}, 09:26:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:26:50 INFO - 'ts_paint', 09:26:50 INFO - 'tpaint', 09:26:50 INFO - 'sessionrestore', 09:26:50 INFO - 'sessionrestore_no_auto_restore', 09:26:50 INFO - 'tabpaint']}, 09:26:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:26:50 INFO - 'ts_paint', 09:26:50 INFO - 'tpaint', 09:26:50 INFO - 'sessionrestore', 09:26:50 INFO - 'sessionrestore_no_auto_restore', 09:26:50 INFO - 'tabpaint']}, 09:26:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:26:50 INFO - 'tests': ['a11yr', 09:26:50 INFO - 'ts_paint', 09:26:50 INFO - 'tpaint', 09:26:50 INFO - 'sessionrestore', 09:26:50 INFO - 'sessionrestore_no_auto_restore', 09:26:50 INFO - 'tabpaint']}, 09:26:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:26:50 INFO - 'tests': ['a11yr', 09:26:50 INFO - 'ts_paint', 09:26:50 INFO - 'tpaint', 09:26:50 INFO - 'sessionrestore', 09:26:50 INFO - 'sessionrestore_no_auto_restore', 09:26:50 INFO - 'tabpaint']}, 09:26:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:26:50 INFO - 'tests': ['tsvgx', 09:26:50 INFO - 'tsvgr_opacity', 09:26:50 INFO - 'tart', 09:26:50 INFO - 'tscrollx', 09:26:50 INFO - 'cart']}, 09:26:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:26:50 INFO - 'tsvgr_opacity', 09:26:50 INFO - 'tart', 09:26:50 INFO - 'tscrollx', 09:26:50 INFO - 'cart']}, 09:26:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:50 INFO - 'talos_options': ['--disable-e10s'], 09:26:50 INFO - 'tests': ['tp5o']}, 09:26:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:50 INFO - 'tests': ['tp5o']}, 09:26:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:50 INFO - 'talos_options': ['--disable-e10s', 09:26:50 INFO - '--xperf_path', 09:26:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:50 INFO - 'tests': ['tp5n']}, 09:26:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:50 INFO - 'talos_options': ['--xperf_path', 09:26:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:50 INFO - 'tests': ['tp5n']}}, 09:26:50 INFO - 'talos.zip': {'path': '', 09:26:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:26:50 INFO - Running post-action listener: _resource_record_post_action 09:26:50 INFO - ##### 09:26:50 INFO - ##### Finished populate-webroot step (success) 09:26:50 INFO - ##### 09:26:50 INFO - ##### 09:26:50 INFO - ##### Running create-virtualenv step. 09:26:50 INFO - ##### 09:26:50 INFO - Running pre-action listener: _resource_record_pre_action 09:26:50 INFO - Running main action method: create_virtualenv 09:26:50 INFO - Creating virtualenv /builds/slave/test/build/venv 09:26:50 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 09:26:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:26:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:26:51 INFO - Using real prefix '/tools/python27' 09:26:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:26:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:26:54 INFO - Installing pip.................done. 09:26:54 INFO - Return code: 0 09:26:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e51aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd40cbd670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3bed30>, '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 0x10e1e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5b20>, '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.FpyQHxTj68/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.eZE4rAUVD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:26:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FpyQHxTj68/Render', 09:26:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:54 INFO - 'HOME': '/Users/cltbld', 09:26:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:54 INFO - 'LOGNAME': 'cltbld', 09:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:54 INFO - 'MOZ_NO_REMOTE': '1', 09:26:54 INFO - 'NO_EM_RESTART': '1', 09:26:54 INFO - 'PAGER': '/bin/cat', 09:26:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:54 INFO - 'PWD': '/builds/slave/test', 09:26:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:54 INFO - 'SHELL': '/bin/bash', 09:26:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eZE4rAUVD1/Listeners', 09:26:54 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:26:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:54 INFO - 'USER': 'cltbld', 09:26:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:54 INFO - 'XPC_FLAGS': '0x0', 09:26:54 INFO - 'XPC_SERVICE_NAME': '0', 09:26:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:54 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 09:26:54 INFO - Cleaning up... 09:26:54 INFO - Return code: 0 09:26:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e51aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd40cbd670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3bed30>, '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 0x10e1e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5b20>, '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.FpyQHxTj68/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.eZE4rAUVD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:26:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FpyQHxTj68/Render', 09:26:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:54 INFO - 'HOME': '/Users/cltbld', 09:26:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:54 INFO - 'LOGNAME': 'cltbld', 09:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:54 INFO - 'MOZ_NO_REMOTE': '1', 09:26:54 INFO - 'NO_EM_RESTART': '1', 09:26:54 INFO - 'PAGER': '/bin/cat', 09:26:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:54 INFO - 'PWD': '/builds/slave/test', 09:26:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:54 INFO - 'SHELL': '/bin/bash', 09:26:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eZE4rAUVD1/Listeners', 09:26:54 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:26:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:54 INFO - 'USER': 'cltbld', 09:26:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:54 INFO - 'XPC_FLAGS': '0x0', 09:26:54 INFO - 'XPC_SERVICE_NAME': '0', 09:26:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:55 INFO - Downloading/unpacking psutil>=0.7.1 09:26:55 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 09:26:55 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 09:26:55 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 09:26:55 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 09:26:55 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 09:26:55 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 09:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:26:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:58 INFO - Installing collected packages: psutil 09:26:58 INFO - Running setup.py install for psutil 09:26:58 INFO - building 'psutil._psutil_osx' extension 09:26:58 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 09:26:58 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 09:26:58 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 09:26:58 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 09:26:59 INFO - building 'psutil._psutil_posix' extension 09:26:59 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 09:26:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:26:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:26:59 INFO - ^ 09:26:59 INFO - 1 warning generated. 09:26:59 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 09:26:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:59 INFO - Successfully installed psutil 09:26:59 INFO - Cleaning up... 09:26:59 INFO - Return code: 0 09:26:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e51aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd40cbd670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3bed30>, '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 0x10e1e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5b20>, '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.FpyQHxTj68/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.eZE4rAUVD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:26:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FpyQHxTj68/Render', 09:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:59 INFO - 'HOME': '/Users/cltbld', 09:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:59 INFO - 'LOGNAME': 'cltbld', 09:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:59 INFO - 'MOZ_NO_REMOTE': '1', 09:26:59 INFO - 'NO_EM_RESTART': '1', 09:26:59 INFO - 'PAGER': '/bin/cat', 09:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:59 INFO - 'PWD': '/builds/slave/test', 09:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:59 INFO - 'SHELL': '/bin/bash', 09:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eZE4rAUVD1/Listeners', 09:26:59 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:59 INFO - 'USER': 'cltbld', 09:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:59 INFO - 'XPC_FLAGS': '0x0', 09:26:59 INFO - 'XPC_SERVICE_NAME': '0', 09:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:26:59 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 09:26:59 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 09:26:59 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 09:26:59 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 09:26:59 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 09:26:59 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 09:27:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:27:02 INFO - Installing collected packages: mozsystemmonitor 09:27:02 INFO - Running setup.py install for mozsystemmonitor 09:27:02 INFO - Successfully installed mozsystemmonitor 09:27:02 INFO - Cleaning up... 09:27:02 INFO - Return code: 0 09:27:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:27:02 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')]} 09:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:02 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')]} 09:27:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:02 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 0x10e51aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd40cbd670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3bed30>, '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 0x10e1e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5b20>, '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.FpyQHxTj68/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.eZE4rAUVD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:27:02 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 09:27:02 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 09:27:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FpyQHxTj68/Render', 09:27:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:02 INFO - 'HOME': '/Users/cltbld', 09:27:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:02 INFO - 'LOGNAME': 'cltbld', 09:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:02 INFO - 'MOZ_NO_REMOTE': '1', 09:27:02 INFO - 'NO_EM_RESTART': '1', 09:27:02 INFO - 'PAGER': '/bin/cat', 09:27:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:02 INFO - 'PWD': '/builds/slave/test', 09:27:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:02 INFO - 'SHELL': '/bin/bash', 09:27:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eZE4rAUVD1/Listeners', 09:27:02 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:27:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:02 INFO - 'USER': 'cltbld', 09:27:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:02 INFO - 'XPC_FLAGS': '0x0', 09:27:02 INFO - 'XPC_SERVICE_NAME': '0', 09:27:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:02 INFO - Downloading/unpacking blobuploader==1.2.4 09:27:02 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 09:27:02 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 09:27:02 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 09:27:02 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 09:27:02 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 09:27:02 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 09:27:04 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:06 INFO - Downloading docopt-0.6.1.tar.gz 09:27:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:06 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:06 INFO - Running setup.py install for blobuploader 09:27:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:06 INFO - Running setup.py install for requests 09:27:06 INFO - Running setup.py install for docopt 09:27:07 INFO - Successfully installed blobuploader requests docopt 09:27:07 INFO - Cleaning up... 09:27:07 INFO - Return code: 0 09:27:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:07 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 0x10e51aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd40cbd670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3bed30>, '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 0x10e1e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5b20>, '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.FpyQHxTj68/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.eZE4rAUVD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:27:07 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 09:27:07 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 09:27:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FpyQHxTj68/Render', 09:27:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:07 INFO - 'HOME': '/Users/cltbld', 09:27:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:07 INFO - 'LOGNAME': 'cltbld', 09:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:07 INFO - 'MOZ_NO_REMOTE': '1', 09:27:07 INFO - 'NO_EM_RESTART': '1', 09:27:07 INFO - 'PAGER': '/bin/cat', 09:27:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:07 INFO - 'PWD': '/builds/slave/test', 09:27:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:07 INFO - 'SHELL': '/bin/bash', 09:27:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eZE4rAUVD1/Listeners', 09:27:07 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:27:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:07 INFO - 'USER': 'cltbld', 09:27:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:07 INFO - 'XPC_FLAGS': '0x0', 09:27:07 INFO - 'XPC_SERVICE_NAME': '0', 09:27:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Mj7Uo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-SSCYUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-3z4HXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-L5eKjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ifwvdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:07 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-opXNx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-f75fFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-XcxbdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Nlnu3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-lKOAOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-LeMCAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-nJVQC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-oJ6S8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-eqiPWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ckWx1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-yzHFer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-YwcIuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:09 INFO - Running setup.py install for manifestparser 09:27:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:09 INFO - Running setup.py install for mozcrash 09:27:09 INFO - Running setup.py install for mozdebug 09:27:09 INFO - Running setup.py install for mozdevice 09:27:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:09 INFO - Running setup.py install for mozfile 09:27:09 INFO - Running setup.py install for mozhttpd 09:27:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:10 INFO - Running setup.py install for mozinfo 09:27:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:10 INFO - Running setup.py install for mozInstall 09:27:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:10 INFO - Running setup.py install for mozleak 09:27:10 INFO - Running setup.py install for mozlog 09:27:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:10 INFO - Running setup.py install for moznetwork 09:27:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:10 INFO - Running setup.py install for mozprocess 09:27:11 INFO - Running setup.py install for mozprofile 09:27:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:11 INFO - Running setup.py install for mozrunner 09:27:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:11 INFO - Running setup.py install for mozscreenshot 09:27:11 INFO - Running setup.py install for moztest 09:27:11 INFO - Running setup.py install for mozversion 09:27:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:11 INFO - Cleaning up... 09:27:11 INFO - Return code: 0 09:27:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:11 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')]} 09:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:11 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')]} 09:27:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:11 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 0x10e51aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd40cbd670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3bed30>, '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 0x10e1e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5b20>, '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.FpyQHxTj68/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.eZE4rAUVD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:27:11 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 09:27:11 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 09:27:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FpyQHxTj68/Render', 09:27:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:11 INFO - 'HOME': '/Users/cltbld', 09:27:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:11 INFO - 'LOGNAME': 'cltbld', 09:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:11 INFO - 'MOZ_NO_REMOTE': '1', 09:27:11 INFO - 'NO_EM_RESTART': '1', 09:27:11 INFO - 'PAGER': '/bin/cat', 09:27:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:11 INFO - 'PWD': '/builds/slave/test', 09:27:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:11 INFO - 'SHELL': '/bin/bash', 09:27:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eZE4rAUVD1/Listeners', 09:27:11 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:27:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:11 INFO - 'USER': 'cltbld', 09:27:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:11 INFO - 'XPC_FLAGS': '0x0', 09:27:11 INFO - 'XPC_SERVICE_NAME': '0', 09:27:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-x5M5tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:12 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)) 09:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-1RZI9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:12 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)) 09:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-FvVdyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:12 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)) 09:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-4YkzBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:12 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)) 09:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-WvPwQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:12 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)) 09:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Y6KdDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:12 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)) 09:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-lKZPRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:12 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)) 09:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-2C9BJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:12 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)) 09:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:12 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ARlt5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:13 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)) 09:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-o_efei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 09:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-tMwH65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:13 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)) 09:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-4wvDhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:13 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)) 09:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-c6Kmwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:13 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)) 09:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-6pEhaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:13 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)) 09:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-PkRsX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:13 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)) 09:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-zuVxbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:13 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)) 09:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Xnu0MM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:13 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)) 09:27:13 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)) 09:27:13 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)) 09:27:13 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)) 09:27:13 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)) 09:27:13 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)) 09:27:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:16 INFO - Downloading blessings-1.6.tar.gz 09:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:16 INFO - Installing collected packages: blessings 09:27:16 INFO - Running setup.py install for blessings 09:27:16 INFO - Successfully installed blessings 09:27:16 INFO - Cleaning up... 09:27:16 INFO - Return code: 0 09:27:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:16 INFO - Reading from file tmpfile_stdout 09:27:16 INFO - Current package versions: 09:27:16 INFO - blessings == 1.6 09:27:16 INFO - blobuploader == 1.2.4 09:27:16 INFO - docopt == 0.6.1 09:27:16 INFO - manifestparser == 1.1 09:27:16 INFO - mozInstall == 1.12 09:27:16 INFO - mozcrash == 0.17 09:27:16 INFO - mozdebug == 0.1 09:27:16 INFO - mozdevice == 0.48 09:27:16 INFO - mozfile == 1.2 09:27:16 INFO - mozhttpd == 0.7 09:27:16 INFO - mozinfo == 0.9 09:27:16 INFO - mozleak == 0.1 09:27:16 INFO - mozlog == 3.1 09:27:16 INFO - moznetwork == 0.27 09:27:16 INFO - mozprocess == 0.22 09:27:16 INFO - mozprofile == 0.28 09:27:16 INFO - mozrunner == 6.11 09:27:16 INFO - mozscreenshot == 0.1 09:27:16 INFO - mozsystemmonitor == 0.0 09:27:16 INFO - moztest == 0.7 09:27:16 INFO - mozversion == 1.4 09:27:16 INFO - psutil == 3.1.1 09:27:16 INFO - requests == 1.2.3 09:27:16 INFO - wsgiref == 0.1.2 09:27:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:16 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 0x10e51aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd40cbd670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3bed30>, '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 0x10e1e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5b20>, '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.FpyQHxTj68/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.eZE4rAUVD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:27:16 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 09:27:16 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 09:27:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FpyQHxTj68/Render', 09:27:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:16 INFO - 'HOME': '/Users/cltbld', 09:27:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:16 INFO - 'LOGNAME': 'cltbld', 09:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:16 INFO - 'MOZ_NO_REMOTE': '1', 09:27:16 INFO - 'NO_EM_RESTART': '1', 09:27:16 INFO - 'PAGER': '/bin/cat', 09:27:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:16 INFO - 'PWD': '/builds/slave/test', 09:27:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:16 INFO - 'SHELL': '/bin/bash', 09:27:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eZE4rAUVD1/Listeners', 09:27:16 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:27:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:16 INFO - 'USER': 'cltbld', 09:27:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:16 INFO - 'XPC_FLAGS': '0x0', 09:27:16 INFO - 'XPC_SERVICE_NAME': '0', 09:27:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:17 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)) 09:27:17 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)) 09:27:17 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)) 09:27:17 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)) 09:27:17 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)) 09:27:17 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)) 09:27:17 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)) 09:27:17 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)) 09:27:17 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)) 09:27:17 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)) 09:27:17 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)) 09:27:17 INFO - Cleaning up... 09:27:17 INFO - Return code: 0 09:27:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:17 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 0x10e51aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd40cbd670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3bed30>, '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 0x10e1e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5b20>, '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.FpyQHxTj68/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.eZE4rAUVD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:27:17 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 09:27:17 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 09:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FpyQHxTj68/Render', 09:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:17 INFO - 'HOME': '/Users/cltbld', 09:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:17 INFO - 'LOGNAME': 'cltbld', 09:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:17 INFO - 'MOZ_NO_REMOTE': '1', 09:27:17 INFO - 'NO_EM_RESTART': '1', 09:27:17 INFO - 'PAGER': '/bin/cat', 09:27:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:17 INFO - 'PWD': '/builds/slave/test', 09:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:17 INFO - 'SHELL': '/bin/bash', 09:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eZE4rAUVD1/Listeners', 09:27:17 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:17 INFO - 'USER': 'cltbld', 09:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:17 INFO - 'XPC_FLAGS': '0x0', 09:27:17 INFO - 'XPC_SERVICE_NAME': '0', 09:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:17 INFO - Downloading/unpacking jsonschema 09:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:27:19 INFO - Downloading/unpacking functools32 (from jsonschema) 09:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:20 INFO - Downloading functools32-3.2.3-2.tar.gz 09:27:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:27:20 INFO - warning: no files found matching '*.txt' 09:27:20 INFO - no previously-included directories found matching 'build' 09:27:20 INFO - no previously-included directories found matching 'dist' 09:27:20 INFO - no previously-included directories found matching '.git*' 09:27:20 INFO - Installing collected packages: jsonschema, functools32 09:27:20 INFO - Running setup.py install for functools32 09:27:20 INFO - warning: no files found matching '*.txt' 09:27:20 INFO - no previously-included directories found matching 'build' 09:27:20 INFO - no previously-included directories found matching 'dist' 09:27:20 INFO - no previously-included directories found matching '.git*' 09:27:20 INFO - Successfully installed jsonschema functools32 09:27:20 INFO - Cleaning up... 09:27:20 INFO - Return code: 0 09:27:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:27:20 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')]} 09:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:20 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')]} 09:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:20 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 0x10e51aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd40cbd670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3bed30>, '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 0x10e1e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5b20>, '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.FpyQHxTj68/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.eZE4rAUVD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:27:20 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 09:27:20 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 09:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FpyQHxTj68/Render', 09:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:20 INFO - 'HOME': '/Users/cltbld', 09:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:20 INFO - 'LOGNAME': 'cltbld', 09:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:20 INFO - 'MOZ_NO_REMOTE': '1', 09:27:20 INFO - 'NO_EM_RESTART': '1', 09:27:20 INFO - 'PAGER': '/bin/cat', 09:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:20 INFO - 'PWD': '/builds/slave/test', 09:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:20 INFO - 'SHELL': '/bin/bash', 09:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eZE4rAUVD1/Listeners', 09:27:20 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:20 INFO - 'USER': 'cltbld', 09:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:20 INFO - 'XPC_FLAGS': '0x0', 09:27:20 INFO - 'XPC_SERVICE_NAME': '0', 09:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:21 INFO - Downloading/unpacking flake8 09:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:27:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:27:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:27:24 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 09:27:24 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 09:27:24 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 09:27:24 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 09:27:24 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 09:27:24 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 09:27:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:27:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:27:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:27:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:27:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:27:24 INFO - no previously-included directories found matching 'docs/_build' 09:27:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:27:24 INFO - Running setup.py install for pep8 09:27:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:27:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:27:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:27:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:27:24 INFO - no previously-included directories found matching 'docs/_build' 09:27:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:27:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:27:24 INFO - Cleaning up... 09:27:25 INFO - Return code: 0 09:27:25 INFO - Running post-action listener: _resource_record_post_action 09:27:25 INFO - Running post-action listener: _start_resource_monitoring 09:27:25 INFO - Starting resource monitoring. 09:27:25 INFO - ##### 09:27:25 INFO - ##### Finished create-virtualenv step (success) 09:27:25 INFO - ##### 09:27:25 INFO - ##### 09:27:25 INFO - ##### Running install step. 09:27:25 INFO - ##### 09:27:25 INFO - Running pre-action listener: _resource_record_pre_action 09:27:25 INFO - Running main action method: install 09:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:25 INFO - Reading from file tmpfile_stdout 09:27:25 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:25 INFO - Reading from file tmpfile_stdout 09:27:25 INFO - Output received: 09:27:25 INFO - Usage: mozinstall [options] installer 09:27:25 INFO - Options: 09:27:25 INFO - -h, --help show this help message and exit 09:27:25 INFO - -d DEST, --destination=DEST 09:27:25 INFO - Directory to install application into. [default: 09:27:25 INFO - "/builds/slave/test"] 09:27:25 INFO - --app=APP Application being installed. [default: firefox] 09:27:25 INFO - mkdir: /builds/slave/test/build/application 09:27:25 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'] 09:27:25 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 09:27:58 INFO - Reading from file tmpfile_stdout 09:27:58 INFO - Output received: 09:27:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:27:58 INFO - Running post-action listener: _resource_record_post_action 09:27:58 INFO - ##### 09:27:58 INFO - ##### Finished install step (success) 09:27:58 INFO - ##### 09:27:58 INFO - ##### 09:27:58 INFO - ##### Running run-tests step. 09:27:58 INFO - ##### 09:27:58 INFO - Running pre-action listener: _resource_record_pre_action 09:27:58 INFO - Running main action method: run_tests 09:27:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:58 INFO - 2016-05-11 09:27:58.550 screenresolution[1639:7673] starting screenresolution argv=screenresolution get 09:27:58 INFO - 2016-05-11 09:27:58.552 screenresolution[1639:7673] Display 0: 1600x1200x32@60 09:27:58 INFO - 2016-05-11 09:27:58.558 screenresolution[1640:7675] starting screenresolution argv=screenresolution list 09:27:58 INFO - Available Modes on Display 0 09:27:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:58 INFO - Intel Iris: 09:27:58 INFO - Chipset Model: Intel Iris 09:27:58 INFO - Type: GPU 09:27:58 INFO - Bus: Built-In 09:27:58 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:58 INFO - Vendor: Intel (0x8086) 09:27:58 INFO - Device ID: 0x0a2e 09:27:58 INFO - Revision ID: 0x0009 09:27:58 INFO - Displays: 09:27:58 INFO - Display: 09:27:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:58 INFO - Display Serial Number: 1600x1200 60 09:27:58 INFO - Main Display: Yes 09:27:58 INFO - Mirror: Off 09:27:58 INFO - Online: Yes 09:27:58 INFO - Rotation: Supported 09:27:58 INFO - Return code: 0 09:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:27:58 INFO - Python 2.7.3 09:27:58 INFO - Return code: 0 09:27:58 INFO - grabbing minidump binary from tooltool 09:27:58 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')]} 09:27:58 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 0x10e3bed30>, '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 0x10e1e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:27:58 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 09:27:58 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 09:27:58 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 09:27:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:27:58 INFO - Return code: 0 09:27:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:27:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:27:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:27:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:27:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:28:00 INFO - Return code: 0 09:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 09:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0131 --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 09:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FpyQHxTj68/Render', 09:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:00 INFO - 'HOME': '/Users/cltbld', 09:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:00 INFO - 'LOGNAME': 'cltbld', 09:28:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:28:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:00 INFO - 'MOZ_NO_REMOTE': '1', 09:28:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:28:00 INFO - 'NO_EM_RESTART': '1', 09:28:00 INFO - 'PAGER': '/bin/cat', 09:28:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:00 INFO - 'PWD': '/builds/slave/test', 09:28:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:00 INFO - 'SHELL': '/bin/bash', 09:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eZE4rAUVD1/Listeners', 09:28:00 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:00 INFO - 'USER': 'cltbld', 09:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:00 INFO - 'XPC_FLAGS': '0x0', 09:28:00 INFO - 'XPC_SERVICE_NAME': '0', 09:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 09:28:00 INFO - mozversion application_buildid: 20160511074307 09:28:00 INFO - mozversion application_changeset: 07e3e85b5ce655c2a67ec0da1f76c66435c68ecc 09:28:00 INFO - mozversion application_display_name: Nightly 09:28:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:28:00 INFO - mozversion application_name: Firefox 09:28:00 INFO - mozversion application_remotingname: firefox 09:28:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:28:00 INFO - mozversion application_vendor: Mozilla 09:28:00 INFO - mozversion application_version: 49.0a1 09:28:00 INFO - mozversion platform_buildid: 20160511074307 09:28:00 INFO - mozversion platform_changeset: 07e3e85b5ce655c2a67ec0da1f76c66435c68ecc 09:28:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:28:00 INFO - mozversion platform_version: 49.0a1 09:28:00 INFO - using testdate: 1462984080 09:28:00 INFO - actual date: 1462984080 09:28:00 INFO - starting webserver on 'localhost:49275' 09:28:00 INFO - SUITE-START | Running 2 tests 09:28:00 INFO - TEST-START | dromaeo_css 09:28:00 INFO - operating with platform_type : mac_ 09:28:00 INFO - Initialising browser for dromaeo_css test... 09:28:00 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpQuZuoE/profile http://localhost:49275/getInfo.html) 09:28:02 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 09:28:02 INFO - PROCESS | 1658 | colorDepth:24 09:28:02 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 09:28:02 INFO - PROCESS | 1658 | __metrics 09:28:02 INFO - PROCESS | 1658 | 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" 09:28:02 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:28:03 INFO - TEST-INFO | 1658: exit 0 09:28:03 INFO - Browser initialized. 09:28:03 INFO - Running cycle 1/1 for dromaeo_css test... 09:28:03 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpQuZuoE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:15 INFO - PROCESS | 1660 | RSS: Main: 238841856 09:28:15 INFO - PROCESS | 1660 | 09:29:06 INFO - PROCESS | 1660 | 1462984146972 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] 09:31:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 09:31:18 INFO - PROCESS | 1660 | RSS: Main: 338493440 09:31:18 INFO - PROCESS | 1660 | 09:34:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 09:34:22 INFO - PROCESS | 1660 | RSS: Main: 366432256 09:34:22 INFO - PROCESS | 1660 | 09:37:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 09:37:25 INFO - PROCESS | 1660 | RSS: Main: 366510080 09:37:25 INFO - PROCESS | 1660 | 09:40:27 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 09:40:28 INFO - PROCESS | 1660 | RSS: Main: 368328704 09:40:28 INFO - PROCESS | 1660 | 09:43:31 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 09:43:32 INFO - PROCESS | 1660 | RSS: Main: 338903040 09:43:32 INFO - PROCESS | 1660 | 09:46:35 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 09:46:35 INFO - PROCESS | 1660 | RSS: Main: 375279616 09:46:35 INFO - PROCESS | 1660 | 09:46:35 INFO - PROCESS | 1660 | __start_tp_report 09:46:35 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 09:46:35 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 09:46:35 INFO - PROCESS | 1660 | |i|pagename|runs| 09:46:35 INFO - PROCESS | 1660 | |0;dojo.html;3437.518747375367;3458.757886947913;3465.237647717501;3483.895483135506;3536.6463353664635;5665.518430933371;5696.572757043217;5706.258186435757;5833.766649334027;5840.094785308279;5729.226554415152;5774.566907481945;5794.710264486769;5837.970810145944;5862.413758624136;5135.563477104451;5173.793048278083;5243.659162154467;5250.763715632786;5325.920111198335;5282.31329927109;5337.5196232339085;5351.491608297225;5353.652012619187;5382.004329199091;5162.277281180619;5182.144946083891;5213.348331458567;5232.529072383504;5246.632735708502;5153.433122356538;5166.43169251382;5204.089284375257;5262.394824595182;5322.547583455411;5186.844394668128;5204.479552044803;5212.921806172911;5221.295125158131;5252.842414727565;4665.206914824454;5582.051051321245;5663.178839068312;5699.515541179005;5783.826485205408;6271;6308.179936608211;6329.493640508748;6354.364563543655;6356.904646430307;4751.857444276678;6292.874142517124;6307.148534947769;6315;6344.936550634527;5744.626599271034;5800.738966746507;5811;5958.612690175125;5971.462568368867;6209.441150296495;6279.277883043508;6286.5599408041;6302.905456418157;6322.272938612026;6143.662098584531;6194.721237544321;6198.2252218472695;6216.7824126155365;6294.590851594632;6223.595466294676;6291.748330066837;6297.779577714758;6298.779542715983;6342.841428964312;6098.765064574808;6285.340039295899;6321.873562528724;6342.619442833353;6346.143270658495;6152.384761523812;6190.876182476325;6195.287541932736;6271.404216599416;6306.432421082125;4909.754512274372;4913.091078150554;4957.578605818546;4977.303177555145;4999.325091112726;4416.712913660602;4454.621357184676;4467.687261891701;4506.256467682796;4517.412736344254;4825.276208568673;5699.458551437607;5729.398413166641;5797.536197104221;5874.0014197586515;5624.156376543551;6273.905891411632;6304.5271604628915;6325.841853953688;6349.49204063683;5635;5693.3168019837885;5794.681292528867;5804.593678442468;5813.88372232553;5443.047466693313;5458.372287187024;5493.697846618475;5496.230527726121;5511.751971161308;6365.681715914093;6400.9039864402075;6404.807855764335;6414.903776443264;6417.2299324081405;6162.506999439945;6278.717457714322;6324.367563243639;6324.778632747832;6328.746850125944;6269.843253918689;6297.937020629735;6318.778842740666;6400;6406.1031455597185;6289.119523266655;6302.117703521602;6322.778702745381;6329.841753956188;6332.986722124438;4711.481737008995;6157.32269450369;6170.916780694116;6341.778037768656;6381.361863813581;5880.735366908414;6127.172831667668;6143.082036032849;6144.678894037804;6188.81433556994;6154.353792851686;6160.9383906160365;6166.167567378347;6171.691415429301;6176.197094377702;5866.6480011199465;5934.762609495572;6081.817545473643;6097.451229389376;6113.052476866093;6139.048447490646;6162.476189523941;6165.969170154121;6204.689765511616;6244.0321750128805;6092.847678808065;6105.145279660762;6111;6112.71632957084;6133.539984501091 09:46:35 INFO - PROCESS | 1660 | |1;ext.html;514.8712821794552;528.748844298958;534.1907010878517;562.0950270065194;574.1015311038225;42471.15057698844;44151.55848441519;44168.558314416885;44346.778266108624;46704.532954670525;35819;45283.54716452835;45421;45447;45507;9691.079347461997;9873.802523949516;9877.259205559612;9893.25800564957;9922.057404546556;9708.223342132647;9796.70609881701;9799.90200097997;9820.50897455128;9826.459544725038;9824.803503929917;9847.950760246225;9858.852117218248;9860.408375497493;9872.45701486418;8555.230029297361;9838.508074596313;9841.212702983745;9846.261530385178;9846.65536706219;9689.224862011022;9787.119159275628;9792.461414622192;9794.853077203848;9796.95101524495;41665.79167104145;42011.57988420107;42074.368884466756;42077.15845683069;42155.57844421547;3152.542881282201;3511.20997775501;3790.3745881929726;3948.1511475033008;4092.0997380576227;28911.855440722873;29351.412971740658;29967.550486742715;30083.849580751958;30183.849080754677;2740.6300149479766;2750.7936904732023;2814.2120206342256;2825.081848399258;2837.744602985741;470381;474285.6285718584;477069;480126;484475;59680.10479842806;68771;70253.2974670257;70321.59356812834;70585;61628.07557886635;62310;62365.68817155885;62561.68719156466;62777;16933.49199524016;16979.15104244783;17060.744088838677;17129.914350428167;17143.314267429414;11772.470238839272;12729.745405091846;12821.23072615646;12827.102102852898;12836;20680.482987925123;21392.679109813365;21419.78580214209;21901;21976.34070977841;7396.667149978264;8812.51531165792;8821.823563528822;8833;8833.646654133763;900.6487469886749;951.46242373059;1073.5115522437272;1088.9525761153059;1097.7914196302677;28671.283217919303;29289.414211715648;29447.26381840471;30014.24964375908;30851.22871928175;1031.9329243599293;1133.1218305812897;1133.2916926920675;1149.0175899605883;1150.533070335329;14903.925480372529;15170.848291516944;15200.695986080216;15445.459408920633;15592.610184745245;671631;677010;677713.6114319396;680260;680326;63093.68453157705;63394.049089263695;63603.04595431072;63652.36347636557;63680.36319636744;1339.2098661789591;1495.356996491519;1502.3089378885616;1524.3140586736013;1640.4996476074673;29876.184120090344;30626;30784.692153078184;31019.68980310258;31366.843165784026;54822.72588637031;54874;55903.88192236214;55980.44019559752;56208.43791562032;601325;612748;613267;614579.9271003617;614593;36085.81957090198;43766.78116609396;45002.09995800131;46139.76930115328;46899.765501172275;46770.59688209359;47969;48244.7587762059;48469;48542.27186592275;31015.379692405575;38441.80779096087;38747.03132421599;39212.80393598014;40284.798576006935;38264.04339891525;38486.42270365891;38772;38909.80545097256;39306.60693393029;36840.26319473649;37480.81259593794;37808.62191378051;38408.807955960045;39350.62272820552;32623.34753304968;32671.836640816644;32825.83587082145;33793.66206337905;34781.13047173739;22486.325410237718;24787.50424991454;24818.25545233646;25180.703646020174;25498.745012549636 09:46:35 INFO - PROCESS | 1660 | |2;jquery.html;392.239056230912;398.6671129606778;401.4901075633947;406.54466996963424;427.6621469039459;13381.866181338184;19891.900540497296;20940;20952.26667066653;20985.580288394223;16020.75968860468;17351;19661.21355145796;20354;20816.2714304999;5855.853603659922;6055.666938318391;6096.4817990470865;6097.390260973889;6114.144019837204;5924.763009479616;5972.820815375546;5979.043353063511;5979.22270104888;6040.879182416349;5786.074228123501;5902.970485147568;5904.763809447617;5956.374580669032;5960.910586341187;5684.54523638108;5902.468777809975;5905.527557795366;5915.231019967398;5953.970230148843;5141.434442211354;5142.07442660321;5162.328897243372;5169.715665638389;5180.6373553851245;22327.77672223273;24921.62617560738;26040.479190416085;26185.607215891778;26190.083347082953;2112.926047588327;2150;2154.278316763886;2180.095260466904;2309.572729045132;14216.933729970191;14578.343974521065;14617;14977;15143.242837858063;1427.921464319462;1771.7165253559497;1791.6774980503503;1815.0743121008381;1817.409341963867;123241;128006.99999999907;131322;132204;133493;9512.429254244767;10397.724716714361;10795.514201860935;11631.95312421886;11787.88212117885;12596.748065038832;14970.101793892183;14979.475718349806;14997;14999.850001500063;7335;7615.5430674159725;8029.996250468691;8409.20112589303;8555.700550480828;7510.774676759706;7786.688532458751;8540.487570745776;8641.481511109354;8650.610722517502;4493.618042466427;4583.839565615188;4621.768911554409;4623.815047398067;4695.647826413033;1943.5918457124162;1980.0891589868822;2004.2183548416128;2016.0826823795137;2016.2942969960397;863.8833757442665;903.6204793986542;907.0566610724904;946.2666433513945;960.7213907966751;17297.56756081083;19542.12060457283;19585.804141958684;20355;20440.795592044185;814.348521183063;911.3165126155384;915.6932427636683;950.1591091883595;974.1670871404989;8436.45163064399;9746.415215087118;10220.131288840383;10485.790284194274;10567.067874426108;483947;518223;564948;572777;573496;106892;123632;136575;138138;155561.2221938883;425.30038087346145;459.05205750125884;474.5302150870613;475.38200339559125;477.11971412743435;8985.730428087169;10040.849387259343;10500.947495262475;11031.669049928514;11306.660800176009;54989.72505137449;56213.594660132185;56429.999999998356;56502.43497565136;57181.85636287334;79110.60444697739;80543.59728201322;80679;82540;82551;7699.6535155917;8104.108548059591;8226.958865205635;8250.958745206235;8498.687951561273;9093.863592046257;11306.717332066764;11850.288982661068;12081.489813773278;12993.415296311878;10854;11054.778904422028;11114.944425277821;11282.041026512838;11287.984081432382;10855.131589472981;10869.29349592274;10960.958708922639;11011.00900918921;11013.229073964745;10938.343699378063;11094.057005154653;11442.485088170886;11496.597619083292;11514.963653271247;7750.92249077502;8169.795755105933;8369.748907532783;8442.493450392996;8532.445391049561;9592.616295348107;11127.833082503606;11394.316341019565;11454.198206125491;11563.248388855034 09:46:35 INFO - PROCESS | 1660 | |3;mootools.html;1425.2374979386032;1611.8791090668196;1638.803343598768;1877.662020836249;1998.860079794415;7895.684172633095;8745.781355466108;8956.373053886231;8974.820503589932;9106;8708.95645521773;8933.910660893405;8938.86591701125;8953.641854325819;8976.640934362618;2020.9393718188442;2125.7555381131137;2176.619091658961;2179.9782002180013;2445.8410203336816;2393.5212957408503;2469.3209367423924;2470.481198948223;2478.467129567143;2480.0947654106303;2410.2648692148887;2426.8422552534116;2443.731189569146;2449.595816690244;2450.791682706972;2130.041481333406;2329.697139371871;2396.808255339569;2407.5425669122897;2450.1669432392982;2445.2786428003783;2472.344828620417;2474.542209691195;2474.8886300116537;2478.231748158059;8806.515641639708;8864.955675221583;8869.600867960957;8891.110888911122;8894.911050889474;5355.250264962909;5478.808241711521;5500.367457742371;5519.724013799294;5555.2778138841695;3326.168457885529;3401.523786669868;3608.2422691234874;3620;3624.2207925295925;1037.9221558383151;1052.8894466081103;1062.6865074802952;1075.2150929821196;1076.580133747839;11923.867232612974;11983.820242696367;11984.760304793856;11997.460114294878;12023.699407514794;4780.737059461763;4889.364382630235;4944.085344211332;4945.480724523945;5017.297578339036;9993.850092248622;10091.142252908438;10108.1408080314;10111.7472063199;10135.239857010607;5397.379301380392;5449.373322068013;5499.532539734168;5500.972495137578;5502.889942201134;4462.709923854939;4648.604868586208;4811.326414302001;4975.378077740283;5043.36957880265;3750.7937063461227;3814.1418180909072;3986;4107.527634322031;4143.523494798137;1135.6990397544657;1338.5649663859285;1345.8721421464945;1354.9932250338686;1360.4558176729388;805.564994902746;852.6163226548078;856.8886044814134;925.6343744220995;944.6457578408097;5776.68228247442;5788.27646544182;5812.564057695689;6007.128966299862;6044.1840351552855;962.2350231565922;973.600823662295;976.1605019683067;986.8075725233654;1025.5282570017876;3473.513708080871;3872.206197729459;3930.8820735377985;3960.4851369321805;3985.601439856049;10588.894111058946;10848.891511084947;11094.500747466383;11101.500432480558;11221.270617409842;10795.352278863293;11041.944790275997;11200.159988000933;11207.60773372928;11210.27133236337;8269.338452923752;8506.574671266535;8513.148685131437;8514.403991720645;8532.23209911111;4739.976300118477;5326.4407237241085;5786;5850.063989761617;5926.259217597801;9671.323007389416;9857.704268871945;10161.745956350855;10292.897071029194;10399.792004160026;10283.33158344735;10346.586136554453;10447.320924139907;10466.947665261625;10593.947030265108;6549.770758023446;6737.056812046219;6874.140732408449;6990.8465103257395;7209;8324.167583241628;8464.746057618282;8501.617427215666;8507;8521.105283945097;7441.590712510864;7582.431317651088;7592.658330375036;7657.885131722917;7695;7174.8565028698085;7384.852302954018;7514.248575142661;7635.809104772425;7651.158372579123;6276.497880169484;7068.752593659197;7101.431885449048;7112.03987461686;7163.283671632795;4690.624750020059;4747.382840230748;4880.585150262267;4890.535399137077;5016.949830501649;4816.109019831342;4929.778159982881;4971.6022718183185;5001.174806157089;5105.4639262878345 09:46:35 INFO - PROCESS | 1660 | |4;prototype.html;309.9628044634644;311.42697436716435;315.12552666350865;320.4040484698462;323.2371603016881;4809.90380192396;4871.001444703836;5318.4681531846845;5356.812511562096;5408.781774843971;4445.422095127637;5306.177542480922;5310;5342.492463215998;5353.627214854646;3050.4509188346087;3981.7212795104356;3984.3027470192746;4007.8557971485116;4030.2544029354517;3699.7040236781127;3944.644981951624;4058.492688413948;4073.674106071522;4127.29835927893;3714.2014466889614;3797.4303854421782;4027.4562934003975;4049.6714389226167;4082.061021015009;3618.583862855766;4076.429299897987;4081.204165187775;4096.713230073896;4104.527979282391;3373.797572145679;3442.6385229550788;3492.511048453219;3498.8775392861126;3547.840347184383;5056.797728090872;5058.418281897592;5060.165072763027;5077.111505486562;5099.832571157281;4616.169089563878;4630.768461576941;4663.714779757928;4678.719276843401;4706.105839890444;5097.980403919231;5505.889882202334;5509.449055094498;5514.305514305523;5602.215689803431;3757.3236817372863;3857.4762968627233;3887.611238876118;3900.531936167678;3914.7651140931353;4980.626453016038;5585.664860108406;5683.204351390808;5785;5860.238169038041;3936.9763861396245;3961.1483531040963;3966.2464131814863;3983.4821473208876;3989.7606143631474;3647.872324468684;3989.982554448597;4047.5952404759287;4066.6543343816106;4096.938545921814;4197.748135111903;4215.199112168678;4663.417072865892;4681.297805329228;4686.78143682645;4731.498690968659;5515.669059856422;5534.142207957773;5548.4451554845;5586.944130558642;1363.236587510997;1390.2562129260798;1393.540131756518;1393.8848920863268;1395.483671041721;699.1819571101823;754.9849003019909;804.1757198871087;843.51497888713;848.2154007542998;3623.1847834237615;4130.01759155607;4312.202242585132;4314.331278651814;4317.654587632982;4312.956870431318;4621.2837010263465;4667.17979987805;4785.665003449725;4786.258129989787;3787.5644300905747;3871.864484743077;4203.55862634419;4364.759938203366;4412.382266482695;2355.705536807899;2439.1326870421285;2492.3519884830075;2539.898404063854;2545.7326980667135;4401.1417773534495;4513.2990947422795;4926.285688575136;4933.950660493421;4943.604511639061;4340.457442819647;4606.585407313358;4619.821945403901;4918.827841025547;4974.850754477371;5033.219850923113;5330.813421530228;5967.6121052131475;6005.429484199081;6010.248718910137;4677.602403795716;5726.627769194989;5734.569907257056;5741.971290143522;5742.425757424224;5286.577073834161;5479.506844384025;5618.623437257898;5759.971200143972;5763.193152958505;5309.663747950889;6017.149024624331;6026.758929642765;6058.4850287724275;6062.575619706578;3911.1982043681546;3913.2760439317676;3924.705647076538;3933.4069701770827;3935.4687117238805;4662.976685116552;5696.557837199126;5705.572082093942;5814.244148260868;5820.283016510055;4287.892802679958;4316.374125751686;4624.768761561976;4636.907261854676;5527.336719593513;4844.563989240985;4962.925556116734;5553.305836770403;5579.302587176603;5630.6069920284535;5159.535641792257;5450.781968721207;5480;5523.7238138093735;5557.833265002057;5048.671836330626;5480.533759808182;5532.723363831873;5608.439156084359;5615.157726341082;4359.869203923888;4382.6055654991205;5335.653182543122;5419.780549376359;5477.260569823023 09:46:35 INFO - PROCESS | 1660 | |5;yui.html;667.2293501006336;692.4840993459873;693.4452438049559;712.4300559552357;773.2422226218308;16577.668446631076;17121;17132.914335428304;17534.386296479624;17556.912215438904;16207.108609026498;16264;16290.83709162908;16304.510864674048;16354.182290885468;718.4324383736841;739.2681245566904;924.727205474385;933.2254228989956;934.2899396458706;897.3897749530316;929.2705226397279;931.9580618872133;932.2448816458656;936.8313703533362;974.3812678948883;986.595495846703;987.5950860147341;989.3569180033;991.1377101921339;753.3634079203065;938;974.3617930255623;976.8192884316353;981.6122631560529;730.0290613484053;730.3317464519957;730.3645828129561;730.3974221267423;732.179958446538;14913;14982.250887455584;14993.475228366955;15007.474738384104;15043.774343384857;11997.160198786089;12032.51869925202;12159.027277817753;12285.324307163102;12339.568115115928;10733.087687546567;10764.892351076545;10989.395583242918;11331.32012079278;11432.085433165326;3998.320285551434;4000.3799411091327;4013.0569316210667;4132.132252227036;4142.130152667944;75518;76289.85565216526;77299;77321;77777.22222777756;7550.35821955129;9219.539023048956;9305.48819814903;9531;9599.184069354184;20389.286374976804;20411.897940510204;20413.591728165353;20432.489187770127;20464.69302960457;7115.146182458138;7205.89191162133;7226.349628533457;7311.3785328247695;7472.402207823361;4020.0954785173685;4057.5536690963972;4057.797110144481;4060.614241647039;4076.490438695163;3395.066356751913;3485.076454739496;3500.1949551602765;3505.544279243733;3508.7719298246043;620.0544170140572;639.0286764118514;642.1042645509582;643.1703102997175;646.8343865506125;501.8369030065243;515.1911498946693;542.6716836313973;545.18222666001;557.3145031611141;7275.490715649959;7525.586092764841;7856.253194456192;7862.52824830512;8250.834983300301;507.652258203132;543.3127094225807;543.8993786149575;568.573569822633;575.1774961804576;3608.3685355063285;3717.5167228260693;3745.3258413485833;3764.006562576364;3778.205874886105;83163.5841820787;84365;85290.57354713311;85828.57085714531;86020;66392.33607663958;70030;74889.25110748931;76573.6171319151;78316.60841695755;60081;60351.79296414047;60448.39551604428;61630.69184654048;63346.68326658337;8140.552269625227;8160.959195204224;8217.5480348579;8348.874766878624;8504.659813607386;32404;42070;42531.1493770129;42650.75501197264;42690.5730942699;53849;53954.730226350184;57844.71077644585;58373.4162658368;58765.70617146887;10609.310394824312;10628.946855265674;11056.281341712764;11178.608748693756;11212.215144940103;9512.714618561455;9611.615535378507;9885.308028437941;11711.531538738354;12253;9100.035783865378;11132.88867111351;11173.55305787792;11187.384693841592;11472.082233421474;11094.611688590861;11137.832932505857;11174.161937854857;11245.662630120782;11285.322880627189;10948.56205751761;11270.267432616854;11310.717232068935;11377.93987928282;11459.255148415326;8097.595120244082;9195.770105747142;9240.907590924005;9442.669506567237;9863.802723945624;9379.772992722765;9882.01179882006;9918.355306905029;9929.382622332034;9933.255005874733 09:46:35 INFO - PROCESS | 1660 | __end_tp_report 09:46:35 INFO - PROCESS | 1660 | __start_cc_report 09:46:35 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1248 09:46:35 INFO - PROCESS | 1660 | __end_cc_report 09:46:35 INFO - PROCESS | 1660 | __startTimestamp1462985195585__endTimestamp 09:46:35 INFO - PROCESS | 1660 | 09:46:35 INFO - PROCESS | 1660 | ------- Summary: start ------- 09:46:35 INFO - PROCESS | 1660 | Number of tests: 6 09:46:35 INFO - PROCESS | 1660 | 09:46:35 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5771.59 Median:6092.85 stddev:656.52 (10.8%) stddev-sans-first:632.48 09:46:35 INFO - PROCESS | 1660 | Values: 3437.5 3458.8 3465.2 3483.9 3536.6 5665.5 5696.6 5706.3 5833.8 5840.1 5729.2 5774.6 5794.7 5838.0 5862.4 5135.6 5173.8 5243.7 5250.8 5325.9 5282.3 5337.5 5351.5 5353.7 5382.0 5162.3 5182.1 5213.3 5232.5 5246.6 5153.4 5166.4 5204.1 5262.4 5322.5 5186.8 5204.5 5212.9 5221.3 5252.8 4665.2 5582.1 5663.2 5699.5 5783.8 6271.0 6308.2 6329.5 6354.4 6356.9 4751.9 6292.9 6307.1 6315.0 6344.9 5744.6 5800.7 5811.0 5958.6 5971.5 6209.4 6279.3 6286.6 6302.9 6322.3 6143.7 6194.7 6198.2 6216.8 6294.6 6223.6 6291.7 6297.8 6298.8 6342.8 6098.8 6285.3 6321.9 6342.6 6346.1 6152.4 6190.9 6195.3 6271.4 6306.4 4909.8 4913.1 4957.6 4977.3 4999.3 4416.7 4454.6 4467.7 4506.3 4517.4 4825.3 5699.5 5729.4 5797.5 5874.0 5624.2 6273.9 6304.5 6325.8 6349.5 5635.0 5693.3 5794.7 5804.6 5813.9 5443.0 5458.4 5493.7 5496.2 5511.8 6365.7 6400.9 6404.8 6414.9 6417.2 6162.5 6278.7 6324.4 6324.8 6328.7 6269.8 6297.9 6318.8 6400.0 6406.1 6289.1 6302.1 6322.8 6329.8 6333.0 4711.5 6157.3 6170.9 6341.8 6381.4 5880.7 6127.2 6143.1 6144.7 6188.8 6154.4 6160.9 6166.2 6171.7 6176.2 5866.6 5934.8 6081.8 6097.5 6113.1 6139.0 6162.5 6166.0 6204.7 6244.0 6092.8 6105.1 6111.0 6112.7 6133.5 09:46:35 INFO - PROCESS | 1660 | 09:46:35 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:73149.77 Median:43118.97 stddev:158885.65 (368.5%) stddev-sans-first:159237.77 09:46:35 INFO - PROCESS | 1660 | Values: 514.9 528.7 534.2 562.1 574.1 42471.2 44151.6 44168.6 44346.8 46704.5 35819.0 45283.5 45421.0 45447.0 45507.0 9691.1 9873.8 9877.3 9893.3 9922.1 9708.2 9796.7 9799.9 9820.5 9826.5 9824.8 9848.0 9858.9 9860.4 9872.5 8555.2 9838.5 9841.2 9846.3 9846.7 9689.2 9787.1 9792.5 9794.9 9797.0 41665.8 42011.6 42074.4 42077.2 42155.6 3152.5 3511.2 3790.4 3948.2 4092.1 28911.9 29351.4 29967.6 30083.8 30183.8 2740.6 2750.8 2814.2 2825.1 2837.7 470381.0 474285.6 477069.0 480126.0 484475.0 59680.1 68771.0 70253.3 70321.6 70585.0 61628.1 62310.0 62365.7 62561.7 62777.0 16933.5 16979.2 17060.7 17129.9 17143.3 11772.5 12729.7 12821.2 12827.1 12836.0 20680.5 21392.7 21419.8 21901.0 21976.3 7396.7 8812.5 8821.8 8833.0 8833.6 900.6 951.5 1073.5 1089.0 1097.8 28671.3 29289.4 29447.3 30014.2 30851.2 1031.9 1133.1 1133.3 1149.0 1150.5 14903.9 15170.8 15200.7 15445.5 15592.6 671631.0 677010.0 677713.6 680260.0 680326.0 63093.7 63394.0 63603.0 63652.4 63680.4 1339.2 1495.4 1502.3 1524.3 1640.5 29876.2 30626.0 30784.7 31019.7 31366.8 54822.7 54874.0 55903.9 55980.4 56208.4 601325.0 612748.0 613267.0 614579.9 614593.0 36085.8 43766.8 45002.1 46139.8 46899.8 46770.6 47969.0 48244.8 48469.0 48542.3 31015.4 38441.8 38747.0 39212.8 40284.8 38264.0 38486.4 38772.0 38909.8 39306.6 36840.3 37480.8 37808.6 38408.8 39350.6 32623.3 32671.8 32825.8 33793.7 34781.1 22486.3 24787.5 24818.3 25180.7 25498.7 09:46:35 INFO - PROCESS | 1660 | 09:46:35 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:33761.73 Median:400.08 stddev:91943.42 (22981.3%) stddev-sans-first:92167.21 09:46:35 INFO - PROCESS | 1660 | Values: 392.2 398.7 401.5 406.5 427.7 13381.9 19891.9 20940.0 20952.3 20985.6 16020.8 17351.0 19661.2 20354.0 20816.3 5855.9 6055.7 6096.5 6097.4 6114.1 5924.8 5972.8 5979.0 5979.2 6040.9 5786.1 5903.0 5904.8 5956.4 5960.9 5684.5 5902.5 5905.5 5915.2 5954.0 5141.4 5142.1 5162.3 5169.7 5180.6 22327.8 24921.6 26040.5 26185.6 26190.1 2112.9 2150.0 2154.3 2180.1 2309.6 14216.9 14578.3 14617.0 14977.0 15143.2 1427.9 1771.7 1791.7 1815.1 1817.4 123241.0 128007.0 131322.0 132204.0 133493.0 9512.4 10397.7 10795.5 11632.0 11787.9 12596.7 14970.1 14979.5 14997.0 14999.9 7335.0 7615.5 8030.0 8409.2 8555.7 7510.8 7786.7 8540.5 8641.5 8650.6 4493.6 4583.8 4621.8 4623.8 4695.6 1943.6 1980.1 2004.2 2016.1 2016.3 863.9 903.6 907.1 946.3 960.7 17297.6 19542.1 19585.8 20355.0 20440.8 814.3 911.3 915.7 950.2 974.2 8436.5 9746.4 10220.1 10485.8 10567.1 483947.0 518223.0 564948.0 572777.0 573496.0 106892.0 123632.0 136575.0 138138.0 155561.2 425.3 459.1 474.5 475.4 477.1 8985.7 10040.8 10500.9 11031.7 11306.7 54989.7 56213.6 56430.0 56502.4 57181.9 79110.6 80543.6 80679.0 82540.0 82551.0 7699.7 8104.1 8227.0 8251.0 8498.7 9093.9 11306.7 11850.3 12081.5 12993.4 10854.0 11054.8 11114.9 11282.0 11288.0 10855.1 10869.3 10961.0 11011.0 11013.2 10938.3 11094.1 11442.5 11496.6 11515.0 7750.9 8169.8 8369.7 8442.5 8532.4 9592.6 11127.8 11394.3 11454.2 11563.2 09:46:35 INFO - PROCESS | 1660 | 09:46:35 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5777.48 Median:4848.35 stddev:3274.57 (67.5%) stddev-sans-first:3267.42 09:46:35 INFO - PROCESS | 1660 | Values: 1425.2 1611.9 1638.8 1877.7 1998.9 7895.7 8745.8 8956.4 8974.8 9106.0 8709.0 8933.9 8938.9 8953.6 8976.6 2020.9 2125.8 2176.6 2180.0 2445.8 2393.5 2469.3 2470.5 2478.5 2480.1 2410.3 2426.8 2443.7 2449.6 2450.8 2130.0 2329.7 2396.8 2407.5 2450.2 2445.3 2472.3 2474.5 2474.9 2478.2 8806.5 8865.0 8869.6 8891.1 8894.9 5355.3 5478.8 5500.4 5519.7 5555.3 3326.2 3401.5 3608.2 3620.0 3624.2 1037.9 1052.9 1062.7 1075.2 1076.6 11923.9 11983.8 11984.8 11997.5 12023.7 4780.7 4889.4 4944.1 4945.5 5017.3 9993.9 10091.1 10108.1 10111.7 10135.2 5397.4 5449.4 5499.5 5501.0 5502.9 4462.7 4648.6 4811.3 4975.4 5043.4 3750.8 3814.1 3986.0 4107.5 4143.5 1135.7 1338.6 1345.9 1355.0 1360.5 805.6 852.6 856.9 925.6 944.6 5776.7 5788.3 5812.6 6007.1 6044.2 962.2 973.6 976.2 986.8 1025.5 3473.5 3872.2 3930.9 3960.5 3985.6 10588.9 10848.9 11094.5 11101.5 11221.3 10795.4 11041.9 11200.2 11207.6 11210.3 8269.3 8506.6 8513.1 8514.4 8532.2 4740.0 5326.4 5786.0 5850.1 5926.3 9671.3 9857.7 10161.7 10292.9 10399.8 10283.3 10346.6 10447.3 10466.9 10593.9 6549.8 6737.1 6874.1 6990.8 7209.0 8324.2 8464.7 8501.6 8507.0 8521.1 7441.6 7582.4 7592.7 7657.9 7695.0 7174.9 7384.9 7514.2 7635.8 7651.2 6276.5 7068.8 7101.4 7112.0 7163.3 4690.6 4747.4 4880.6 4890.5 5016.9 4816.1 4929.8 4971.6 5001.2 5105.5 09:46:35 INFO - PROCESS | 1660 | 09:46:35 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4367.89 Median:4665.45 stddev:1339.24 (28.7%) stddev-sans-first:1307.90 09:46:35 INFO - PROCESS | 1660 | Values: 310.0 311.4 315.1 320.4 323.2 4809.9 4871.0 5318.5 5356.8 5408.8 4445.4 5306.2 5310.0 5342.5 5353.6 3050.5 3981.7 3984.3 4007.9 4030.3 3699.7 3944.6 4058.5 4073.7 4127.3 3714.2 3797.4 4027.5 4049.7 4082.1 3618.6 4076.4 4081.2 4096.7 4104.5 3373.8 3442.6 3492.5 3498.9 3547.8 5056.8 5058.4 5060.2 5077.1 5099.8 4616.2 4630.8 4663.7 4678.7 4706.1 5098.0 5505.9 5509.4 5514.3 5602.2 3757.3 3857.5 3887.6 3900.5 3914.8 4980.6 5585.7 5683.2 5785.0 5860.2 3937.0 3961.1 3966.2 3983.5 3989.8 3647.9 3990.0 4047.6 4066.7 4096.9 4197.7 4215.2 4663.4 4681.3 4686.8 4731.5 5515.7 5534.1 5548.4 5586.9 1363.2 1390.3 1393.5 1393.9 1395.5 699.2 755.0 804.2 843.5 848.2 3623.2 4130.0 4312.2 4314.3 4317.7 4313.0 4621.3 4667.2 4785.7 4786.3 3787.6 3871.9 4203.6 4364.8 4412.4 2355.7 2439.1 2492.4 2539.9 2545.7 4401.1 4513.3 4926.3 4934.0 4943.6 4340.5 4606.6 4619.8 4918.8 4974.9 5033.2 5330.8 5967.6 6005.4 6010.2 4677.6 5726.6 5734.6 5742.0 5742.4 5286.6 5479.5 5618.6 5760.0 5763.2 5309.7 6017.1 6026.8 6058.5 6062.6 3911.2 3913.3 3924.7 3933.4 3935.5 4663.0 5696.6 5705.6 5814.2 5820.3 4287.9 4316.4 4624.8 4636.9 5527.3 4844.6 4962.9 5553.3 5579.3 5630.6 5159.5 5450.8 5480.0 5523.7 5557.8 5048.7 5480.5 5532.7 5608.4 5615.2 4359.9 4382.6 5335.7 5419.8 5477.3 09:46:35 INFO - PROCESS | 1660 | 09:46:35 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:17043.90 Median:59423.35 stddev:23177.31 (39.0%) stddev-sans-first:23209.71 09:46:35 INFO - PROCESS | 1660 | Values: 667.2 692.5 693.4 712.4 773.2 16577.7 17121.0 17132.9 17534.4 17556.9 16207.1 16264.0 16290.8 16304.5 16354.2 718.4 739.3 924.7 933.2 934.3 897.4 929.3 932.0 932.2 936.8 974.4 986.6 987.6 989.4 991.1 753.4 938.0 974.4 976.8 981.6 730.0 730.3 730.4 730.4 732.2 14913.0 14982.3 14993.5 15007.5 15043.8 11997.2 12032.5 12159.0 12285.3 12339.6 10733.1 10764.9 10989.4 11331.3 11432.1 3998.3 4000.4 4013.1 4132.1 4142.1 75518.0 76289.9 77299.0 77321.0 77777.2 7550.4 9219.5 9305.5 9531.0 9599.2 20389.3 20411.9 20413.6 20432.5 20464.7 7115.1 7205.9 7226.3 7311.4 7472.4 4020.1 4057.6 4057.8 4060.6 4076.5 3395.1 3485.1 3500.2 3505.5 3508.8 620.1 639.0 642.1 643.2 646.8 501.8 515.2 542.7 545.2 557.3 7275.5 7525.6 7856.3 7862.5 8250.8 507.7 543.3 543.9 568.6 575.2 3608.4 3717.5 3745.3 3764.0 3778.2 83163.6 84365.0 85290.6 85828.6 86020.0 66392.3 70030.0 74889.3 76573.6 78316.6 60081.0 60351.8 60448.4 61630.7 63346.7 8140.6 8161.0 8217.5 8348.9 8504.7 32404.0 42070.0 42531.1 42650.8 42690.6 53849.0 53954.7 57844.7 58373.4 58765.7 10609.3 10628.9 11056.3 11178.6 11212.2 9512.7 9611.6 9885.3 11711.5 12253.0 9100.0 11132.9 11173.6 11187.4 11472.1 11094.6 11137.8 11174.2 11245.7 11285.3 10948.6 11270.3 11310.7 11377.9 11459.3 8097.6 9195.8 9240.9 9442.7 9863.8 9379.8 9882.0 9918.4 9929.4 9933.3 09:46:35 INFO - PROCESS | 1660 | -------- Summary: end -------- 09:46:35 INFO - PROCESS | 1660 | 09:46:36 INFO - TEST-INFO | 1660: exit 0 09:46:36 INFO - TEST-OK | dromaeo_css | took 1115883ms 09:46:36 INFO - TEST-START | kraken 09:46:36 INFO - operating with platform_type : mac_ 09:46:36 INFO - Initialising browser for kraken test... 09:46:36 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpfdYkmP/profile http://localhost:49275/getInfo.html) 09:46:38 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 09:46:38 INFO - PROCESS | 1672 | colorDepth:24 09:46:38 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 09:46:38 INFO - PROCESS | 1672 | __metrics 09:46:38 INFO - PROCESS | 1672 | 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" 09:46:38 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:46:39 INFO - TEST-INFO | 1672: exit 0 09:46:39 INFO - Browser initialized. 09:46:39 INFO - Running cycle 1/1 for kraken test... 09:46:39 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpfdYkmP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:51 INFO - PROCESS | 1674 | RSS: Main: 238587904 09:46:51 INFO - PROCESS | 1674 | 09:46:53 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 09:46:54 INFO - PROCESS | 1674 | RSS: Main: 286613504 09:46:54 INFO - PROCESS | 1674 | 09:46:57 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 09:46:58 INFO - PROCESS | 1674 | RSS: Main: 295591936 09:46:58 INFO - PROCESS | 1674 | 09:47:01 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 09:47:02 INFO - PROCESS | 1674 | RSS: Main: 382246912 09:47:02 INFO - PROCESS | 1674 | 09:47:04 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 09:47:04 INFO - PROCESS | 1674 | RSS: Main: 283123712 09:47:04 INFO - PROCESS | 1674 | 09:47:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:47:07 INFO - PROCESS | 1674 | RSS: Main: 286801920 09:47:07 INFO - PROCESS | 1674 | 09:47:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 09:47:11 INFO - PROCESS | 1674 | RSS: Main: 326475776 09:47:11 INFO - PROCESS | 1674 | 09:47:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:15 INFO - PROCESS | 1674 | RSS: Main: 327491584 09:47:15 INFO - PROCESS | 1674 | 09:47:18 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 09:47:19 INFO - PROCESS | 1674 | RSS: Main: 327045120 09:47:19 INFO - PROCESS | 1674 | 09:47:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:21 INFO - PROCESS | 1674 | RSS: Main: 316067840 09:47:21 INFO - PROCESS | 1674 | 09:47:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:24 INFO - PROCESS | 1674 | RSS: Main: 322793472 09:47:24 INFO - PROCESS | 1674 | 09:47:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:27 INFO - PROCESS | 1674 | RSS: Main: 325013504 09:47:27 INFO - PROCESS | 1674 | 09:47:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:30 INFO - PROCESS | 1674 | RSS: Main: 329265152 09:47:30 INFO - PROCESS | 1674 | 09:47:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:47:34 INFO - PROCESS | 1674 | RSS: Main: 327020544 09:47:34 INFO - PROCESS | 1674 | 09:47:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 09:47:36 INFO - PROCESS | 1674 | RSS: Main: 373788672 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | __start_tp_report 09:47:36 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 09:47:36 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 09:47:36 INFO - PROCESS | 1674 | |i|pagename|runs| 09:47:36 INFO - PROCESS | 1674 | |0;ai-astar;113;106;112;111;113;112;111;113;113;112 09:47:36 INFO - PROCESS | 1674 | |1;audio-beat-detection;157;118;142;199;118;141;138;117;168;119 09:47:36 INFO - PROCESS | 1674 | |2;audio-dft;188;183;185;184;190;185;185;183;186;181 09:47:36 INFO - PROCESS | 1674 | |3;audio-fft;83;73;57;126;61;62;57;60;126;80 09:47:36 INFO - PROCESS | 1674 | |4;audio-oscillator;94;97;134;108;113;110;103;80;84;102 09:47:36 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;89;85;89;86;87;86;94;99;134;85 09:47:36 INFO - PROCESS | 1674 | |6;imaging-darkroom;97;92;93;94;93;93;93;97;143;94 09:47:36 INFO - PROCESS | 1674 | |7;imaging-desaturate;86;85;85;85;85;84;85;95;140;130 09:47:36 INFO - PROCESS | 1674 | |8;json-parse-financial;57;58;58;58;58;60;59;121;59;59 09:47:36 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;63;69;65;81;72;71;70;65;68;66 09:47:36 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;86;85;86;84;85;85;82;83;85;86 09:47:36 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;140;121;153;148;147;139;140;140;234;141 09:47:36 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;168;170;168;174;168;168;168;170;167;165 09:47:36 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;66;67;68;68;67;65;66;66;66;66 09:47:36 INFO - PROCESS | 1674 | __end_tp_report 09:47:36 INFO - PROCESS | 1674 | __start_cc_report 09:47:36 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,2200 09:47:36 INFO - PROCESS | 1674 | __end_cc_report 09:47:36 INFO - PROCESS | 1674 | __startTimestamp1462985256151__endTimestamp 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | ------- Summary: start ------- 09:47:36 INFO - PROCESS | 1674 | Number of tests: 14 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:2.12 (1.9%) stddev-sans-first:2.19 09:47:36 INFO - PROCESS | 1674 | Values: 113.0 106.0 112.0 111.0 113.0 112.0 111.0 113.0 113.0 112.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:141.50 stddev:26.82 (19.0%) stddev-sans-first:27.87 09:47:36 INFO - PROCESS | 1674 | Values: 157.0 118.0 142.0 199.0 118.0 141.0 138.0 117.0 168.0 119.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.00 stddev:2.58 (1.4%) stddev-sans-first:2.50 09:47:36 INFO - PROCESS | 1674 | Values: 188.0 183.0 185.0 184.0 190.0 185.0 185.0 183.0 186.0 181.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:78.50 Median:61.50 stddev:26.69 (43.4%) stddev-sans-first:28.26 09:47:36 INFO - PROCESS | 1674 | Values: 83.0 73.0 57.0 126.0 61.0 62.0 57.0 60.0 126.0 80.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:107.00 stddev:15.42 (14.4%) stddev-sans-first:16.05 09:47:36 INFO - PROCESS | 1674 | Values: 94.0 97.0 134.0 108.0 113.0 110.0 103.0 80.0 84.0 102.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:93.40 Median:88.00 stddev:14.95 (17.0%) stddev-sans-first:15.77 09:47:36 INFO - PROCESS | 1674 | Values: 89.0 85.0 89.0 86.0 87.0 86.0 94.0 99.0 134.0 85.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:98.90 Median:93.50 stddev:15.59 (16.7%) stddev-sans-first:16.52 09:47:36 INFO - PROCESS | 1674 | Values: 97.0 92.0 93.0 94.0 93.0 93.0 93.0 97.0 143.0 94.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:85.00 stddev:20.93 (24.6%) stddev-sans-first:21.88 09:47:36 INFO - PROCESS | 1674 | Values: 86.0 85.0 85.0 85.0 85.0 84.0 85.0 95.0 140.0 130.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:58.50 stddev:19.80 (33.8%) stddev-sans-first:20.80 09:47:36 INFO - PROCESS | 1674 | Values: 57.0 58.0 58.0 58.0 58.0 60.0 59.0 121.0 59.0 59.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:69.50 stddev:5.12 (7.4%) stddev-sans-first:4.95 09:47:36 INFO - PROCESS | 1674 | Values: 63.0 69.0 65.0 81.0 72.0 71.0 70.0 65.0 68.0 66.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.33 09:47:36 INFO - PROCESS | 1674 | Values: 86.0 85.0 86.0 84.0 85.0 85.0 82.0 83.0 85.0 86.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:144.00 stddev:30.59 (21.2%) stddev-sans-first:32.21 09:47:36 INFO - PROCESS | 1674 | Values: 140.0 121.0 153.0 148.0 147.0 139.0 140.0 140.0 234.0 141.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:168.00 stddev:2.37 (1.4%) stddev-sans-first:2.50 09:47:36 INFO - PROCESS | 1674 | Values: 168.0 170.0 168.0 174.0 168.0 168.0 168.0 170.0 167.0 165.0 09:47:36 INFO - PROCESS | 1674 | 09:47:36 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.50 stddev:0.97 (1.5%) stddev-sans-first:1.01 09:47:36 INFO - PROCESS | 1674 | Values: 66.0 67.0 68.0 68.0 67.0 65.0 66.0 66.0 66.0 66.0 09:47:36 INFO - PROCESS | 1674 | -------- Summary: end -------- 09:47:36 INFO - PROCESS | 1674 | 09:47:37 INFO - TEST-INFO | 1674: exit 0 09:47:37 INFO - TEST-OK | kraken | took 60986ms 09:47:37 INFO - SUITE-END | took 1176s 09:47:37 INFO - Completed test suite (00:19:37) 09:47:37 INFO - javascript benchmark 09:47:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3437.518747375367, 3458.757886947913, 3465.237647717501, 3483.895483135506, 3536.6463353664635, 5665.518430933371, 5696.572757043217, 5706.258186435757, 5833.766649334027, 5840.094785308279, 5729.226554415152, 5774.566907481945, 5794.710264486769, 5837.970810145944, 5862.413758624136, 5135.563477104451, 5173.793048278083, 5243.659162154467, 5250.763715632786, 5325.920111198335, 5282.31329927109, 5337.5196232339085, 5351.491608297225, 5353.652012619187, 5382.004329199091, 5162.277281180619, 5182.144946083891, 5213.348331458567, 5232.529072383504, 5246.632735708502, 5153.433122356538, 5166.43169251382, 5204.089284375257, 5262.394824595182, 5322.547583455411, 5186.844394668128, 5204.479552044803, 5212.921806172911, 5221.295125158131, 5252.842414727565, 4665.206914824454, 5582.051051321245, 5663.178839068312, 5699.515541179005, 5783.826485205408, 6271.0, 6308.179936608211, 6329.493640508748, 6354.364563543655, 6356.904646430307, 4751.857444276678, 6292.874142517124, 6307.148534947769, 6315.0, 6344.936550634527, 5744.626599271034, 5800.738966746507, 5811.0, 5958.612690175125, 5971.462568368867, 6209.441150296495, 6279.277883043508, 6286.5599408041, 6302.905456418157, 6322.272938612026, 6143.662098584531, 6194.721237544321, 6198.2252218472695, 6216.7824126155365, 6294.590851594632, 6223.595466294676, 6291.748330066837, 6297.779577714758, 6298.779542715983, 6342.841428964312, 6098.765064574808, 6285.340039295899, 6321.873562528724, 6342.619442833353, 6346.143270658495, 6152.384761523812, 6190.876182476325, 6195.287541932736, 6271.404216599416, 6306.432421082125, 4909.754512274372, 4913.091078150554, 4957.578605818546, 4977.303177555145, 4999.325091112726, 4416.712913660602, 4454.621357184676, 4467.687261891701, 4506.256467682796, 4517.412736344254, 4825.276208568673, 5699.458551437607, 5729.398413166641, 5797.536197104221, 5874.0014197586515, 5624.156376543551, 6273.905891411632, 6304.5271604628915, 6325.841853953688, 6349.49204063683, 5635.0, 5693.3168019837885, 5794.681292528867, 5804.593678442468, 5813.88372232553, 5443.047466693313, 5458.372287187024, 5493.697846618475, 5496.230527726121, 5511.751971161308, 6365.681715914093, 6400.9039864402075, 6404.807855764335, 6414.903776443264, 6417.2299324081405, 6162.506999439945, 6278.717457714322, 6324.367563243639, 6324.778632747832, 6328.746850125944, 6269.843253918689, 6297.937020629735, 6318.778842740666, 6400.0, 6406.1031455597185, 6289.119523266655, 6302.117703521602, 6322.778702745381, 6329.841753956188, 6332.986722124438, 4711.481737008995, 6157.32269450369, 6170.916780694116, 6341.778037768656, 6381.361863813581, 5880.735366908414, 6127.172831667668, 6143.082036032849, 6144.678894037804, 6188.81433556994, 6154.353792851686, 6160.9383906160365, 6166.167567378347, 6171.691415429301, 6176.197094377702, 5866.6480011199465, 5934.762609495572, 6081.817545473643, 6097.451229389376, 6113.052476866093, 6139.048447490646, 6162.476189523941, 6165.969170154121, 6204.689765511616, 6244.0321750128805, 6092.847678808065, 6105.145279660762, 6111.0, 6112.71632957084, 6133.539984501091], "value": 5731.907932248177, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.8712821794552, 528.748844298958, 534.1907010878517, 562.0950270065194, 574.1015311038225, 42471.15057698844, 44151.55848441519, 44168.558314416885, 44346.778266108624, 46704.532954670525, 35819.0, 45283.54716452835, 45421.0, 45447.0, 45507.0, 9691.079347461997, 9873.802523949516, 9877.259205559612, 9893.25800564957, 9922.057404546556, 9708.223342132647, 9796.70609881701, 9799.90200097997, 9820.50897455128, 9826.459544725038, 9824.803503929917, 9847.950760246225, 9858.852117218248, 9860.408375497493, 9872.45701486418, 8555.230029297361, 9838.508074596313, 9841.212702983745, 9846.261530385178, 9846.65536706219, 9689.224862011022, 9787.119159275628, 9792.461414622192, 9794.853077203848, 9796.95101524495, 41665.79167104145, 42011.57988420107, 42074.368884466756, 42077.15845683069, 42155.57844421547, 3152.542881282201, 3511.20997775501, 3790.3745881929726, 3948.1511475033008, 4092.0997380576227, 28911.855440722873, 29351.412971740658, 29967.550486742715, 30083.849580751958, 30183.849080754677, 2740.6300149479766, 2750.7936904732023, 2814.2120206342256, 2825.081848399258, 2837.744602985741, 470381.0, 474285.6285718584, 477069.0, 480126.0, 484475.0, 59680.10479842806, 68771.0, 70253.2974670257, 70321.59356812834, 70585.0, 61628.07557886635, 62310.0, 62365.68817155885, 62561.68719156466, 62777.0, 16933.49199524016, 16979.15104244783, 17060.744088838677, 17129.914350428167, 17143.314267429414, 11772.470238839272, 12729.745405091846, 12821.23072615646, 12827.102102852898, 12836.0, 20680.482987925123, 21392.679109813365, 21419.78580214209, 21901.0, 21976.34070977841, 7396.667149978264, 8812.51531165792, 8821.823563528822, 8833.0, 8833.646654133763, 900.6487469886749, 951.46242373059, 1073.5115522437272, 1088.9525761153059, 1097.7914196302677, 28671.283217919303, 29289.414211715648, 29447.26381840471, 30014.24964375908, 30851.22871928175, 1031.9329243599293, 1133.1218305812897, 1133.2916926920675, 1149.0175899605883, 1150.533070335329, 14903.925480372529, 15170.848291516944, 15200.695986080216, 15445.459408920633, 15592.610184745245, 671631.0, 677010.0, 677713.6114319396, 680260.0, 680326.0, 63093.68453157705, 63394.049089263695, 63603.04595431072, 63652.36347636557, 63680.36319636744, 1339.2098661789591, 1495.356996491519, 1502.3089378885616, 1524.3140586736013, 1640.4996476074673, 29876.184120090344, 30626.0, 30784.692153078184, 31019.68980310258, 31366.843165784026, 54822.72588637031, 54874.0, 55903.88192236214, 55980.44019559752, 56208.43791562032, 601325.0, 612748.0, 613267.0, 614579.9271003617, 614593.0, 36085.81957090198, 43766.78116609396, 45002.09995800131, 46139.76930115328, 46899.765501172275, 46770.59688209359, 47969.0, 48244.7587762059, 48469.0, 48542.27186592275, 31015.379692405575, 38441.80779096087, 38747.03132421599, 39212.80393598014, 40284.798576006935, 38264.04339891525, 38486.42270365891, 38772.0, 38909.80545097256, 39306.60693393029, 36840.26319473649, 37480.81259593794, 37808.62191378051, 38408.807955960045, 39350.62272820552, 32623.34753304968, 32671.836640816644, 32825.83587082145, 33793.66206337905, 34781.13047173739, 22486.325410237718, 24787.50424991454, 24818.25545233646, 25180.703646020174, 25498.745012549636], "value": 20867.935851530205, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.239056230912, 398.6671129606778, 401.4901075633947, 406.54466996963424, 427.6621469039459, 13381.866181338184, 19891.900540497296, 20940.0, 20952.26667066653, 20985.580288394223, 16020.75968860468, 17351.0, 19661.21355145796, 20354.0, 20816.2714304999, 5855.853603659922, 6055.666938318391, 6096.4817990470865, 6097.390260973889, 6114.144019837204, 5924.763009479616, 5972.820815375546, 5979.043353063511, 5979.22270104888, 6040.879182416349, 5786.074228123501, 5902.970485147568, 5904.763809447617, 5956.374580669032, 5960.910586341187, 5684.54523638108, 5902.468777809975, 5905.527557795366, 5915.231019967398, 5953.970230148843, 5141.434442211354, 5142.07442660321, 5162.328897243372, 5169.715665638389, 5180.6373553851245, 22327.77672223273, 24921.62617560738, 26040.479190416085, 26185.607215891778, 26190.083347082953, 2112.926047588327, 2150.0, 2154.278316763886, 2180.095260466904, 2309.572729045132, 14216.933729970191, 14578.343974521065, 14617.0, 14977.0, 15143.242837858063, 1427.921464319462, 1771.7165253559497, 1791.6774980503503, 1815.0743121008381, 1817.409341963867, 123241.0, 128006.99999999907, 131322.0, 132204.0, 133493.0, 9512.429254244767, 10397.724716714361, 10795.514201860935, 11631.95312421886, 11787.88212117885, 12596.748065038832, 14970.101793892183, 14979.475718349806, 14997.0, 14999.850001500063, 7335.0, 7615.5430674159725, 8029.996250468691, 8409.20112589303, 8555.700550480828, 7510.774676759706, 7786.688532458751, 8540.487570745776, 8641.481511109354, 8650.610722517502, 4493.618042466427, 4583.839565615188, 4621.768911554409, 4623.815047398067, 4695.647826413033, 1943.5918457124162, 1980.0891589868822, 2004.2183548416128, 2016.0826823795137, 2016.2942969960397, 863.8833757442665, 903.6204793986542, 907.0566610724904, 946.2666433513945, 960.7213907966751, 17297.56756081083, 19542.12060457283, 19585.804141958684, 20355.0, 20440.795592044185, 814.348521183063, 911.3165126155384, 915.6932427636683, 950.1591091883595, 974.1670871404989, 8436.45163064399, 9746.415215087118, 10220.131288840383, 10485.790284194274, 10567.067874426108, 483947.0, 518223.0, 564948.0, 572777.0, 573496.0, 106892.0, 123632.0, 136575.0, 138138.0, 155561.2221938883, 425.30038087346145, 459.05205750125884, 474.5302150870613, 475.38200339559125, 477.11971412743435, 8985.730428087169, 10040.849387259343, 10500.947495262475, 11031.669049928514, 11306.660800176009, 54989.72505137449, 56213.594660132185, 56429.999999998356, 56502.43497565136, 57181.85636287334, 79110.60444697739, 80543.59728201322, 80679.0, 82540.0, 82551.0, 7699.6535155917, 8104.108548059591, 8226.958865205635, 8250.958745206235, 8498.687951561273, 9093.863592046257, 11306.717332066764, 11850.288982661068, 12081.489813773278, 12993.415296311878, 10854.0, 11054.778904422028, 11114.944425277821, 11282.041026512838, 11287.984081432382, 10855.131589472981, 10869.29349592274, 10960.958708922639, 11011.00900918921, 11013.229073964745, 10938.343699378063, 11094.057005154653, 11442.485088170886, 11496.597619083292, 11514.963653271247, 7750.92249077502, 8169.795755105933, 8369.748907532783, 8442.493450392996, 8532.445391049561, 9592.616295348107, 11127.833082503606, 11394.316341019565, 11454.198206125491, 11563.248388855034], "value": 9192.017696465033, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.2374979386032, 1611.8791090668196, 1638.803343598768, 1877.662020836249, 1998.860079794415, 7895.684172633095, 8745.781355466108, 8956.373053886231, 8974.820503589932, 9106.0, 8708.95645521773, 8933.910660893405, 8938.86591701125, 8953.641854325819, 8976.640934362618, 2020.9393718188442, 2125.7555381131137, 2176.619091658961, 2179.9782002180013, 2445.8410203336816, 2393.5212957408503, 2469.3209367423924, 2470.481198948223, 2478.467129567143, 2480.0947654106303, 2410.2648692148887, 2426.8422552534116, 2443.731189569146, 2449.595816690244, 2450.791682706972, 2130.041481333406, 2329.697139371871, 2396.808255339569, 2407.5425669122897, 2450.1669432392982, 2445.2786428003783, 2472.344828620417, 2474.542209691195, 2474.8886300116537, 2478.231748158059, 8806.515641639708, 8864.955675221583, 8869.600867960957, 8891.110888911122, 8894.911050889474, 5355.250264962909, 5478.808241711521, 5500.367457742371, 5519.724013799294, 5555.2778138841695, 3326.168457885529, 3401.523786669868, 3608.2422691234874, 3620.0, 3624.2207925295925, 1037.9221558383151, 1052.8894466081103, 1062.6865074802952, 1075.2150929821196, 1076.580133747839, 11923.867232612974, 11983.820242696367, 11984.760304793856, 11997.460114294878, 12023.699407514794, 4780.737059461763, 4889.364382630235, 4944.085344211332, 4945.480724523945, 5017.297578339036, 9993.850092248622, 10091.142252908438, 10108.1408080314, 10111.7472063199, 10135.239857010607, 5397.379301380392, 5449.373322068013, 5499.532539734168, 5500.972495137578, 5502.889942201134, 4462.709923854939, 4648.604868586208, 4811.326414302001, 4975.378077740283, 5043.36957880265, 3750.7937063461227, 3814.1418180909072, 3986.0, 4107.527634322031, 4143.523494798137, 1135.6990397544657, 1338.5649663859285, 1345.8721421464945, 1354.9932250338686, 1360.4558176729388, 805.564994902746, 852.6163226548078, 856.8886044814134, 925.6343744220995, 944.6457578408097, 5776.68228247442, 5788.27646544182, 5812.564057695689, 6007.128966299862, 6044.1840351552855, 962.2350231565922, 973.600823662295, 976.1605019683067, 986.8075725233654, 1025.5282570017876, 3473.513708080871, 3872.206197729459, 3930.8820735377985, 3960.4851369321805, 3985.601439856049, 10588.894111058946, 10848.891511084947, 11094.500747466383, 11101.500432480558, 11221.270617409842, 10795.352278863293, 11041.944790275997, 11200.159988000933, 11207.60773372928, 11210.27133236337, 8269.338452923752, 8506.574671266535, 8513.148685131437, 8514.403991720645, 8532.23209911111, 4739.976300118477, 5326.4407237241085, 5786.0, 5850.063989761617, 5926.259217597801, 9671.323007389416, 9857.704268871945, 10161.745956350855, 10292.897071029194, 10399.792004160026, 10283.33158344735, 10346.586136554453, 10447.320924139907, 10466.947665261625, 10593.947030265108, 6549.770758023446, 6737.056812046219, 6874.140732408449, 6990.8465103257395, 7209.0, 8324.167583241628, 8464.746057618282, 8501.617427215666, 8507.0, 8521.105283945097, 7441.590712510864, 7582.431317651088, 7592.658330375036, 7657.885131722917, 7695.0, 7174.8565028698085, 7384.852302954018, 7514.248575142661, 7635.809104772425, 7651.158372579123, 6276.497880169484, 7068.752593659197, 7101.431885449048, 7112.03987461686, 7163.283671632795, 4690.624750020059, 4747.382840230748, 4880.585150262267, 4890.535399137077, 5016.949830501649, 4816.109019831342, 4929.778159982881, 4971.6022718183185, 5001.174806157089, 5105.4639262878345], "value": 4635.386742056482, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.9628044634644, 311.42697436716435, 315.12552666350865, 320.4040484698462, 323.2371603016881, 4809.90380192396, 4871.001444703836, 5318.4681531846845, 5356.812511562096, 5408.781774843971, 4445.422095127637, 5306.177542480922, 5310.0, 5342.492463215998, 5353.627214854646, 3050.4509188346087, 3981.7212795104356, 3984.3027470192746, 4007.8557971485116, 4030.2544029354517, 3699.7040236781127, 3944.644981951624, 4058.492688413948, 4073.674106071522, 4127.29835927893, 3714.2014466889614, 3797.4303854421782, 4027.4562934003975, 4049.6714389226167, 4082.061021015009, 3618.583862855766, 4076.429299897987, 4081.204165187775, 4096.713230073896, 4104.527979282391, 3373.797572145679, 3442.6385229550788, 3492.511048453219, 3498.8775392861126, 3547.840347184383, 5056.797728090872, 5058.418281897592, 5060.165072763027, 5077.111505486562, 5099.832571157281, 4616.169089563878, 4630.768461576941, 4663.714779757928, 4678.719276843401, 4706.105839890444, 5097.980403919231, 5505.889882202334, 5509.449055094498, 5514.305514305523, 5602.215689803431, 3757.3236817372863, 3857.4762968627233, 3887.611238876118, 3900.531936167678, 3914.7651140931353, 4980.626453016038, 5585.664860108406, 5683.204351390808, 5785.0, 5860.238169038041, 3936.9763861396245, 3961.1483531040963, 3966.2464131814863, 3983.4821473208876, 3989.7606143631474, 3647.872324468684, 3989.982554448597, 4047.5952404759287, 4066.6543343816106, 4096.938545921814, 4197.748135111903, 4215.199112168678, 4663.417072865892, 4681.297805329228, 4686.78143682645, 4731.498690968659, 5515.669059856422, 5534.142207957773, 5548.4451554845, 5586.944130558642, 1363.236587510997, 1390.2562129260798, 1393.540131756518, 1393.8848920863268, 1395.483671041721, 699.1819571101823, 754.9849003019909, 804.1757198871087, 843.51497888713, 848.2154007542998, 3623.1847834237615, 4130.01759155607, 4312.202242585132, 4314.331278651814, 4317.654587632982, 4312.956870431318, 4621.2837010263465, 4667.17979987805, 4785.665003449725, 4786.258129989787, 3787.5644300905747, 3871.864484743077, 4203.55862634419, 4364.759938203366, 4412.382266482695, 2355.705536807899, 2439.1326870421285, 2492.3519884830075, 2539.898404063854, 2545.7326980667135, 4401.1417773534495, 4513.2990947422795, 4926.285688575136, 4933.950660493421, 4943.604511639061, 4340.457442819647, 4606.585407313358, 4619.821945403901, 4918.827841025547, 4974.850754477371, 5033.219850923113, 5330.813421530228, 5967.6121052131475, 6005.429484199081, 6010.248718910137, 4677.602403795716, 5726.627769194989, 5734.569907257056, 5741.971290143522, 5742.425757424224, 5286.577073834161, 5479.506844384025, 5618.623437257898, 5759.971200143972, 5763.193152958505, 5309.663747950889, 6017.149024624331, 6026.758929642765, 6058.4850287724275, 6062.575619706578, 3911.1982043681546, 3913.2760439317676, 3924.705647076538, 3933.4069701770827, 3935.4687117238805, 4662.976685116552, 5696.557837199126, 5705.572082093942, 5814.244148260868, 5820.283016510055, 4287.892802679958, 4316.374125751686, 4624.768761561976, 4636.907261854676, 5527.336719593513, 4844.563989240985, 4962.925556116734, 5553.305836770403, 5579.302587176603, 5630.6069920284535, 5159.535641792257, 5450.781968721207, 5480.0, 5523.7238138093735, 5557.833265002057, 5048.671836330626, 5480.533759808182, 5532.723363831873, 5608.439156084359, 5615.157726341082, 4359.869203923888, 4382.6055654991205, 5335.653182543122, 5419.780549376359, 5477.260569823023], "value": 3946.2017494345646, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.2293501006336, 692.4840993459873, 693.4452438049559, 712.4300559552357, 773.2422226218308, 16577.668446631076, 17121.0, 17132.914335428304, 17534.386296479624, 17556.912215438904, 16207.108609026498, 16264.0, 16290.83709162908, 16304.510864674048, 16354.182290885468, 718.4324383736841, 739.2681245566904, 924.727205474385, 933.2254228989956, 934.2899396458706, 897.3897749530316, 929.2705226397279, 931.9580618872133, 932.2448816458656, 936.8313703533362, 974.3812678948883, 986.595495846703, 987.5950860147341, 989.3569180033, 991.1377101921339, 753.3634079203065, 938.0, 974.3617930255623, 976.8192884316353, 981.6122631560529, 730.0290613484053, 730.3317464519957, 730.3645828129561, 730.3974221267423, 732.179958446538, 14913.0, 14982.250887455584, 14993.475228366955, 15007.474738384104, 15043.774343384857, 11997.160198786089, 12032.51869925202, 12159.027277817753, 12285.324307163102, 12339.568115115928, 10733.087687546567, 10764.892351076545, 10989.395583242918, 11331.32012079278, 11432.085433165326, 3998.320285551434, 4000.3799411091327, 4013.0569316210667, 4132.132252227036, 4142.130152667944, 75518.0, 76289.85565216526, 77299.0, 77321.0, 77777.22222777756, 7550.35821955129, 9219.539023048956, 9305.48819814903, 9531.0, 9599.184069354184, 20389.286374976804, 20411.897940510204, 20413.591728165353, 20432.489187770127, 20464.69302960457, 7115.146182458138, 7205.89191162133, 7226.349628533457, 7311.3785328247695, 7472.402207823361, 4020.0954785173685, 4057.5536690963972, 4057.797110144481, 4060.614241647039, 4076.490438695163, 3395.066356751913, 3485.076454739496, 3500.1949551602765, 3505.544279243733, 3508.7719298246043, 620.0544170140572, 639.0286764118514, 642.1042645509582, 643.1703102997175, 646.8343865506125, 501.8369030065243, 515.1911498946693, 542.6716836313973, 545.18222666001, 557.3145031611141, 7275.490715649959, 7525.586092764841, 7856.253194456192, 7862.52824830512, 8250.834983300301, 507.652258203132, 543.3127094225807, 543.8993786149575, 568.573569822633, 575.1774961804576, 3608.3685355063285, 3717.5167228260693, 3745.3258413485833, 3764.006562576364, 3778.205874886105, 83163.5841820787, 84365.0, 85290.57354713311, 85828.57085714531, 86020.0, 66392.33607663958, 70030.0, 74889.25110748931, 76573.6171319151, 78316.60841695755, 60081.0, 60351.79296414047, 60448.39551604428, 61630.69184654048, 63346.68326658337, 8140.552269625227, 8160.959195204224, 8217.5480348579, 8348.874766878624, 8504.659813607386, 32404.0, 42070.0, 42531.1493770129, 42650.75501197264, 42690.5730942699, 53849.0, 53954.730226350184, 57844.71077644585, 58373.4162658368, 58765.70617146887, 10609.310394824312, 10628.946855265674, 11056.281341712764, 11178.608748693756, 11212.215144940103, 9512.714618561455, 9611.615535378507, 9885.308028437941, 11711.531538738354, 12253.0, 9100.035783865378, 11132.88867111351, 11173.55305787792, 11187.384693841592, 11472.082233421474, 11094.611688590861, 11137.832932505857, 11174.161937854857, 11245.662630120782, 11285.322880627189, 10948.56205751761, 11270.267432616854, 11310.717232068935, 11377.93987928282, 11459.255148415326, 8097.595120244082, 9195.770105747142, 9240.907590924005, 9442.669506567237, 9863.802723945624, 9379.772992722765, 9882.01179882006, 9918.355306905029, 9929.382622332034, 9933.255005874733], "value": 6741.816590986773, "unit": "score"}], "name": "dromaeo_css", "value": 7167.703458719683, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 112.0, 111.0, 113.0, 112.0, 111.0, 113.0, 113.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 118.0, 142.0, 199.0, 118.0, 141.0, 138.0, 117.0, 168.0, 119.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 183.0, 185.0, 184.0, 190.0, 185.0, 185.0, 183.0, 186.0, 181.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 73.0, 57.0, 126.0, 61.0, 62.0, 57.0, 60.0, 126.0, 80.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 134.0, 108.0, 113.0, 110.0, 103.0, 80.0, 84.0, 102.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 86.0, 87.0, 86.0, 94.0, 99.0, 134.0, 85.0], "value": 93.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 92.0, 93.0, 94.0, 93.0, 93.0, 93.0, 97.0, 143.0, 94.0], "value": 98.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 85.0, 85.0, 84.0, 85.0, 95.0, 140.0, 130.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 58.0, 58.0, 60.0, 59.0, 121.0, 59.0, 59.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 69.0, 65.0, 81.0, 72.0, 71.0, 70.0, 65.0, 68.0, 66.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 84.0, 85.0, 85.0, 82.0, 83.0, 85.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 121.0, 153.0, 148.0, 147.0, 139.0, 140.0, 140.0, 234.0, 141.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 168.0, 174.0, 168.0, 168.0, 168.0, 170.0, 167.0, 165.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 68.0, 67.0, 65.0, 66.0, 66.0, 66.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1511.3999999999999, "alertThreshold": 2.0}]} 09:47:37 INFO - Return code: 0 09:47:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:47:37 ERROR - # TBPL SUCCESS # 09:47:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:37 INFO - 2016-05-11 09:47:37.451 screenresolution[1678:10150] starting screenresolution argv=screenresolution get 09:47:37 INFO - 2016-05-11 09:47:37.454 screenresolution[1678:10150] Display 0: 1600x1200x32@60 09:47:37 INFO - 2016-05-11 09:47:37.460 screenresolution[1679:10153] starting screenresolution argv=screenresolution list 09:47:37 INFO - Available Modes on Display 0 09:47:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:37 INFO - Intel Iris: 09:47:37 INFO - Chipset Model: Intel Iris 09:47:37 INFO - Type: GPU 09:47:37 INFO - Bus: Built-In 09:47:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:37 INFO - Vendor: Intel (0x8086) 09:47:37 INFO - Device ID: 0x0a2e 09:47:37 INFO - Revision ID: 0x0009 09:47:37 INFO - Displays: 09:47:37 INFO - Display: 09:47:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:37 INFO - Display Serial Number: 1600x1200 60 09:47:37 INFO - Main Display: Yes 09:47:37 INFO - Mirror: Off 09:47:37 INFO - Online: Yes 09:47:37 INFO - Rotation: Supported 09:47:37 INFO - Return code: 0 09:47:37 INFO - Running post-action listener: _resource_record_post_action 09:47:37 INFO - ##### 09:47:37 INFO - ##### Finished run-tests step (success) 09:47:37 INFO - ##### 09:47:37 INFO - Running post-run listener: _resource_record_post_run 09:47:38 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37914112; Write bytes: 693021696; Read time: 395; Write time: 1839 09:47:38 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 388200960; Write bytes: 381920768; Read time: 28569; Write time: 656 09:47:38 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36091392; Write bytes: 295904768; Read time: 373; Write time: 1148 09:47:38 INFO - Running post-run listener: _upload_blobber_files 09:47:38 INFO - Blob upload gear active. 09:47:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:38 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 09:47:38 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'] 09:47:38 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 09:47:39 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:47:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:40 INFO - (blobuploader) - INFO - Done attempting. 09:47:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:47:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:47:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:41 INFO - (blobuploader) - INFO - Done attempting. 09:47:41 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:41 INFO - Return code: 0 09:47:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/34c807a4876ca3e9474536e748454cacd6025c9595cdeec2c0c85ae9260a4ef1cf30a8c0439e13fecbe81893422ee1308f82289e813c6a4adacf992a41792346", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b58876ca3932a95292da5f7b396953d2980375bc81b2221335f290e56201489dbc03a3cd7802867dc34483ee1d26ed7b29bcd50e4831ca6c095c55d193b87b7a"} 09:47:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:41 INFO - Contents: 09:47:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/34c807a4876ca3e9474536e748454cacd6025c9595cdeec2c0c85ae9260a4ef1cf30a8c0439e13fecbe81893422ee1308f82289e813c6a4adacf992a41792346", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b58876ca3932a95292da5f7b396953d2980375bc81b2221335f290e56201489dbc03a3cd7802867dc34483ee1d26ed7b29bcd50e4831ca6c095c55d193b87b7a"} 09:47:41 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:41 INFO - Copying logs to upload dir... 09:47:41 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.215303 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-11 09:47:41.308162) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 09:47:41.311487) ========= 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.FpyQHxTj68/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.eZE4rAUVD1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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/34c807a4876ca3e9474536e748454cacd6025c9595cdeec2c0c85ae9260a4ef1cf30a8c0439e13fecbe81893422ee1308f82289e813c6a4adacf992a41792346", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b58876ca3932a95292da5f7b396953d2980375bc81b2221335f290e56201489dbc03a3cd7802867dc34483ee1d26ed7b29bcd50e4831ca6c095c55d193b87b7a"} build_url:https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011104 build_url: 'https://queue.taskcluster.net/v1/task/Lz-dRho6RWu1R1wEBwnjLg/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/34c807a4876ca3e9474536e748454cacd6025c9595cdeec2c0c85ae9260a4ef1cf30a8c0439e13fecbe81893422ee1308f82289e813c6a4adacf992a41792346", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b58876ca3932a95292da5f7b396953d2980375bc81b2221335f290e56201489dbc03a3cd7802867dc34483ee1d26ed7b29bcd50e4831ca6c095c55d193b87b7a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 09:47:41.338882) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:47:41.339210) ========= 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.FpyQHxTj68/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.eZE4rAUVD1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.004948 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:47:41.389384) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 09:47:41.389892) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 09:47:41.390466) ========= ========= Total master_lag: 0.15 =========