builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-008 starttime: 1474507354.01 results: failure (2) buildid: 20160921153000 builduid: 38d70451cdc84c52b462ba29f3834586 revision: 94d6bc5adab3d63533f5c5c33df14c7d3f763859 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.009331) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.009849) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.010212) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.040547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.041007) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 18:22:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-09-21 18:22:34 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098530 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.156699) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.157058) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015819 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.216678) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.217052) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 94d6bc5adab3d63533f5c5c33df14c7d3f763859 --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 94d6bc5adab3d63533f5c5c33df14c7d3f763859 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 18:22:34,475 truncating revision to first 12 chars 2016-09-21 18:22:34,475 Setting DEBUG logging. 2016-09-21 18:22:34,475 attempt 1/10 2016-09-21 18:22:34,475 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/94d6bc5adab3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 18:22:34,790 unpacking tar archive at: fx-team-94d6bc5adab3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.675467 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.912102) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.912475) ========= script_repo_revision: 94d6bc5adab3d63533f5c5c33df14c7d3f763859 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.912901) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.913188) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 18:22:34.927705) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 34 secs) (at 2016-09-21 18:22:34.928125) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:22:35 INFO - MultiFileLogger online at 20160921 18:22:35 in /builds/slave/test 18:22:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:22:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:22:35 INFO - {'append_to_log': False, 18:22:35 INFO - 'base_work_dir': '/builds/slave/test', 18:22:35 INFO - 'blob_upload_branch': 'Fx-Team', 18:22:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:22:35 INFO - 'branch': 'Fx-Team', 18:22:35 INFO - 'buildbot_json_path': 'buildprops.json', 18:22:35 INFO - 'config_files': ('talos/linux_config.py',), 18:22:35 INFO - 'default_actions': ('clobber', 18:22:35 INFO - 'read-buildbot-config', 18:22:35 INFO - 'download-and-extract', 18:22:35 INFO - 'populate-webroot', 18:22:35 INFO - 'create-virtualenv', 18:22:35 INFO - 'install', 18:22:35 INFO - 'run-tests'), 18:22:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:22:35 INFO - 'download_minidump_stackwalk': True, 18:22:35 INFO - 'download_symbols': 'ondemand', 18:22:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:22:35 INFO - 'tooltool.py': '/tools/tooltool.py', 18:22:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:22:35 INFO - '/tools/misc-python/virtualenv.py')}, 18:22:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:22:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:22:35 INFO - 'installer_path': 'installer.exe', 18:22:35 INFO - 'log_level': 'info', 18:22:35 INFO - 'log_name': 'talos', 18:22:35 INFO - 'log_to_console': True, 18:22:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:22:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:22:35 INFO - 'opt_config_files': (), 18:22:35 INFO - 'pip_index': False, 18:22:35 INFO - 'sps_profile': False, 18:22:35 INFO - 'sps_profile_interval': 0, 18:22:35 INFO - 'suite': 'dromaeojs-e10s', 18:22:35 INFO - 'system_bits': '32', 18:22:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:22:35 INFO - 'title': 'talos-linux64-ix-008', 18:22:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:22:35 INFO - 'use_talos_json': True, 18:22:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:22:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:22:35 INFO - 'work_dir': 'build'} 18:22:35 INFO - [mozharness: 2016-09-22 01:22:35.274842Z] Running clobber step. 18:22:35 INFO - Running pre-action listener: _resource_record_pre_action 18:22:35 INFO - Running main action method: clobber 18:22:35 INFO - rmtree: /builds/slave/test/build 18:22:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:22:35 INFO - Running post-action listener: _resource_record_post_action 18:22:35 INFO - [mozharness: 2016-09-22 01:22:35.729135Z] Finished clobber step (success) 18:22:35 INFO - [mozharness: 2016-09-22 01:22:35.729238Z] Running read-buildbot-config step. 18:22:35 INFO - Running pre-action listener: _resource_record_pre_action 18:22:35 INFO - Running main action method: read_buildbot_config 18:22:35 INFO - Using buildbot properties: 18:22:35 INFO - { 18:22:35 INFO - "project": "", 18:22:35 INFO - "product": "firefox", 18:22:35 INFO - "script_repo_revision": "production", 18:22:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 18:22:35 INFO - "repository": "", 18:22:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 18:22:35 INFO - "buildid": "20160921153000", 18:22:35 INFO - "pgo_build": "True", 18:22:35 INFO - "basedir": "/builds/slave/test", 18:22:35 INFO - "buildnumber": 764, 18:22:35 INFO - "slavename": "talos-linux64-ix-008", 18:22:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:22:35 INFO - "platform": "ubuntu64_hw", 18:22:35 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 18:22:35 INFO - "branch": "fx-team", 18:22:35 INFO - "repo_path": "integration/fx-team", 18:22:35 INFO - "revision": "94d6bc5adab3d63533f5c5c33df14c7d3f763859", 18:22:35 INFO - "stage_platform": "linux64-pgo", 18:22:35 INFO - "builduid": "38d70451cdc84c52b462ba29f3834586", 18:22:35 INFO - "slavebuilddir": "test" 18:22:35 INFO - } 18:22:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 18:22:35 INFO - Running post-action listener: _resource_record_post_action 18:22:35 INFO - [mozharness: 2016-09-22 01:22:35.731717Z] Finished read-buildbot-config step (success) 18:22:35 INFO - [mozharness: 2016-09-22 01:22:35.731808Z] Running download-and-extract step. 18:22:35 INFO - Running pre-action listener: _resource_record_pre_action 18:22:35 INFO - Running main action method: download_and_extract 18:22:35 INFO - mkdir: /builds/slave/test/build/tests 18:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:35 INFO - trying https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:22:35 INFO - Downloading https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:22:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:22:36 INFO - Downloaded 1361 bytes. 18:22:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:22:36 INFO - Using the following test package requirements: 18:22:36 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:22:36 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:22:36 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:22:36 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:36 INFO - u'jsshell-linux-x86_64.zip'], 18:22:36 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:22:36 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:22:36 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:22:36 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:22:36 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:22:36 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:36 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:22:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:22:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:22:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:22:36 INFO - Fetch https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:22:36 INFO - Expected file size: 21941737 18:22:41 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:22:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:22:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:22:41 INFO - Fetch https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:22:41 INFO - Expected file size: 21941737 18:22:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 18:22:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:22:46 INFO - Fetch https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:22:47 INFO - Expected file size: 14414059 18:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:48 INFO - trying https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:22:48 INFO - Downloading https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:22:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:22:49 INFO - Downloaded 59537751 bytes. 18:22:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:22:49 INFO - mkdir: /builds/slave/test/properties 18:22:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:22:49 INFO - Writing to file /builds/slave/test/properties/build_url 18:22:49 INFO - Contents: 18:22:49 INFO - build_url:https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:22:50 INFO - Running post-action listener: _resource_record_post_action 18:22:50 INFO - Running post-action listener: set_extra_try_arguments 18:22:50 INFO - [mozharness: 2016-09-22 01:22:50.546187Z] Finished download-and-extract step (success) 18:22:50 INFO - [mozharness: 2016-09-22 01:22:50.546418Z] Running populate-webroot step. 18:22:50 INFO - Running pre-action listener: _resource_record_pre_action 18:22:50 INFO - Running main action method: populate_webroot 18:22:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:22:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:22:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:22:50 INFO - '--tppagecycles', 18:22:50 INFO - '7'], 18:22:50 INFO - 'tests': ['tsvgm']}}, 18:22:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:22:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:22:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:22:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:22:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:50 INFO - 'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:50 INFO - 'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['damp', 'tps']}, 18:22:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:50 INFO - 'tests': ['damp', 'tps']}, 18:22:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['dromaeo_dom']}, 18:22:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:22:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['basic_compositor_video']}, 18:22:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:22:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['a11yr', 18:22:50 INFO - 'ts_paint', 18:22:50 INFO - 'tpaint', 18:22:50 INFO - 'sessionrestore', 18:22:50 INFO - 'sessionrestore_no_auto_restore', 18:22:50 INFO - 'tabpaint']}, 18:22:50 INFO - 'other-e10s': {'tests': ['a11yr', 18:22:50 INFO - 'ts_paint', 18:22:50 INFO - 'tpaint', 18:22:50 INFO - 'sessionrestore', 18:22:50 INFO - 'sessionrestore_no_auto_restore', 18:22:50 INFO - 'tabpaint']}, 18:22:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:22:50 INFO - 'ts_paint', 18:22:50 INFO - 'tpaint', 18:22:50 INFO - 'sessionrestore', 18:22:50 INFO - 'sessionrestore_no_auto_restore', 18:22:50 INFO - 'tabpaint']}, 18:22:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:22:50 INFO - 'ts_paint', 18:22:50 INFO - 'tpaint', 18:22:50 INFO - 'sessionrestore', 18:22:50 INFO - 'sessionrestore_no_auto_restore', 18:22:50 INFO - 'tabpaint']}, 18:22:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['a11yr', 18:22:50 INFO - 'ts_paint', 18:22:50 INFO - 'tpaint', 18:22:50 INFO - 'sessionrestore', 18:22:50 INFO - 'sessionrestore_no_auto_restore', 18:22:50 INFO - 'tabpaint']}, 18:22:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['a11yr', 18:22:50 INFO - 'ts_paint', 18:22:50 INFO - 'tpaint', 18:22:50 INFO - 'sessionrestore', 18:22:50 INFO - 'sessionrestore_no_auto_restore', 18:22:50 INFO - 'tabpaint']}, 18:22:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['tsvgx', 18:22:50 INFO - 'tsvgr_opacity', 18:22:50 INFO - 'tart', 18:22:50 INFO - 'tscrollx', 18:22:50 INFO - 'cart']}, 18:22:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:22:50 INFO - 'tsvgr_opacity', 18:22:50 INFO - 'tart', 18:22:50 INFO - 'tscrollx', 18:22:50 INFO - 'cart']}, 18:22:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:50 INFO - 'talos_options': ['--disable-e10s'], 18:22:50 INFO - 'tests': ['tp5o']}, 18:22:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:50 INFO - 'tests': ['tp5o']}, 18:22:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:50 INFO - 'talos_options': ['--disable-e10s', 18:22:50 INFO - '--xperf_path', 18:22:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:50 INFO - 'tests': ['tp5n']}, 18:22:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:50 INFO - 'talos_options': ['--xperf_path', 18:22:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:50 INFO - 'tests': ['tp5n']}}, 18:22:50 INFO - 'talos.zip': {'path': '', 18:22:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:22:50 INFO - Running post-action listener: _resource_record_post_action 18:22:50 INFO - [mozharness: 2016-09-22 01:22:50.561833Z] Finished populate-webroot step (success) 18:22:50 INFO - [mozharness: 2016-09-22 01:22:50.561924Z] Running create-virtualenv step. 18:22:50 INFO - Running pre-action listener: _resource_record_pre_action 18:22:50 INFO - Running main action method: create_virtualenv 18:22:50 INFO - Creating virtualenv /builds/slave/test/build/venv 18:22: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 18:22:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:22:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:22:50 INFO - Using real prefix '/usr' 18:22:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:22:51 INFO - Installing distribute.............................................................................................................................................................................................done. 18:22:53 INFO - Installing pip.................done. 18:22:53 INFO - Return code: 0 18:22:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:53 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 0x7f09ff333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:22:53 INFO - Using env: {'DISPLAY': ':0', 18:22:53 INFO - 'HOME': '/home/cltbld', 18:22:53 INFO - 'LANG': 'en_US.UTF-8', 18:22:53 INFO - 'LANGUAGE': 'en_US:en', 18:22:53 INFO - 'LOGNAME': 'cltbld', 18:22:53 INFO - 'MAIL': '/var/mail/cltbld', 18:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:53 INFO - 'MOZ_NO_REMOTE': '1', 18:22:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:53 INFO - 'NO_EM_RESTART': '1', 18:22:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:53 INFO - 'PWD': '/builds/slave/test', 18:22:53 INFO - 'SHELL': '/bin/bash', 18:22:53 INFO - 'SHLVL': '1', 18:22:53 INFO - 'TERM': 'linux', 18:22:53 INFO - 'TMOUT': '86400', 18:22:53 INFO - 'USER': 'cltbld', 18:22:53 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:53 INFO - '_': '/tools/buildbot/bin/python'} 18:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:22:53 INFO - Cleaning up... 18:22:53 INFO - Return code: 0 18:22:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f09ff333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:22:53 INFO - Using env: {'DISPLAY': ':0', 18:22:53 INFO - 'HOME': '/home/cltbld', 18:22:53 INFO - 'LANG': 'en_US.UTF-8', 18:22:53 INFO - 'LANGUAGE': 'en_US:en', 18:22:53 INFO - 'LOGNAME': 'cltbld', 18:22:53 INFO - 'MAIL': '/var/mail/cltbld', 18:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:53 INFO - 'MOZ_NO_REMOTE': '1', 18:22:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:53 INFO - 'NO_EM_RESTART': '1', 18:22:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:53 INFO - 'PWD': '/builds/slave/test', 18:22:53 INFO - 'SHELL': '/bin/bash', 18:22:53 INFO - 'SHLVL': '1', 18:22:53 INFO - 'TERM': 'linux', 18:22:53 INFO - 'TMOUT': '86400', 18:22:53 INFO - 'USER': 'cltbld', 18:22:53 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:53 INFO - '_': '/tools/buildbot/bin/python'} 18:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:53 INFO - Downloading/unpacking psutil>=3.1.1 18:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:22:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:55 INFO - Installing collected packages: psutil 18:22:55 INFO - Running setup.py install for psutil 18:22:55 INFO - building 'psutil._psutil_linux' extension 18:22:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:22:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:22:55 INFO - building 'psutil._psutil_posix' extension 18:22:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:22:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:22:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:56 INFO - Successfully installed psutil 18:22:56 INFO - Cleaning up... 18:22:56 INFO - Return code: 0 18:22:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:22:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f09ff333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:22:56 INFO - Using env: {'DISPLAY': ':0', 18:22:56 INFO - 'HOME': '/home/cltbld', 18:22:56 INFO - 'LANG': 'en_US.UTF-8', 18:22:56 INFO - 'LANGUAGE': 'en_US:en', 18:22:56 INFO - 'LOGNAME': 'cltbld', 18:22:56 INFO - 'MAIL': '/var/mail/cltbld', 18:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:56 INFO - 'MOZ_NO_REMOTE': '1', 18:22:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:56 INFO - 'NO_EM_RESTART': '1', 18:22:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:56 INFO - 'PWD': '/builds/slave/test', 18:22:56 INFO - 'SHELL': '/bin/bash', 18:22:56 INFO - 'SHLVL': '1', 18:22:56 INFO - 'TERM': 'linux', 18:22:56 INFO - 'TMOUT': '86400', 18:22:56 INFO - 'USER': 'cltbld', 18:22:56 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:56 INFO - '_': '/tools/buildbot/bin/python'} 18:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:22:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:22:58 INFO - Installing collected packages: mozsystemmonitor 18:22:58 INFO - Running setup.py install for mozsystemmonitor 18:22:58 INFO - Successfully installed mozsystemmonitor 18:22:58 INFO - Cleaning up... 18:22:58 INFO - Return code: 0 18:22:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:58 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f09ff333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:58 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==2.5.1'] in /builds/slave/test/build 18:22:58 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==2.5.1 18:22:58 INFO - Using env: {'DISPLAY': ':0', 18:22:58 INFO - 'HOME': '/home/cltbld', 18:22:58 INFO - 'LANG': 'en_US.UTF-8', 18:22:58 INFO - 'LANGUAGE': 'en_US:en', 18:22:58 INFO - 'LOGNAME': 'cltbld', 18:22:58 INFO - 'MAIL': '/var/mail/cltbld', 18:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:58 INFO - 'MOZ_NO_REMOTE': '1', 18:22:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:58 INFO - 'NO_EM_RESTART': '1', 18:22:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:58 INFO - 'PWD': '/builds/slave/test', 18:22:58 INFO - 'SHELL': '/bin/bash', 18:22:58 INFO - 'SHLVL': '1', 18:22:58 INFO - 'TERM': 'linux', 18:22:58 INFO - 'TMOUT': '86400', 18:22:58 INFO - 'USER': 'cltbld', 18:22:58 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:58 INFO - '_': '/tools/buildbot/bin/python'} 18:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:58 INFO - Downloading/unpacking jsonschema==2.5.1 18:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:23:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:00 INFO - Downloading functools32-3.2.3-2.tar.gz 18:23:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:23:00 INFO - warning: no files found matching '*.txt' 18:23:00 INFO - no previously-included directories found matching 'build' 18:23:00 INFO - no previously-included directories found matching 'dist' 18:23:00 INFO - no previously-included directories found matching '.git*' 18:23:00 INFO - Installing collected packages: jsonschema, functools32 18:23:00 INFO - Running setup.py install for functools32 18:23:00 INFO - warning: no files found matching '*.txt' 18:23:00 INFO - no previously-included directories found matching 'build' 18:23:00 INFO - no previously-included directories found matching 'dist' 18:23:00 INFO - no previously-included directories found matching '.git*' 18:23:00 INFO - Successfully installed jsonschema functools32 18:23:00 INFO - Cleaning up... 18:23:01 INFO - Return code: 0 18:23:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:23:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:01 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f09ff333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:23:01 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:23:01 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 functools32==3.2.3-2 18:23:01 INFO - Using env: {'DISPLAY': ':0', 18:23:01 INFO - 'HOME': '/home/cltbld', 18:23:01 INFO - 'LANG': 'en_US.UTF-8', 18:23:01 INFO - 'LANGUAGE': 'en_US:en', 18:23:01 INFO - 'LOGNAME': 'cltbld', 18:23:01 INFO - 'MAIL': '/var/mail/cltbld', 18:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:01 INFO - 'MOZ_NO_REMOTE': '1', 18:23:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:01 INFO - 'NO_EM_RESTART': '1', 18:23:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:01 INFO - 'PWD': '/builds/slave/test', 18:23:01 INFO - 'SHELL': '/bin/bash', 18:23:01 INFO - 'SHLVL': '1', 18:23:01 INFO - 'TERM': 'linux', 18:23:01 INFO - 'TMOUT': '86400', 18:23:01 INFO - 'USER': 'cltbld', 18:23:01 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:01 INFO - '_': '/tools/buildbot/bin/python'} 18:23:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:23:01 INFO - Cleaning up... 18:23:01 INFO - Return code: 0 18:23:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:23:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:01 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 0x7f09ff333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:23:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:23:01 INFO - Using env: {'DISPLAY': ':0', 18:23:01 INFO - 'HOME': '/home/cltbld', 18:23:01 INFO - 'LANG': 'en_US.UTF-8', 18:23:01 INFO - 'LANGUAGE': 'en_US:en', 18:23:01 INFO - 'LOGNAME': 'cltbld', 18:23:01 INFO - 'MAIL': '/var/mail/cltbld', 18:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:01 INFO - 'MOZ_NO_REMOTE': '1', 18:23:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:01 INFO - 'NO_EM_RESTART': '1', 18:23:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:01 INFO - 'PWD': '/builds/slave/test', 18:23:01 INFO - 'SHELL': '/bin/bash', 18:23:01 INFO - 'SHLVL': '1', 18:23:01 INFO - 'TERM': 'linux', 18:23:01 INFO - 'TMOUT': '86400', 18:23:01 INFO - 'USER': 'cltbld', 18:23:01 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:01 INFO - '_': '/tools/buildbot/bin/python'} 18:23:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:01 INFO - Downloading/unpacking blobuploader==1.2.4 18:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:03 INFO - Downloading blobuploader-1.2.4.tar.gz 18:23:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:23:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:23:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:04 INFO - Downloading docopt-0.6.1.tar.gz 18:23:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:23:04 INFO - Installing collected packages: blobuploader, requests, docopt 18:23:04 INFO - Running setup.py install for blobuploader 18:23:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:23:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:23:04 INFO - Running setup.py install for requests 18:23:04 INFO - Running setup.py install for docopt 18:23:04 INFO - Successfully installed blobuploader requests docopt 18:23:04 INFO - Cleaning up... 18:23:04 INFO - Return code: 0 18:23:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:04 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 0x7f09ff333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:23:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:23:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:23:04 INFO - Using env: {'DISPLAY': ':0', 18:23:04 INFO - 'HOME': '/home/cltbld', 18:23:04 INFO - 'LANG': 'en_US.UTF-8', 18:23:04 INFO - 'LANGUAGE': 'en_US:en', 18:23:04 INFO - 'LOGNAME': 'cltbld', 18:23:04 INFO - 'MAIL': '/var/mail/cltbld', 18:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:04 INFO - 'MOZ_NO_REMOTE': '1', 18:23:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:04 INFO - 'NO_EM_RESTART': '1', 18:23:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:04 INFO - 'PWD': '/builds/slave/test', 18:23:04 INFO - 'SHELL': '/bin/bash', 18:23:04 INFO - 'SHLVL': '1', 18:23:04 INFO - 'TERM': 'linux', 18:23:04 INFO - 'TMOUT': '86400', 18:23:04 INFO - 'USER': 'cltbld', 18:23:04 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:04 INFO - '_': '/tools/buildbot/bin/python'} 18:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:04 INFO - Running setup.py (path:/tmp/pip-KNNP7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:04 INFO - Running setup.py (path:/tmp/pip-zmQM2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:05 INFO - Running setup.py (path:/tmp/pip-nX9git-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:05 INFO - Running setup.py (path:/tmp/pip-stTP8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:05 INFO - Running setup.py (path:/tmp/pip-0gd9TK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:05 INFO - Running setup.py (path:/tmp/pip-cxZve_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:05 INFO - Running setup.py (path:/tmp/pip-bKTXS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:05 INFO - Running setup.py (path:/tmp/pip-6Oml2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:05 INFO - Running setup.py (path:/tmp/pip-VAAvtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:05 INFO - Running setup.py (path:/tmp/pip-xv6O6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:05 INFO - Running setup.py (path:/tmp/pip-TYt0Hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:05 INFO - Running setup.py (path:/tmp/pip-Ngukmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:23:05 INFO - Running setup.py (path:/tmp/pip-WgalS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:23:05 INFO - Running setup.py (path:/tmp/pip-VRjOTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:05 INFO - Running setup.py (path:/tmp/pip-nKiU0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:23:05 INFO - Running setup.py (path:/tmp/pip-FVOInA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:23:06 INFO - Running setup.py (path:/tmp/pip-X7V9Ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:23:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:23:06 INFO - Running setup.py install for manifestparser 18:23:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:23:06 INFO - Running setup.py install for mozcrash 18:23:06 INFO - Running setup.py install for mozdebug 18:23:06 INFO - Running setup.py install for mozdevice 18:23:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:23:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:23:06 INFO - Running setup.py install for mozfile 18:23:06 INFO - Running setup.py install for mozhttpd 18:23:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:23:06 INFO - Running setup.py install for mozinfo 18:23:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:23:06 INFO - Running setup.py install for mozInstall 18:23:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:23:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:23:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:23:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:23:06 INFO - Running setup.py install for mozleak 18:23:07 INFO - Running setup.py install for mozlog 18:23:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:23:07 INFO - Running setup.py install for moznetwork 18:23:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:23:07 INFO - Running setup.py install for mozprocess 18:23:07 INFO - Running setup.py install for mozprofile 18:23:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:23:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:23:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:23:07 INFO - Running setup.py install for mozrunner 18:23:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:23:07 INFO - Running setup.py install for mozscreenshot 18:23:07 INFO - Running setup.py install for moztest 18:23:07 INFO - Running setup.py install for mozversion 18:23:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:23:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:23:07 INFO - Cleaning up... 18:23:07 INFO - Return code: 0 18:23:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:07 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 0x7f09ff333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:23:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:23:07 INFO - Using env: {'DISPLAY': ':0', 18:23:07 INFO - 'HOME': '/home/cltbld', 18:23:07 INFO - 'LANG': 'en_US.UTF-8', 18:23:07 INFO - 'LANGUAGE': 'en_US:en', 18:23:07 INFO - 'LOGNAME': 'cltbld', 18:23:07 INFO - 'MAIL': '/var/mail/cltbld', 18:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:07 INFO - 'MOZ_NO_REMOTE': '1', 18:23:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:07 INFO - 'NO_EM_RESTART': '1', 18:23:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:07 INFO - 'PWD': '/builds/slave/test', 18:23:07 INFO - 'SHELL': '/bin/bash', 18:23:07 INFO - 'SHLVL': '1', 18:23:07 INFO - 'TERM': 'linux', 18:23:07 INFO - 'TMOUT': '86400', 18:23:07 INFO - 'USER': 'cltbld', 18:23:07 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:07 INFO - '_': '/tools/buildbot/bin/python'} 18:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:08 INFO - Running setup.py (path:/tmp/pip-GjZIYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:08 INFO - Running setup.py (path:/tmp/pip-fTV6pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:08 INFO - Running setup.py (path:/tmp/pip-9HjQEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:08 INFO - Running setup.py (path:/tmp/pip-etfCrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:08 INFO - Running setup.py (path:/tmp/pip-iqKVP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:08 INFO - Running setup.py (path:/tmp/pip-F6QhMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:08 INFO - Running setup.py (path:/tmp/pip-oTvtki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:08 INFO - Running setup.py (path:/tmp/pip-qzSitW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:08 INFO - Running setup.py (path:/tmp/pip-sp6VoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:08 INFO - Running setup.py (path:/tmp/pip-CKSHY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:08 INFO - Running setup.py (path:/tmp/pip-lOLDkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:08 INFO - Running setup.py (path:/tmp/pip-AZtyod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:23:09 INFO - Running setup.py (path:/tmp/pip-UY77ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:23:09 INFO - Running setup.py (path:/tmp/pip-0SnDDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:09 INFO - Running setup.py (path:/tmp/pip-FlgMNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:23:09 INFO - Running setup.py (path:/tmp/pip-LmYXYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:23:09 INFO - Running setup.py (path:/tmp/pip-cmllF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:23:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:11 INFO - Downloading blessings-1.6.tar.gz 18:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:23:11 INFO - Installing collected packages: blessings 18:23:11 INFO - Running setup.py install for blessings 18:23:11 INFO - Successfully installed blessings 18:23:11 INFO - Cleaning up... 18:23:11 INFO - Return code: 0 18:23:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:23:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:11 INFO - Reading from file tmpfile_stdout 18:23:11 INFO - Current package versions: 18:23:11 INFO - argparse == 1.2.1 18:23:11 INFO - blessings == 1.6 18:23:11 INFO - blobuploader == 1.2.4 18:23:11 INFO - docopt == 0.6.1 18:23:11 INFO - functools32 == 3.2.3-2 18:23:11 INFO - jsonschema == 2.5.1 18:23:11 INFO - manifestparser == 1.1 18:23:11 INFO - mozInstall == 1.12 18:23:11 INFO - mozcrash == 0.17 18:23:11 INFO - mozdebug == 0.1 18:23:11 INFO - mozdevice == 0.48 18:23:11 INFO - mozfile == 1.2 18:23:11 INFO - mozhttpd == 0.7 18:23:11 INFO - mozinfo == 0.9 18:23:11 INFO - mozleak == 0.1 18:23:11 INFO - mozlog == 3.4 18:23:11 INFO - moznetwork == 0.27 18:23:11 INFO - mozprocess == 0.23 18:23:11 INFO - mozprofile == 0.28 18:23:11 INFO - mozrunner == 6.12 18:23:11 INFO - mozscreenshot == 0.1 18:23:11 INFO - mozsystemmonitor == 0.3 18:23:11 INFO - moztest == 0.7 18:23:11 INFO - mozversion == 1.4 18:23:11 INFO - psutil == 3.1.1 18:23:11 INFO - requests == 1.2.3 18:23:11 INFO - wsgiref == 0.1.2 18:23:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:11 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 0x7f09ff333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:23:11 INFO - Using env: {'DISPLAY': ':0', 18:23:11 INFO - 'HOME': '/home/cltbld', 18:23:11 INFO - 'LANG': 'en_US.UTF-8', 18:23:11 INFO - 'LANGUAGE': 'en_US:en', 18:23:11 INFO - 'LOGNAME': 'cltbld', 18:23:11 INFO - 'MAIL': '/var/mail/cltbld', 18:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:11 INFO - 'MOZ_NO_REMOTE': '1', 18:23:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:11 INFO - 'NO_EM_RESTART': '1', 18:23:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:11 INFO - 'PWD': '/builds/slave/test', 18:23:11 INFO - 'SHELL': '/bin/bash', 18:23:11 INFO - 'SHLVL': '1', 18:23:11 INFO - 'TERM': 'linux', 18:23:11 INFO - 'TMOUT': '86400', 18:23:11 INFO - 'USER': 'cltbld', 18:23:11 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:11 INFO - '_': '/tools/buildbot/bin/python'} 18:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:23:11 INFO - Cleaning up... 18:23:11 INFO - Return code: 0 18:23:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:11 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 0x7f09ff333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:23:11 INFO - Using env: {'DISPLAY': ':0', 18:23:11 INFO - 'HOME': '/home/cltbld', 18:23:11 INFO - 'LANG': 'en_US.UTF-8', 18:23:11 INFO - 'LANGUAGE': 'en_US:en', 18:23:11 INFO - 'LOGNAME': 'cltbld', 18:23:11 INFO - 'MAIL': '/var/mail/cltbld', 18:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:11 INFO - 'MOZ_NO_REMOTE': '1', 18:23:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:11 INFO - 'NO_EM_RESTART': '1', 18:23:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:11 INFO - 'PWD': '/builds/slave/test', 18:23:11 INFO - 'SHELL': '/bin/bash', 18:23:11 INFO - 'SHLVL': '1', 18:23:11 INFO - 'TERM': 'linux', 18:23:11 INFO - 'TMOUT': '86400', 18:23:11 INFO - 'USER': 'cltbld', 18:23:11 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:11 INFO - '_': '/tools/buildbot/bin/python'} 18:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:23:12 INFO - Cleaning up... 18:23:12 INFO - Return code: 0 18:23:12 INFO - Running post-action listener: _resource_record_post_action 18:23:12 INFO - Running post-action listener: _start_resource_monitoring 18:23:12 INFO - Starting resource monitoring. 18:23:12 INFO - [mozharness: 2016-09-22 01:23:12.224905Z] Finished create-virtualenv step (success) 18:23:12 INFO - [mozharness: 2016-09-22 01:23:12.225433Z] Running install step. 18:23:12 INFO - Running pre-action listener: _resource_record_pre_action 18:23:12 INFO - Running main action method: install 18:23:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:12 INFO - Reading from file tmpfile_stdout 18:23:12 INFO - Detecting whether we're running mozinstall >=1.0... 18:23:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:23:12 INFO - Reading from file tmpfile_stdout 18:23:12 INFO - Output received: 18:23:12 INFO - Usage: mozinstall [options] installer 18:23:12 INFO - Options: 18:23:12 INFO - -h, --help show this help message and exit 18:23:12 INFO - -d DEST, --destination=DEST 18:23:12 INFO - Directory to install application into. [default: 18:23:12 INFO - "/builds/slave/test"] 18:23:12 INFO - --app=APP Application being installed. [default: firefox] 18:23:12 INFO - mkdir: /builds/slave/test/build/application 18:23:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:23:29 INFO - Reading from file tmpfile_stdout 18:23:29 INFO - Output received: 18:23:29 INFO - /builds/slave/test/build/application/firefox/firefox 18:23:29 INFO - Running post-action listener: _resource_record_post_action 18:23:29 INFO - [mozharness: 2016-09-22 01:23:29.454183Z] Finished install step (success) 18:23:29 INFO - [mozharness: 2016-09-22 01:23:29.454430Z] Running run-tests step. 18:23:29 INFO - Running pre-action listener: _resource_record_pre_action 18:23:29 INFO - Running main action method: run_tests 18:23:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:23:29 INFO - Python 2.7.3 18:23:29 INFO - Return code: 0 18:23:29 INFO - grabbing minidump binary from tooltool 18:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:29 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/linux64/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 0x2329790>, '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 0x232a690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x232ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:23:29 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:23:29 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/linux64/releng.manifest -o -c /builds/tooltool_cache 18:23:29 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:23:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:23:29 INFO - Return code: 0 18:23:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:23:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:23:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:23:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:23:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:23:29 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:23:29 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:23:29 INFO - Using env: {'DISPLAY': ':0', 18:23:29 INFO - 'HOME': '/home/cltbld', 18:23:29 INFO - 'LANG': 'en_US.UTF-8', 18:23:29 INFO - 'LANGUAGE': 'en_US:en', 18:23:29 INFO - 'LOGNAME': 'cltbld', 18:23:29 INFO - 'MAIL': '/var/mail/cltbld', 18:23:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:23:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:29 INFO - 'MOZ_NO_REMOTE': '1', 18:23:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:23:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:29 INFO - 'NO_EM_RESTART': '1', 18:23:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:29 INFO - 'PWD': '/builds/slave/test', 18:23:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:23:29 INFO - 'SHELL': '/bin/bash', 18:23:29 INFO - 'SHLVL': '1', 18:23:29 INFO - 'TERM': 'linux', 18:23:29 INFO - 'TMOUT': '86400', 18:23:29 INFO - 'USER': 'cltbld', 18:23:29 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448', 18:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:29 INFO - '_': '/tools/buildbot/bin/python'} 18:23:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:23:29 INFO - mozversion application_buildid: 20160921153000 18:23:29 INFO - mozversion application_changeset: 94d6bc5adab3d63533f5c5c33df14c7d3f763859 18:23:29 INFO - mozversion application_display_name: Nightly 18:23:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:23:29 INFO - mozversion application_name: Firefox 18:23:29 INFO - mozversion application_remotingname: firefox 18:23:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:23:29 INFO - mozversion application_vendor: Mozilla 18:23:29 INFO - mozversion application_version: 52.0a1 18:23:29 INFO - mozversion platform_buildid: 20160921153000 18:23:29 INFO - mozversion platform_changeset: 94d6bc5adab3d63533f5c5c33df14c7d3f763859 18:23:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:23:29 INFO - mozversion platform_version: 52.0a1 18:23:29 INFO - using testdate: 1474507409 18:23:29 INFO - actual date: 1474507409 18:23:29 INFO - starting webserver on 'localhost:41569' 18:23:29 INFO - SUITE-START | Running 2 tests 18:23:29 INFO - TEST-START | dromaeo_css 18:23:29 INFO - operating with platform_type : linux_ 18:23:29 INFO - Initialising browser for dromaeo_css test... 18:23:29 INFO - TEST-INFO | started process 25724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZqJLf6/profile http://localhost:41569/getInfo.html) 18:23:34 INFO - PROCESS | 25724 | __metrics Screen width/height:1600/1200 18:23:34 INFO - PROCESS | 25724 | colorDepth:24 18:23:34 INFO - PROCESS | 25724 | Browser inner width/height: 1024/697 18:23:34 INFO - PROCESS | 25724 | __metrics 18:23:34 ERROR - PROCESS | 25724 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 18:23:35 INFO - TEST-INFO | 25724: exit 0 18:23:35 INFO - Browser initialized. 18:23:35 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:35 INFO - TEST-INFO | started process 25846 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZqJLf6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:47 INFO - PROCESS | 25846 | RSS: Main: 185729024 18:23:47 INFO - PROCESS | 25846 | 18:24:37 INFO - PROCESS | 25846 | 1474507477559 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:26:47 INFO - PROCESS | 25846 | Cycle 1(1): loaded http://localhost:41569/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41569/tests/dromaeo/cssquery-ext.html) 18:26:48 INFO - PROCESS | 25846 | RSS: Main: 200278016 18:26:48 INFO - PROCESS | 25846 | 18:29:50 INFO - PROCESS | 25846 | Cycle 1(1): loaded http://localhost:41569/tests/dromaeo/cssquery-ext.html (next: http://localhost:41569/tests/dromaeo/cssquery-jquery.html) 18:29:50 INFO - PROCESS | 25846 | RSS: Main: 196771840 18:29:50 INFO - PROCESS | 25846 | 18:32:52 INFO - PROCESS | 25846 | Cycle 1(1): loaded http://localhost:41569/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41569/tests/dromaeo/cssquery-mootools.html) 18:32:53 INFO - PROCESS | 25846 | RSS: Main: 199086080 18:32:53 INFO - PROCESS | 25846 | 18:35:55 INFO - PROCESS | 25846 | Cycle 1(1): loaded http://localhost:41569/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41569/tests/dromaeo/cssquery-prototype.html) 18:35:55 INFO - PROCESS | 25846 | RSS: Main: 195362816 18:35:55 INFO - PROCESS | 25846 | 18:38:58 INFO - PROCESS | 25846 | Cycle 1(1): loaded http://localhost:41569/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41569/tests/dromaeo/cssquery-yui.html) 18:38:58 INFO - PROCESS | 25846 | RSS: Main: 199143424 18:38:58 INFO - PROCESS | 25846 | 18:42:01 INFO - PROCESS | 25846 | Cycle 1(1): loaded http://localhost:41569/tests/dromaeo/cssquery-yui.html (next: http://localhost:41569/tests/dromaeo/cssquery-dojo.html) 18:42:01 INFO - PROCESS | 25846 | RSS: Main: 199311360 18:42:01 INFO - PROCESS | 25846 | 18:42:01 INFO - PROCESS | 25846 | __start_tp_report 18:42:01 INFO - PROCESS | 25846 | _x_x_mozilla_page_load 18:42:01 INFO - PROCESS | 25846 | _x_x_mozilla_page_load_details 18:42:01 INFO - PROCESS | 25846 | |i|pagename|runs| 18:42:01 INFO - PROCESS | 25846 | |0;dojo.html;3064.509678451448;3068.7391571716403;3069.4628440023016;3101.937961240775;3107.9999999999986;5254.553362964143;5325.174597937321;5376.032314183451;5416.729163541822;5484.040292948728;5323.520883120518;5326.946730532693;5395.514403703666;5414.052540805363;5497.945020549793;4795.448523419807;4856.05306965142;4868.9269660955115;4928;4951.975240123795;4941.407031156266;5001.899962000758;5007.824726134586;5012.323336349583;5057.620678449112;4730.739809310487;4768.523147685239;4915.557599816034;4924.556789888928;4981.5516603505675;4758.666893317469;4780.330753694486;4826.517348265181;4829.855104346875;4873.34209881665;4753.429588449409;4781.8087276508895;4783.210770222909;4794.184988551954;4843.539863712942;5153;5233.241180028913;5272.762725677354;5292.179712144623;5304.17785243284;5568.109102543614;5645.661260324394;5694.5159661428825;5705.229793977802;5775.711214439303;5703.201551782754;5723.255976723;5743.10981797822;5746.913796293059;5751.539876809887;5350.464953504657;5357.383900851413;5361.678299302054;5362.731863406814;5417.51242388187;5768.7692492300675;5831.183634291203;5842.152887831241;5868.647881127061;5894.38108998549;5651.406602306781;5662.631928924607;5665.065264231438;5665.886682266331;5668.971655141698;5567.220589117527;5757.481826635623;5763.971180144156;5811.041178205549;5818.185454036438;5584.301962254718;5676.318841739017;5727.627704199213;5741.110127930177;5743.822516384132;5731;5747.48272655463;5750.741216645261;5767.740451679684;5778;4308.698391112592;4437.400950871655;4449.844255451044;4458.284054673106;4512.368268442421;3878;3930.9213815723524;3963.128111815454;3979.2041591681777;4015.819288132041;5474.562035037187;5518.255035570227;5531.668099914018;5536.972315138479;5538.556915446835;5809.7095145242565;5828.067509198508;5830.3586605473365;5832.795852145155;5894.498967587803;5334.146536554133;5390.056740070472;5418.701971391532;5437.918431223456;5467.535259502987;4980.526849949249;4982.128127577659;4983.02830947969;4983.875403114878;5031.169856973558;5842.211301474246;5842.912356314659;5883.529317654577;5907.556933230111;5943.821685349447;5519.972400137974;5636.915446268391;5703.315602127771;5727.513161381329;5742.28221472316;5937.376575459687;5940.673262970578;5949.345571986992;5984.102384642339;6003.309619393825;5653.265075540154;5653.773849045992;5662.631928924607;5669.149627555735;5670.149477578231;5602.579806514446;5656.43435656431;5664.773409063591;5728.484436400744;5781.277340332458;5519.061759500814;5569.164625306236;5579.46995035471;5648.378678345297;5657.60396772222;5594.776208951759;5599.832005039855;5600.243967064394;5604.495595396434;5613.74738136793;5360.731963401892;5460.726963651881;5477.096279113902;5556.638818476786;5578.191162281528;5686.374498805046;5695;5696.686682232351;5702.372738998624;5766.048601980626;5523.061079616395;5550.38945715979;5551.084071128219;5552.861178470571;5566 18:42:01 INFO - PROCESS | 25846 | |1;ext.html;555.3094921728031;577.6967092276554;597.9923828349234;605.424846395924;629.7386584567405;36433.635663643356;37936.43095353572;37954.43068353974;39255;39265;43871;44211.77894110533;44317.77841110798;44356.112877742424;44463;8967.461952282867;9136.040715724854;9142.908570914273;9146.817063658722;9149.542522873862;8771;8922.73231803047;8923.241524470395;8972.820543589125;9022.729318120468;9020.188183063523;9039.502827344526;9054.728358149267;9075.500847453388;9078.682246121387;8868.467891926473;8981.19169274762;9017.790641292893;9028.683996060106;9052.502112383849;8843.071477494836;8886.377953543255;8899.73300800977;8915.063918288552;8915.866262006075;42233;42933.785331073144;43000.1399972002;43203.56796432058;43220.56779432197;3375.8818441354433;3624.6012938576737;3636.218213084173;3802.9429558556644;3820.1977584707247;31821.681783182335;31870.681293187004;32067.198320041953;32135.357292854245;32486.18784530382;4107.856225032109;4222.915541689149;4243.766592837393;4254.553271906467;4416.447944006999;364023;366457;370209;371038;371237.9999999973;59377;59997;60096.699516502136;60160.39839601635;60218.39781602215;36596.451053234225;37631.81184094117;37751.244975100344;39257.41113883294;39357.40963885544;16098.919505402398;16115.919420402823;16186.190690465664;16276;16343.26455309514;12238.632841014783;12359.62921112368;12364.505419783109;12395.380230988414;12438.129330946747;18916.337928172725;19149;19179.424617261506;19187.424377268704;19239;7321.560706357635;7323.487355885143;7333.1566869809585;7471.775846724607;7472.3957032225835;930.6835675870236;1002.7192386131894;1021.3208216536069;1050.165118730611;1121.3216004317433;30388;30559.2360190997;30860;31312.21719456986;31330.68669313278;1111.244353839381;1114.576460944852;1117.5641499815079;1130.5477808876515;1140.2189500192394;16195.838041619669;16322.428714994918;16414.917925410537;16436.260368283456;16870.325186992384;540297;544037;547904;548542;549544;56751.71624141935;56853.71573142108;57034.7148264256;57070.71464642733;57250.713746431;1203.6870413692502;1249;1251.2742609286593;1251.4994002399112;1320.2408615046195;28742;29314;29978.25054373658;30329;30833.229169270948;50081;50482.74758626183;50532.747336263084;50546.2418063722;50654.2401863965;458667;461456;463930;464162;464207;27775.44449111047;28519.429611407242;28521.857390712914;28642;28679.569806452502;43634.56365436305;43875.34186987262;43935.56064439443;46155.76922115368;46629;35106.29787404289;35290.11774705653;35496;35576.110597235274;36201;40173.397399038455;40291.19417611573;40626.59373406346;41906.58093419027;42078.15843683048;38711.806440967615;39257.80371098241;39435.60564394319;39565.40651890282;40641.796791015855;35482.822585886905;36851;36982;37437.81281093686;37883;27123;27255.182344529694;27599.44801104007;27672.446551069268;27825.721742783124 18:42:01 INFO - PROCESS | 25846 | |2;jquery.html;390.64451349272184;396.6331143692084;398.0983073338887;398.9840406383745;416.4398883501689;17956.281748730053;19101.14044867981;19147.712784308213;19443.222271109156;19558.022098895053;18407.815921840778;19008.76443031202;19201.039948002614;19419.90290048548;19753.50616234591;5424.918626220609;5443.428440013801;5598.608097433182;5621;5635.042042852725;5557.777688892439;5580.888382232353;5602.831915042535;5616.494515493605;5619.269494965649;5374.193870919355;5493.368262649787;5498.780048798043;5520.365158006841;5532.585056120787;5452.073147564904;5483.972580137113;5487.368952570466;5494.011078005958;5513.283273174502;4824;4860.6147248034495;4998.975005124988;5005.9499405005845;5010.649254552182;21093;21304.5739085219;21320;21400.250991215387;21415;2321.3848330192345;2327.464683122891;2329.743728189898;2369.5260947810334;3095.2571382868173;13992.580222593338;14552.098393773153;14825.258737063212;14830.406783728638;14972.850271497255;1932.8936908470027;1949.2982526290525;1962.0680176915994;1998.5003749062735;2020.7676117246558;80025.79961300643;80366.59816700938;80974;81412;82750.17249827545;9886;9891.950540247253;10122.240831937486;10534.841977370346;10674.306170098918;14173.078749881224;14197.432102715775;14209.36057877398;14230.28848557568;14235.074720143157;7810.648520816577;8232.34141268697;8236.876446853183;8401.243888050105;8474.872876906851;7934.960325198337;7960.4825686330205;8035.83928321442;8247.505149691038;8339.583020848933;6204.565680402418;6286.779962701375;6393.616383016941;6432.485401167895;6609.405153536109;1924.5840815071379;1931.9613607727767;1952;1957.2171131547211;1985.334912804198;884.186548375496;998.4558415663481;1002.5087707023652;1017.4861694844057;1048.2714513413105;19157.80842191588;19315.710264346046;19561.510962225773;19563.706544401844;19761.209551618063;956.8421210500189;988.4415305352596;999.8900120986685;1017.5217647705566;1048.3971716263027;10726.839097413545;11087.22389432748;11164;11258.774824503464;11485.138614603939;480154;482923.12230338063;484145;490426;496720;76985;77204.54079668892;80564.59717701492;80742;81697;984.566790612114;1016.9694909152589;1055.2560444886337;1057.3867157048894;1059.2003037706481;15538.844611553739;15743.763843542585;15975.760363594787;16139.273732681822;16341.019538827708;39274;39803.40294895521;39867;40558.79720601496;40805.79597101996;49920.750396247786;52860;53090;53231.73384133055;53936.460635393145;8092.797680058045;8110.472819266729;8215.301699355621;8322.833543329221;8334.333253339839;11729.882701173221;12124.333161676192;12324.322162280801;12357;12658.493660253855;10116.797664046531;10387.324823886423;10496.265261431627;10709.678709638723;10755.085817705583;5427.48438898304;8466.830663386821;10249.641262555775;10579.624648795607;10702.678919632424;10815.837762433726;10916.399598022184;10959.780804384027;10992.395418251754;11085.833712494481;7947.5231486111015;8063.959680201561;8116.228958248957;8147.348212142896;8421.789455263668;10581.4709264538;10686.198535110054;10709.785804284027;10738.946305268424;11019.614313498989 18:42:01 INFO - PROCESS | 25846 | |3;mootools.html;1326.5821266301114;1393.0944885824217;1423.8789702875256;1508.9622759431006;1516.0979217365664;7267.672954717037;7538.773836784905;8378;9047.592858321375;9057.547122643875;8223.671053157881;8517.27603153731;8537.701180458685;8736.21374076333;8761.693340733076;2315.6410756332752;2330.8951097200584;2335.8131349492;2342.847714898535;2346.061575369857;2257.8758168300737;2294.506681063571;2296.919607813727;2301.3200363734486;2304.7810458006547;2328.7205535335784;2337.684412604294;2339.5671800716837;2348.8708121053337;2358.162852187466;2250.0437314141504;2254.0532976149975;2255.3684968208927;2257.480779420734;2259.028617694391;2227.042371780134;2229.687843701883;2235.184157782414;2239.261043855524;2243.091547923094;8661.263792577638;8756.474611523328;8772.298216142693;8802.07578204292;8830.161134692195;5073.036123136592;5144.742762861842;5177.042247184246;5183.170692689152;5185.274061631375;3260.5272235525954;3325.218573635194;3448.793072415638;3452.482127680843;3472.5485686860798;1103.707517507861;1109.2124591540016;1126.5831642292321;1136.136536232461;1139.5726602524053;12117.454714537867;12131.09016823733;12169.14815962883;12170.513179472811;12204.206726562745;4415.933760993524;4474.261746811805;4621.69958952667;4633.397658304399;4730.219513780187;10220.540075696612;10318.277720559488;10331.225158113171;10363.430011349448;10453.895461045444;5222.634415590872;5240.737963101906;5245.554127899095;5261.026710058575;5262.7631756571045;4746.928796068062;4859.1496488114435;4888.706677599355;4939.407271127488;4992.101421744121;3797.3924172132324;3891.0855948851995;3902.375619900802;3954.1696243789124;3977.065389633434;1200.5557943560939;1203.5366383942246;1205.9035277177804;1214.2228973456995;1215.1372525506813;754.6981207516927;849.7026040885764;876.640577363278;915.4461550761827;954.7088138117801;5389.353277606712;5445.292112025411;5639.8590035249445;5640.943590564124;5832.75438956005;901.9052999434965;920.4260714178696;920.8848893888264;955.3790036476207;992.8858181309242;3587.551556055493;3711.5917249102577;3897.415387691869;3904.375299951994;3925.784081875467;10740.409277489813;10864.565417383215;10946.890531094747;10956.397398143019;10980.341179529254;10883.945580272048;10894.564217431214;10899;10947.945260273647;10951.397673128055;7978.401619878532;8236.258736713724;8263.297619702393;8414.747557573282;8450.61972211252;5691.060974939089;5710.172025056427;5724.513416359656;5864.9413505864395;5889.94110058894;10174.8473772892;10238.33450825694;10304.948475257575;10348.172146228435;10469.790604188025;9557.522123893916;9692.17616502577;9844.704658860246;9978.052085051908;10458.738531536772;7123.643817809192;7325.816854578678;7905.802354941173;7978.720744773905;8237.670493180445;8327.125651806473;8393.83212335762;8560.614772335135;8586.914130858611;8601.39790214679;7485.66314515859;7504.812379690551;7532.43506736986;7553.169151393452;7596.088469383489;7383.889241661487;7452.403807695479;7480.177180510248;7649.464537482323;7696.769096927102;6986.825329366807;7101.680424381018;7123.216446191019;7170.749023784142;7182.60495672743;4815.6147508199965;4864.610831133572;4932.161532539549;4984.22744474607;5037.974810125926;4976.925346119739;5013.39839219296;5016.1221786188;5070.467600901852;5110.795568177231 18:42:01 INFO - PROCESS | 25846 | |4;prototype.html;272.98362098274094;291.30669007761526;296.22389339929384;297.974672152867;307.3192877775727;5386.1920711893235;5524.320704458334;5530.308711411074;5588.552915766739;5600.691961942097;5334.51989320961;5458.317710286215;5487.957288115255;5554.694491802959;5580.330360356753;4238.533761286264;4282.892927676818;4286.585426809161;4288.163808057415;4290.742555446684;4085.1829634073306;4198.454200953872;4222.071144348254;4284.485861696585;4288.592583704559;4184.769837658928;4187.413762073312;4194.622483976442;4215.3044747616605;4216.409702641633;4216.7259128156575;4222.746635201897;4226.098255784909;4236.894077648052;4244.851430199959;3670.014715613369;3701.777893326409;3724.5902950675404;3730.570984336809;3734.533183352081;5335.599830012765;5353.518183363516;5398.649087809319;5417.51242388183;5450.754716037788;4813.232391374973;4835.419749630067;4842.975785121052;4847.103285892122;4848.7090774553635;5630.324361076656;5640.72766280787;5768.971155144198;5774.2782152230975;5789.942100579025;3981.960180398217;3995.98002009991;3997.4403583498333;4006.659433948118;4040.2929487339893;5983.940160598382;6026.246719160105;6040.96979515104;6091.969540152315;6092.482139018189;3798.810059497014;4110.547839737626;4141.834326626901;4155.459790227251;4160.854370097032;4063.39049142631;4063.79681015948;4064.532578753481;4069.959300407017;4072.8392408164123;4795.928061079087;4815.590674792682;4819.6867203631655;4820.156472617278;4830.29960655703;5702.600817942787;5892.882142357214;5924.407559244041;5925.9407405925385;5940.732667029907;1400.838903526087;1421.2467392282108;1422.2533170085787;1425.5081996711117;1430.2848575712144;864.9922839891582;888.5646033443566;891.7280229530055;905.1762895764822;907.7049958763429;4849.587785038311;4974.37820272466;4995.227648654959;4995.425526064549;5038.319826823332;5216.686998780086;5239.764210610531;5289.3917199521775;5294.7352632369;5311.628186027018;4854;4998.325226094504;4999.3757023348735;5003.824866129668;5012.924806127911;2797.2447439191305;2809;2845.8719357628956;2847.743703066734;2857.199984004482;5440.91838622421;5483.479069488392;5502.669839809624;5512.779488820483;5525.176691691732;5182.637215394886;5334.3196892978585;5350.705711185922;5361.973190134025;5457.918131228034;5333.813316533902;5350;5365.651232669942;5384.326959130109;5390;5200.4279529251;5217.860677122361;5220.738963051756;5241.737913104405;5313.760880760297;4935.383077115361;5217.478252174752;5241.318628578413;5244.112119637004;5258.8948221036135;5380.865478362915;5418.729063546885;5419.349678038503;5429.212764149255;5432.619716619799;3718.2524213620636;3860.575336712903;4087.5503694592976;4089.1617218469546;4096.180763847302;5960.314563825215;5969.104634304889;5986.760529578943;6040.607360521552;6068.878622427438;5615;5877.4122587742595;5899.823005309848;5904.9704751475965;5909.5133871016305;5797.550612346913;5797.666536696499;5802.274715660543;5806.274215723035;5847.210626565359;5752.942470575408;5796.82609521715;5832.708364581838;5833.79581714638;5839.824805255849;5612.438756124355;5871.853203669943;5877.882442351214;5903.645781253139;5906.557008224315;5741.067368852183;5746.569007324384;5760.481556659921;5777.480026797609;5794.884102318014 18:42:01 INFO - PROCESS | 25846 | |5;yui.html;762.7444805989993;764.3808515102767;782.1240210963722;794.4002278279897;804.7545498622919;15462.768058479118;16008.759868601981;16065.759013614805;16250.756238656415;16334.18329083547;15607;15691.136987465685;15792.763108553352;15799.684006319867;15843;657.0538424668486;673.0879658063327;777.8716511775579;791.2483141016029;794.0749027383092;812.1959260332258;817.8773184022415;821.8068753842872;822.40786633624;823.9505629662209;861.8965724113084;863.5336918064238;864.0452300208274;864.3430992445755;864.4856310495245;848.2662496940164;850.0691742541907;851.638053827124;853.9701110461166;857.2242120880613;623.3766233766233;628.9810506978678;629.625372903122;634.6255709131589;634.6572850660638;15318.693626127526;15340.23298835065;15398.307076181598;15407.614809629737;15489.767653485207;14755.11469311834;14795.40818367264;14813;14852.851471485255;15071.924640376728;11275.436228188557;11596.246243994114;11794.410279486077;11918.523259069627;11979.10156738248;4426.756528390937;4450.376947227391;4459.10817836434;4472.329150627399;4558.908821823578;77256.61371693105;77692;78099;81396;83045;10481.37111773296;10704.892951070546;10970.561177552969;11211.103111751041;11216.158788090763;18559.164837582342;18665.253389864254;18678.719819202724;18697;19033.238670453025;8442.240198382175;8588.22705956467;8607.18231767981;8622.353323500762;8719.128087191357;4526.95473045272;4570.154521413549;4571.771411429415;4628.490866004737;4642.141203877293;3150.1652062203534;3253.6583658715967;3262.2822978944596;3264.053424506914;3266.758631719979;528.0811388184608;535.2105644174811;535.7428434351456;536.3804805449712;546.0144439287045;618.3816183816184;629.719774700254;648.1347401219425;654.5483616304734;660.425429876011;8716;9145.222656074307;9167.404118732262;9176.44941303524;9470.857937130948;628.5348841856993;636.1189752193153;637.8150336402484;653.7907869481816;713.4043074033136;4135;4431.62331201845;4443.911121777611;4474.105178964221;4517.5482451755215;68964;69365.30634693589;117721;120397;123166;59494;81188;102696;103090.48454757828;103474.48262758789;86039;89999;92376.53811730898;92750.53624731833;92788.53605732063;8655.826883462421;8673.653053878026;8694.782630434036;8843.380963332505;8923.107689231025;51607.225891610906;52241.21638175506;53563.73218133884;53754.462455374945;54481.72759136179;79025;79817;80771.59614202127;82990;83414;10580.15358771312;10653.786924261627;11145.777084458103;11392.487338069972;11741;12551.435185416862;12931.224126552437;14500.637484062983;14591.343389547708;14810.407583696531;13245.933770331087;14012.719745605234;14298.142111473346;14695.191764453059;14856.702865942836;13510;14053.227072510701;14164.291785410895;14220;14235.572932812032;13811.17132972025;13937;14049.157050576998;14082.507112251022;14941.701165976838;10559;10614.257002009785;11608.593699220486;12247;12397.75204495887;11125.641383991635;12344.259344439362;12622.368881556069;12650.114491985842;12685.682857928256 18:42:01 INFO - PROCESS | 25846 | __end_tp_report 18:42:01 INFO - PROCESS | 25846 | __start_cc_report 18:42:01 INFO - PROCESS | 25846 | _x_x_mozilla_cycle_collect,344 18:42:01 INFO - PROCESS | 25846 | __end_cc_report 18:42:01 INFO - PROCESS | 25846 | __startTimestamp1474508521605__endTimestamp 18:42:01 INFO - PROCESS | 25846 | 18:42:01 INFO - PROCESS | 25846 | ------- Summary: start ------- 18:42:01 INFO - PROCESS | 25846 | Number of tests: 6 18:42:01 INFO - PROCESS | 25846 | 18:42:01 INFO - PROCESS | 25846 | [#0] dojo.html Cycles:165 Average:5329.68 Median:5551.08 stddev:602.41 (10.9%) stddev-sans-first:577.45 18:42:01 INFO - PROCESS | 25846 | Values: 3064.5 3068.7 3069.5 3101.9 3108.0 5254.6 5325.2 5376.0 5416.7 5484.0 5323.5 5326.9 5395.5 5414.1 5497.9 4795.4 4856.1 4868.9 4928.0 4952.0 4941.4 5001.9 5007.8 5012.3 5057.6 4730.7 4768.5 4915.6 4924.6 4981.6 4758.7 4780.3 4826.5 4829.9 4873.3 4753.4 4781.8 4783.2 4794.2 4843.5 5153.0 5233.2 5272.8 5292.2 5304.2 5568.1 5645.7 5694.5 5705.2 5775.7 5703.2 5723.3 5743.1 5746.9 5751.5 5350.5 5357.4 5361.7 5362.7 5417.5 5768.8 5831.2 5842.2 5868.6 5894.4 5651.4 5662.6 5665.1 5665.9 5669.0 5567.2 5757.5 5764.0 5811.0 5818.2 5584.3 5676.3 5727.6 5741.1 5743.8 5731.0 5747.5 5750.7 5767.7 5778.0 4308.7 4437.4 4449.8 4458.3 4512.4 3878.0 3930.9 3963.1 3979.2 4015.8 5474.6 5518.3 5531.7 5537.0 5538.6 5809.7 5828.1 5830.4 5832.8 5894.5 5334.1 5390.1 5418.7 5437.9 5467.5 4980.5 4982.1 4983.0 4983.9 5031.2 5842.2 5842.9 5883.5 5907.6 5943.8 5520.0 5636.9 5703.3 5727.5 5742.3 5937.4 5940.7 5949.3 5984.1 6003.3 5653.3 5653.8 5662.6 5669.1 5670.1 5602.6 5656.4 5664.8 5728.5 5781.3 5519.1 5569.2 5579.5 5648.4 5657.6 5594.8 5599.8 5600.2 5604.5 5613.7 5360.7 5460.7 5477.1 5556.6 5578.2 5686.4 5695.0 5696.7 5702.4 5766.0 5523.1 5550.4 5551.1 5552.9 5566.0 18:42:01 INFO - PROCESS | 25846 | 18:42:01 INFO - PROCESS | 25846 | [#1] ext.html Cycles:180 Average:60638.36 Median:39396.51 stddev:123447.31 (313.3%) stddev-sans-first:123711.19 18:42:01 INFO - PROCESS | 25846 | Values: 555.3 577.7 598.0 605.4 629.7 36433.6 37936.4 37954.4 39255.0 39265.0 43871.0 44211.8 44317.8 44356.1 44463.0 8967.5 9136.0 9142.9 9146.8 9149.5 8771.0 8922.7 8923.2 8972.8 9022.7 9020.2 9039.5 9054.7 9075.5 9078.7 8868.5 8981.2 9017.8 9028.7 9052.5 8843.1 8886.4 8899.7 8915.1 8915.9 42233.0 42933.8 43000.1 43203.6 43220.6 3375.9 3624.6 3636.2 3802.9 3820.2 31821.7 31870.7 32067.2 32135.4 32486.2 4107.9 4222.9 4243.8 4254.6 4416.4 364023.0 366457.0 370209.0 371038.0 371238.0 59377.0 59997.0 60096.7 60160.4 60218.4 36596.5 37631.8 37751.2 39257.4 39357.4 16098.9 16115.9 16186.2 16276.0 16343.3 12238.6 12359.6 12364.5 12395.4 12438.1 18916.3 19149.0 19179.4 19187.4 19239.0 7321.6 7323.5 7333.2 7471.8 7472.4 930.7 1002.7 1021.3 1050.2 1121.3 30388.0 30559.2 30860.0 31312.2 31330.7 1111.2 1114.6 1117.6 1130.5 1140.2 16195.8 16322.4 16414.9 16436.3 16870.3 540297.0 544037.0 547904.0 548542.0 549544.0 56751.7 56853.7 57034.7 57070.7 57250.7 1203.7 1249.0 1251.3 1251.5 1320.2 28742.0 29314.0 29978.3 30329.0 30833.2 50081.0 50482.7 50532.7 50546.2 50654.2 458667.0 461456.0 463930.0 464162.0 464207.0 27775.4 28519.4 28521.9 28642.0 28679.6 43634.6 43875.3 43935.6 46155.8 46629.0 35106.3 35290.1 35496.0 35576.1 36201.0 40173.4 40291.2 40626.6 41906.6 42078.2 38711.8 39257.8 39435.6 39565.4 40641.8 35482.8 36851.0 36982.0 37437.8 37883.0 27123.0 27255.2 27599.4 27672.4 27825.7 18:42:01 INFO - PROCESS | 25846 | 18:42:01 INFO - PROCESS | 25846 | [#2] jquery.html Cycles:180 Average:28186.26 Median:19832.32 stddev:80025.42 (403.5%) stddev-sans-first:80222.70 18:42:01 INFO - PROCESS | 25846 | Values: 390.6 396.6 398.1 399.0 416.4 17956.3 19101.1 19147.7 19443.2 19558.0 18407.8 19008.8 19201.0 19419.9 19753.5 5424.9 5443.4 5598.6 5621.0 5635.0 5557.8 5580.9 5602.8 5616.5 5619.3 5374.2 5493.4 5498.8 5520.4 5532.6 5452.1 5484.0 5487.4 5494.0 5513.3 4824.0 4860.6 4999.0 5005.9 5010.6 21093.0 21304.6 21320.0 21400.3 21415.0 2321.4 2327.5 2329.7 2369.5 3095.3 13992.6 14552.1 14825.3 14830.4 14972.9 1932.9 1949.3 1962.1 1998.5 2020.8 80025.8 80366.6 80974.0 81412.0 82750.2 9886.0 9892.0 10122.2 10534.8 10674.3 14173.1 14197.4 14209.4 14230.3 14235.1 7810.6 8232.3 8236.9 8401.2 8474.9 7935.0 7960.5 8035.8 8247.5 8339.6 6204.6 6286.8 6393.6 6432.5 6609.4 1924.6 1932.0 1952.0 1957.2 1985.3 884.2 998.5 1002.5 1017.5 1048.3 19157.8 19315.7 19561.5 19563.7 19761.2 956.8 988.4 999.9 1017.5 1048.4 10726.8 11087.2 11164.0 11258.8 11485.1 480154.0 482923.1 484145.0 490426.0 496720.0 76985.0 77204.5 80564.6 80742.0 81697.0 984.6 1017.0 1055.3 1057.4 1059.2 15538.8 15743.8 15975.8 16139.3 16341.0 39274.0 39803.4 39867.0 40558.8 40805.8 49920.8 52860.0 53090.0 53231.7 53936.5 8092.8 8110.5 8215.3 8322.8 8334.3 11729.9 12124.3 12324.3 12357.0 12658.5 10116.8 10387.3 10496.3 10709.7 10755.1 5427.5 8466.8 10249.6 10579.6 10702.7 10815.8 10916.4 10959.8 10992.4 11085.8 7947.5 8064.0 8116.2 8147.3 8421.8 10581.5 10686.2 10709.8 10738.9 11019.6 18:42:01 INFO - PROCESS | 25846 | 18:42:01 INFO - PROCESS | 25846 | [#3] mootools.html Cycles:180 Average:5724.44 Median:4910.43 stddev:3290.89 (67.0%) stddev-sans-first:3283.52 18:42:01 INFO - PROCESS | 25846 | Values: 1326.6 1393.1 1423.9 1509.0 1516.1 7267.7 7538.8 8378.0 9047.6 9057.5 8223.7 8517.3 8537.7 8736.2 8761.7 2315.6 2330.9 2335.8 2342.8 2346.1 2257.9 2294.5 2296.9 2301.3 2304.8 2328.7 2337.7 2339.6 2348.9 2358.2 2250.0 2254.1 2255.4 2257.5 2259.0 2227.0 2229.7 2235.2 2239.3 2243.1 8661.3 8756.5 8772.3 8802.1 8830.2 5073.0 5144.7 5177.0 5183.2 5185.3 3260.5 3325.2 3448.8 3452.5 3472.5 1103.7 1109.2 1126.6 1136.1 1139.6 12117.5 12131.1 12169.1 12170.5 12204.2 4415.9 4474.3 4621.7 4633.4 4730.2 10220.5 10318.3 10331.2 10363.4 10453.9 5222.6 5240.7 5245.6 5261.0 5262.8 4746.9 4859.1 4888.7 4939.4 4992.1 3797.4 3891.1 3902.4 3954.2 3977.1 1200.6 1203.5 1205.9 1214.2 1215.1 754.7 849.7 876.6 915.4 954.7 5389.4 5445.3 5639.9 5640.9 5832.8 901.9 920.4 920.9 955.4 992.9 3587.6 3711.6 3897.4 3904.4 3925.8 10740.4 10864.6 10946.9 10956.4 10980.3 10883.9 10894.6 10899.0 10947.9 10951.4 7978.4 8236.3 8263.3 8414.7 8450.6 5691.1 5710.2 5724.5 5864.9 5889.9 10174.8 10238.3 10304.9 10348.2 10469.8 9557.5 9692.2 9844.7 9978.1 10458.7 7123.6 7325.8 7905.8 7978.7 8237.7 8327.1 8393.8 8560.6 8586.9 8601.4 7485.7 7504.8 7532.4 7553.2 7596.1 7383.9 7452.4 7480.2 7649.5 7696.8 6986.8 7101.7 7123.2 7170.7 7182.6 4815.6 4864.6 4932.2 4984.2 5038.0 4976.9 5013.4 5016.1 5070.5 5110.8 18:42:01 INFO - PROCESS | 25846 | 18:42:01 INFO - PROCESS | 25846 | [#4] prototype.html Cycles:180 Average:4672.12 Median:5251.50 stddev:1387.15 (26.4%) stddev-sans-first:1351.18 18:42:01 INFO - PROCESS | 25846 | Values: 273.0 291.3 296.2 298.0 307.3 5386.2 5524.3 5530.3 5588.6 5600.7 5334.5 5458.3 5488.0 5554.7 5580.3 4238.5 4282.9 4286.6 4288.2 4290.7 4085.2 4198.5 4222.1 4284.5 4288.6 4184.8 4187.4 4194.6 4215.3 4216.4 4216.7 4222.7 4226.1 4236.9 4244.9 3670.0 3701.8 3724.6 3730.6 3734.5 5335.6 5353.5 5398.6 5417.5 5450.8 4813.2 4835.4 4843.0 4847.1 4848.7 5630.3 5640.7 5769.0 5774.3 5789.9 3982.0 3996.0 3997.4 4006.7 4040.3 5983.9 6026.2 6041.0 6092.0 6092.5 3798.8 4110.5 4141.8 4155.5 4160.9 4063.4 4063.8 4064.5 4070.0 4072.8 4795.9 4815.6 4819.7 4820.2 4830.3 5702.6 5892.9 5924.4 5925.9 5940.7 1400.8 1421.2 1422.3 1425.5 1430.3 865.0 888.6 891.7 905.2 907.7 4849.6 4974.4 4995.2 4995.4 5038.3 5216.7 5239.8 5289.4 5294.7 5311.6 4854.0 4998.3 4999.4 5003.8 5012.9 2797.2 2809.0 2845.9 2847.7 2857.2 5440.9 5483.5 5502.7 5512.8 5525.2 5182.6 5334.3 5350.7 5362.0 5457.9 5333.8 5350.0 5365.7 5384.3 5390.0 5200.4 5217.9 5220.7 5241.7 5313.8 4935.4 5217.5 5241.3 5244.1 5258.9 5380.9 5418.7 5419.3 5429.2 5432.6 3718.3 3860.6 4087.6 4089.2 4096.2 5960.3 5969.1 5986.8 6040.6 6068.9 5615.0 5877.4 5899.8 5905.0 5909.5 5797.6 5797.7 5802.3 5806.3 5847.2 5752.9 5796.8 5832.7 5833.8 5839.8 5612.4 5871.9 5877.9 5903.6 5906.6 5741.1 5746.6 5760.5 5777.5 5794.9 18:42:01 INFO - PROCESS | 25846 | 18:42:01 INFO - PROCESS | 25846 | [#5] yui.html Cycles:180 Average:20521.98 Median:4542.93 stddev:29396.02 (647.1%) stddev-sans-first:29441.04 18:42:01 INFO - PROCESS | 25846 | Values: 762.7 764.4 782.1 794.4 804.8 15462.8 16008.8 16065.8 16250.8 16334.2 15607.0 15691.1 15792.8 15799.7 15843.0 657.1 673.1 777.9 791.2 794.1 812.2 817.9 821.8 822.4 824.0 861.9 863.5 864.0 864.3 864.5 848.3 850.1 851.6 854.0 857.2 623.4 629.0 629.6 634.6 634.7 15318.7 15340.2 15398.3 15407.6 15489.8 14755.1 14795.4 14813.0 14852.9 15071.9 11275.4 11596.2 11794.4 11918.5 11979.1 4426.8 4450.4 4459.1 4472.3 4558.9 77256.6 77692.0 78099.0 81396.0 83045.0 10481.4 10704.9 10970.6 11211.1 11216.2 18559.2 18665.3 18678.7 18697.0 19033.2 8442.2 8588.2 8607.2 8622.4 8719.1 4527.0 4570.2 4571.8 4628.5 4642.1 3150.2 3253.7 3262.3 3264.1 3266.8 528.1 535.2 535.7 536.4 546.0 618.4 629.7 648.1 654.5 660.4 8716.0 9145.2 9167.4 9176.4 9470.9 628.5 636.1 637.8 653.8 713.4 4135.0 4431.6 4443.9 4474.1 4517.5 68964.0 69365.3 117721.0 120397.0 123166.0 59494.0 81188.0 102696.0 103090.5 103474.5 86039.0 89999.0 92376.5 92750.5 92788.5 8655.8 8673.7 8694.8 8843.4 8923.1 51607.2 52241.2 53563.7 53754.5 54481.7 79025.0 79817.0 80771.6 82990.0 83414.0 10580.2 10653.8 11145.8 11392.5 11741.0 12551.4 12931.2 14500.6 14591.3 14810.4 13245.9 14012.7 14298.1 14695.2 14856.7 13510.0 14053.2 14164.3 14220.0 14235.6 13811.2 13937.0 14049.2 14082.5 14941.7 10559.0 10614.3 11608.6 12247.0 12397.8 11125.6 12344.3 12622.4 12650.1 12685.7 18:42:01 INFO - PROCESS | 25846 | -------- Summary: end -------- 18:42:01 INFO - PROCESS | 25846 | 18:42:02 INFO - TEST-INFO | 25846: exit 0 18:42:02 INFO - TEST-OK | dromaeo_css | took 1112854ms 18:42:02 INFO - TEST-START | kraken 18:42:02 INFO - operating with platform_type : linux_ 18:42:02 INFO - Initialising browser for kraken test... 18:42:02 INFO - TEST-INFO | started process 26211 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplMylsN/profile http://localhost:41569/getInfo.html) 18:42:02 INFO - PROCESS | 26211 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 18:42:02 INFO - TEST-INFO | 26211: exit 127 18:42:02 INFO - Could not find __metrics(.*)__metrics in browser output 18:42:02 INFO - Raw results:Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 18:42:02 INFO - TEST-UNEXPECTED-ERROR | kraken | browser failed to close after being initialized 18:42:02 ERROR - Traceback (most recent call last): 18:42:02 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 18:42:02 INFO - talos_results.add(mytest.runTest(browser_config, test)) 18:42:02 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 69, in runTest 18:42:02 INFO - with FFSetup(browser_config, test_config) as setup: 18:42:02 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 165, in __enter__ 18:42:02 INFO - self._run_profile() 18:42:02 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 141, in _run_profile 18:42:02 INFO - raise TalosError("browser failed to close after being initialized") 18:42:02 INFO - TalosError: browser failed to close after being initialized 18:42:02 INFO - TEST-INFO took 278ms 18:42:02 INFO - SUITE-END | took 1113s 18:42:02 ERROR - Return code: 2 18:42:02 WARNING - setting return code to 2 18:42:02 ERROR - # TBPL FAILURE # 18:42:02 INFO - Running post-action listener: _resource_record_post_action 18:42:02 INFO - [mozharness: 2016-09-22 01:42:02.859728Z] Finished run-tests step (success) 18:42:02 INFO - Running post-run listener: _resource_record_post_run 18:42:02 INFO - Total resource usage - Wall time: 1130s; CPU: 13.0%; Read bytes: 4096; Write bytes: 343293952; Read time: 16; Write time: 282488 18:42:02 INFO - TinderboxPrint: CPU usage
13.4% 18:42:02 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 18:42:02 INFO - TinderboxPrint: I/O write bytes / time
343,293,952 / 282,488 18:42:02 INFO - TinderboxPrint: CPU idle
7,800.6 (86.5%) 18:42:02 INFO - TinderboxPrint: CPU user
1,165.7 (12.9%) 18:42:02 INFO - TinderboxPrint: Swap in / out
0 / 0 18:42:02 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114282496; Read time: 0; Write time: 81444 18:42:03 INFO - run-tests - Wall time: 1113s; CPU: 13.0%; Read bytes: 0; Write bytes: 227717120; Read time: 0; Write time: 200968 18:42:03 INFO - Running post-run listener: _upload_blobber_files 18:42:03 INFO - Blob upload gear active. 18:42:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:42:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:42:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:42:03 INFO - (blobuploader) - INFO - Open directory for files ... 18:42:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:42:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:42:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:08 INFO - (blobuploader) - INFO - Done attempting. 18:42:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:42:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:42:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:09 INFO - (blobuploader) - INFO - Done attempting. 18:42:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:42:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:42:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:09 INFO - (blobuploader) - INFO - Done attempting. 18:42:09 INFO - (blobuploader) - INFO - Iteration through files over. 18:42:09 INFO - Return code: 0 18:42:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:42:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:42:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0590221a5868f17de46aecfbddff757bea3626a08cdc7c38acf95ef56b2ce956e2921c446d6fc4ccf0c22b1585f344c5fd4cb927abd8cfb8c28372495e6a83cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62c6c01776f9cce2dd9c835abf224c5f9273759556f999786a57b64f56b646b1478fdbb7cf4e594319ec1def8b71165295a6f29c42d1565f5629dd6b671a2612", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e7f8c6b4290352f1b8e8b1f5dac28b440ef597432daa3576574202c993ca52626be1fd95c77447b733a2b780f0bd1ec539074ed77cfc716a3f82532c657e700d"} 18:42:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:42:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:42:09 INFO - Contents: 18:42:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0590221a5868f17de46aecfbddff757bea3626a08cdc7c38acf95ef56b2ce956e2921c446d6fc4ccf0c22b1585f344c5fd4cb927abd8cfb8c28372495e6a83cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62c6c01776f9cce2dd9c835abf224c5f9273759556f999786a57b64f56b646b1478fdbb7cf4e594319ec1def8b71165295a6f29c42d1565f5629dd6b671a2612", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e7f8c6b4290352f1b8e8b1f5dac28b440ef597432daa3576574202c993ca52626be1fd95c77447b733a2b780f0bd1ec539074ed77cfc716a3f82532c657e700d"} 18:42:09 INFO - Running post-run listener: copy_logs_to_upload_dir 18:42:09 INFO - Copying logs to upload dir... 18:42:09 INFO - mkdir: /builds/slave/test/build/upload/logs 18:42:09 INFO - Copying logs to upload dir... 18:42:09 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=1174.770408 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 34 secs) (at 2016-09-21 18:42:09.718041) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 18:42:09.721634) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0590221a5868f17de46aecfbddff757bea3626a08cdc7c38acf95ef56b2ce956e2921c446d6fc4ccf0c22b1585f344c5fd4cb927abd8cfb8c28372495e6a83cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62c6c01776f9cce2dd9c835abf224c5f9273759556f999786a57b64f56b646b1478fdbb7cf4e594319ec1def8b71165295a6f29c42d1565f5629dd6b671a2612", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e7f8c6b4290352f1b8e8b1f5dac28b440ef597432daa3576574202c993ca52626be1fd95c77447b733a2b780f0bd1ec539074ed77cfc716a3f82532c657e700d"} build_url:https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/AuVVSrCjQ8q-EFfV2bbuHg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0590221a5868f17de46aecfbddff757bea3626a08cdc7c38acf95ef56b2ce956e2921c446d6fc4ccf0c22b1585f344c5fd4cb927abd8cfb8c28372495e6a83cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62c6c01776f9cce2dd9c835abf224c5f9273759556f999786a57b64f56b646b1478fdbb7cf4e594319ec1def8b71165295a6f29c42d1565f5629dd6b671a2612", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e7f8c6b4290352f1b8e8b1f5dac28b440ef597432daa3576574202c993ca52626be1fd95c77447b733a2b780f0bd1ec539074ed77cfc716a3f82532c657e700d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 18:42:09.752700) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:42:09.753556) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1474506325.32422-112756448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 18:42:09.836032) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 18:42:09.836746) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 18:42:09.837281) ========= ========= Total master_lag: 0.20 =========