builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-008 starttime: 1476548399.03 results: failure (2) buildid: 20161015062335 builduid: c4ec9ddf26a64dcaa0945431942ee743 revision: df83c59042d9b82f1d27b4afb9066744da22cd98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-15 09:19:59.028100) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-15 09:19:59.028599) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 09:19:59.028931) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 09:19:59.118625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 09:19:59.118921) ========= 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-1476547946.232719-1156656160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-15 09:20:00-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.7M=0s 2016-10-15 09:20:00 (32.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148016 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 09:19:59.291469) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 09:19:59.291800) ========= 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-1476547946.232719-1156656160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014708 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 09:19:59.343532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 09:19:59.343905) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev df83c59042d9b82f1d27b4afb9066744da22cd98 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev df83c59042d9b82f1d27b4afb9066744da22cd98 --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-1476547946.232719-1156656160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-15 09:20:00,350 truncating revision to first 12 chars 2016-10-15 09:20:00,350 Setting DEBUG logging. 2016-10-15 09:20:00,350 attempt 1/10 2016-10-15 09:20:00,350 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/df83c59042d9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-15 09:20:00,647 unpacking tar archive at: mozilla-aurora-df83c59042d9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.624749 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 09:20:00.001680) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 09:20:00.001999) ========= script_repo_revision: df83c59042d9b82f1d27b4afb9066744da22cd98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 09:20:00.002458) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 09:20:00.002777) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 09:20:00.023039) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 56 secs) (at 2016-10-15 09:20:00.023376) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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-1476547946.232719-1156656160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:20:01 INFO - MultiFileLogger online at 20161015 09:20:01 in /builds/slave/test 09:20:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:20:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:20:01 INFO - {'append_to_log': False, 09:20:01 INFO - 'base_work_dir': '/builds/slave/test', 09:20:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:20:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:20:01 INFO - 'branch': 'Mozilla-Aurora', 09:20:01 INFO - 'buildbot_json_path': 'buildprops.json', 09:20:01 INFO - 'config_files': ('talos/linux_config.py',), 09:20:01 INFO - 'default_actions': ('clobber', 09:20:01 INFO - 'read-buildbot-config', 09:20:01 INFO - 'download-and-extract', 09:20:01 INFO - 'populate-webroot', 09:20:01 INFO - 'create-virtualenv', 09:20:01 INFO - 'install', 09:20:01 INFO - 'run-tests'), 09:20:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:20:01 INFO - 'download_minidump_stackwalk': True, 09:20:01 INFO - 'download_symbols': 'ondemand', 09:20:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:20:01 INFO - 'tooltool.py': '/tools/tooltool.py', 09:20:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:20:01 INFO - '/tools/misc-python/virtualenv.py')}, 09:20:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:20:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:20:01 INFO - 'installer_path': 'installer.exe', 09:20:01 INFO - 'log_level': 'info', 09:20:01 INFO - 'log_name': 'talos', 09:20:01 INFO - 'log_to_console': True, 09:20:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:20:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:20:01 INFO - 'opt_config_files': (), 09:20:01 INFO - 'pip_index': False, 09:20:01 INFO - 'sps_profile': False, 09:20:01 INFO - 'sps_profile_interval': 0, 09:20:01 INFO - 'suite': 'dromaeojs', 09:20:01 INFO - 'system_bits': '32', 09:20:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:20:01 INFO - 'title': 'talos-linux64-ix-008', 09:20:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:20:01 INFO - 'use_talos_json': True, 09:20:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:20:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:20:01 INFO - 'work_dir': 'build'} 09:20:01 INFO - [mozharness: 2016-10-15 16:20:01.124764Z] Running clobber step. 09:20:01 INFO - Running pre-action listener: _resource_record_pre_action 09:20:01 INFO - Running main action method: clobber 09:20:01 INFO - rmtree: /builds/slave/test/build 09:20:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:20:02 INFO - Running post-action listener: _resource_record_post_action 09:20:02 INFO - [mozharness: 2016-10-15 16:20:02.107696Z] Finished clobber step (success) 09:20:02 INFO - [mozharness: 2016-10-15 16:20:02.107796Z] Running read-buildbot-config step. 09:20:02 INFO - Running pre-action listener: _resource_record_pre_action 09:20:02 INFO - Running main action method: read_buildbot_config 09:20:02 INFO - Using buildbot properties: 09:20:02 INFO - { 09:20:02 INFO - "project": "", 09:20:02 INFO - "product": "firefox", 09:20:02 INFO - "script_repo_revision": "production", 09:20:02 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:20:02 INFO - "repository": "", 09:20:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:20:02 INFO - "buildid": "20161015062335", 09:20:02 INFO - "pgo_build": "True", 09:20:02 INFO - "basedir": "/builds/slave/test", 09:20:02 INFO - "buildnumber": 518, 09:20:02 INFO - "slavename": "talos-linux64-ix-008", 09:20:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:20:02 INFO - "platform": "ubuntu64_hw", 09:20:02 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:20:02 INFO - "branch": "mozilla-aurora", 09:20:02 INFO - "repo_path": "releases/mozilla-aurora", 09:20:02 INFO - "revision": "df83c59042d9b82f1d27b4afb9066744da22cd98", 09:20:02 INFO - "stage_platform": "linux64-pgo", 09:20:02 INFO - "builduid": "c4ec9ddf26a64dcaa0945431942ee743", 09:20:02 INFO - "slavebuilddir": "test" 09:20:02 INFO - } 09:20:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 09:20:02 INFO - Running post-action listener: _resource_record_post_action 09:20:02 INFO - [mozharness: 2016-10-15 16:20:02.110215Z] Finished read-buildbot-config step (success) 09:20:02 INFO - [mozharness: 2016-10-15 16:20:02.110305Z] Running download-and-extract step. 09:20:02 INFO - Running pre-action listener: _resource_record_pre_action 09:20:02 INFO - Running main action method: download_and_extract 09:20:02 INFO - mkdir: /builds/slave/test/build/tests 09:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:02 INFO - trying https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:20:02 INFO - Downloading https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:20:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:20:03 INFO - Downloaded 1361 bytes. 09:20:03 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:20:03 INFO - Using the following test package requirements: 09:20:03 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:20:03 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:03 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:20:03 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:03 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:20:03 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:03 INFO - u'jsshell-linux-x86_64.zip'], 09:20:03 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:03 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:20:03 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:20:03 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:03 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:20:03 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:03 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:20:03 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:03 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:20:03 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:03 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:20:03 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:20:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:20:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:20:03 INFO - Fetch https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:20:11 INFO - Expected file size: 21907087 09:20:15 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:20:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:20:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:20:15 INFO - Fetch https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:20:31 INFO - Expected file size: 21907087 09:20:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 09:20:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:20:35 INFO - Fetch https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:20:36 INFO - Expected file size: 14414081 09:20:37 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')]} 09:20:37 INFO - trying https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:20:37 INFO - Downloading https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:20:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:20:38 INFO - Downloaded 64001099 bytes. 09:20:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:20:38 INFO - mkdir: /builds/slave/test/properties 09:20:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:20:38 INFO - Writing to file /builds/slave/test/properties/build_url 09:20:38 INFO - Contents: 09:20:38 INFO - build_url:https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:20:39 INFO - Running post-action listener: _resource_record_post_action 09:20:39 INFO - Running post-action listener: set_extra_try_arguments 09:20:39 INFO - [mozharness: 2016-10-15 16:20:39.081281Z] Finished download-and-extract step (success) 09:20:39 INFO - [mozharness: 2016-10-15 16:20:39.081509Z] Running populate-webroot step. 09:20:39 INFO - Running pre-action listener: _resource_record_pre_action 09:20:39 INFO - Running main action method: populate_webroot 09:20:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:20:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:20:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:20:39 INFO - '--tppagecycles', 09:20:39 INFO - '7'], 09:20:39 INFO - 'tests': ['tsvgm']}}, 09:20:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:20:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:20:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:20:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:20:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:39 INFO - 'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:20:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:20:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:39 INFO - 'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['damp', 'tps']}, 09:20:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:39 INFO - 'tests': ['damp', 'tps']}, 09:20:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['dromaeo_dom']}, 09:20:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:20:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['basic_compositor_video']}, 09:20:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:20:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['a11yr', 09:20:39 INFO - 'ts_paint', 09:20:39 INFO - 'tpaint', 09:20:39 INFO - 'sessionrestore', 09:20:39 INFO - 'sessionrestore_no_auto_restore', 09:20:39 INFO - 'tabpaint']}, 09:20:39 INFO - 'other-e10s': {'tests': ['a11yr', 09:20:39 INFO - 'ts_paint', 09:20:39 INFO - 'tpaint', 09:20:39 INFO - 'sessionrestore', 09:20:39 INFO - 'sessionrestore_no_auto_restore', 09:20:39 INFO - 'tabpaint']}, 09:20:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:20:39 INFO - 'ts_paint', 09:20:39 INFO - 'tpaint', 09:20:39 INFO - 'sessionrestore', 09:20:39 INFO - 'sessionrestore_no_auto_restore', 09:20:39 INFO - 'tabpaint']}, 09:20:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:20:39 INFO - 'ts_paint', 09:20:39 INFO - 'tpaint', 09:20:39 INFO - 'sessionrestore', 09:20:39 INFO - 'sessionrestore_no_auto_restore', 09:20:39 INFO - 'tabpaint']}, 09:20:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['a11yr', 09:20:39 INFO - 'ts_paint', 09:20:39 INFO - 'tpaint', 09:20:39 INFO - 'sessionrestore', 09:20:39 INFO - 'sessionrestore_no_auto_restore', 09:20:39 INFO - 'tabpaint']}, 09:20:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['a11yr', 09:20:39 INFO - 'ts_paint', 09:20:39 INFO - 'tpaint', 09:20:39 INFO - 'sessionrestore', 09:20:39 INFO - 'sessionrestore_no_auto_restore', 09:20:39 INFO - 'tabpaint']}, 09:20:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['tsvgx', 09:20:39 INFO - 'tsvgr_opacity', 09:20:39 INFO - 'tart', 09:20:39 INFO - 'tscrollx', 09:20:39 INFO - 'cart']}, 09:20:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:20:39 INFO - 'tsvgr_opacity', 09:20:39 INFO - 'tart', 09:20:39 INFO - 'tscrollx', 09:20:39 INFO - 'cart']}, 09:20:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:39 INFO - 'talos_options': ['--disable-e10s'], 09:20:39 INFO - 'tests': ['tp5o']}, 09:20:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:39 INFO - 'tests': ['tp5o']}, 09:20:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:39 INFO - 'talos_options': ['--disable-e10s', 09:20:39 INFO - '--xperf_path', 09:20:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:20:39 INFO - 'tests': ['tp5n']}, 09:20:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:39 INFO - 'talos_options': ['--xperf_path', 09:20:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:20:39 INFO - 'tests': ['tp5n']}}, 09:20:39 INFO - 'talos.zip': {'path': '', 09:20:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:20:39 INFO - Running post-action listener: _resource_record_post_action 09:20:39 INFO - [mozharness: 2016-10-15 16:20:39.098872Z] Finished populate-webroot step (success) 09:20:39 INFO - [mozharness: 2016-10-15 16:20:39.098960Z] Running create-virtualenv step. 09:20:39 INFO - Running pre-action listener: _resource_record_pre_action 09:20:39 INFO - Running main action method: create_virtualenv 09:20:39 INFO - Creating virtualenv /builds/slave/test/build/venv 09:20:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:20:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:20:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:20:39 INFO - Using real prefix '/usr' 09:20:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:20:39 INFO - Installing distribute.............................................................................................................................................................................................done. 09:20:41 INFO - Installing pip.................done. 09:20:41 INFO - Return code: 0 09:20:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:41 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 0x7fbff2833f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2384ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, '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-1476547946.232719-1156656160', '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 09:20:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:20:41 INFO - Using env: {'DISPLAY': ':0', 09:20:41 INFO - 'HOME': '/home/cltbld', 09:20:41 INFO - 'LANG': 'en_US.UTF-8', 09:20:41 INFO - 'LANGUAGE': 'en_US:en', 09:20:41 INFO - 'LOGNAME': 'cltbld', 09:20:41 INFO - 'MAIL': '/var/mail/cltbld', 09:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:41 INFO - 'MOZ_NO_REMOTE': '1', 09:20:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:41 INFO - 'NO_EM_RESTART': '1', 09:20:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:41 INFO - 'PWD': '/builds/slave/test', 09:20:41 INFO - 'SHELL': '/bin/bash', 09:20:41 INFO - 'SHLVL': '1', 09:20:41 INFO - 'TERM': 'linux', 09:20:41 INFO - 'TMOUT': '86400', 09:20:41 INFO - 'USER': 'cltbld', 09:20:41 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:41 INFO - '_': '/tools/buildbot/bin/python'} 09:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:20:41 INFO - Cleaning up... 09:20:41 INFO - Return code: 0 09:20:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:41 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 0x7fbff2833f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2384ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, '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-1476547946.232719-1156656160', '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 09:20:41 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 09:20:41 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 09:20:41 INFO - Using env: {'DISPLAY': ':0', 09:20:41 INFO - 'HOME': '/home/cltbld', 09:20:41 INFO - 'LANG': 'en_US.UTF-8', 09:20:41 INFO - 'LANGUAGE': 'en_US:en', 09:20:41 INFO - 'LOGNAME': 'cltbld', 09:20:41 INFO - 'MAIL': '/var/mail/cltbld', 09:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:41 INFO - 'MOZ_NO_REMOTE': '1', 09:20:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:41 INFO - 'NO_EM_RESTART': '1', 09:20:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:41 INFO - 'PWD': '/builds/slave/test', 09:20:41 INFO - 'SHELL': '/bin/bash', 09:20:41 INFO - 'SHLVL': '1', 09:20:41 INFO - 'TERM': 'linux', 09:20:41 INFO - 'TMOUT': '86400', 09:20:41 INFO - 'USER': 'cltbld', 09:20:41 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:41 INFO - '_': '/tools/buildbot/bin/python'} 09:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:42 INFO - Downloading/unpacking psutil>=3.1.1 09:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:20:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:44 INFO - Installing collected packages: psutil 09:20:44 INFO - Running setup.py install for psutil 09:20:44 INFO - building 'psutil._psutil_linux' extension 09:20:44 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 09:20:44 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 09:20:44 INFO - building 'psutil._psutil_posix' extension 09:20:44 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 09:20:44 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 09:20:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:44 INFO - Successfully installed psutil 09:20:44 INFO - Cleaning up... 09:20:44 INFO - Return code: 0 09:20:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:20:44 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')]} 09:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:44 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')]} 09:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:44 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 0x7fbff2833f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2384ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, '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-1476547946.232719-1156656160', '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 09:20:44 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 09:20:44 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 09:20:44 INFO - Using env: {'DISPLAY': ':0', 09:20:44 INFO - 'HOME': '/home/cltbld', 09:20:44 INFO - 'LANG': 'en_US.UTF-8', 09:20:44 INFO - 'LANGUAGE': 'en_US:en', 09:20:44 INFO - 'LOGNAME': 'cltbld', 09:20:44 INFO - 'MAIL': '/var/mail/cltbld', 09:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:44 INFO - 'MOZ_NO_REMOTE': '1', 09:20:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:44 INFO - 'NO_EM_RESTART': '1', 09:20:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:44 INFO - 'PWD': '/builds/slave/test', 09:20:44 INFO - 'SHELL': '/bin/bash', 09:20:44 INFO - 'SHLVL': '1', 09:20:44 INFO - 'TERM': 'linux', 09:20:44 INFO - 'TMOUT': '86400', 09:20:44 INFO - 'USER': 'cltbld', 09:20:44 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:44 INFO - '_': '/tools/buildbot/bin/python'} 09:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:20:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:20:46 INFO - Installing collected packages: mozsystemmonitor 09:20:46 INFO - Running setup.py install for mozsystemmonitor 09:20:46 INFO - Successfully installed mozsystemmonitor 09:20:46 INFO - Cleaning up... 09:20:47 INFO - Return code: 0 09:20:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:47 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 0x7fbff2833f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2384ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, '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-1476547946.232719-1156656160', '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 09:20:47 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 09:20:47 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 09:20:47 INFO - Using env: {'DISPLAY': ':0', 09:20:47 INFO - 'HOME': '/home/cltbld', 09:20:47 INFO - 'LANG': 'en_US.UTF-8', 09:20:47 INFO - 'LANGUAGE': 'en_US:en', 09:20:47 INFO - 'LOGNAME': 'cltbld', 09:20:47 INFO - 'MAIL': '/var/mail/cltbld', 09:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:47 INFO - 'MOZ_NO_REMOTE': '1', 09:20:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:47 INFO - 'NO_EM_RESTART': '1', 09:20:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:47 INFO - 'PWD': '/builds/slave/test', 09:20:47 INFO - 'SHELL': '/bin/bash', 09:20:47 INFO - 'SHLVL': '1', 09:20:47 INFO - 'TERM': 'linux', 09:20:47 INFO - 'TMOUT': '86400', 09:20:47 INFO - 'USER': 'cltbld', 09:20:47 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:47 INFO - '_': '/tools/buildbot/bin/python'} 09:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:47 INFO - Downloading/unpacking jsonschema==2.5.1 09:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:20:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:49 INFO - Downloading functools32-3.2.3-2.tar.gz 09:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:20:49 INFO - warning: no files found matching '*.txt' 09:20:49 INFO - no previously-included directories found matching 'build' 09:20:49 INFO - no previously-included directories found matching 'dist' 09:20:49 INFO - no previously-included directories found matching '.git*' 09:20:49 INFO - Installing collected packages: jsonschema, functools32 09:20:49 INFO - Running setup.py install for functools32 09:20:49 INFO - warning: no files found matching '*.txt' 09:20:49 INFO - no previously-included directories found matching 'build' 09:20:49 INFO - no previously-included directories found matching 'dist' 09:20:49 INFO - no previously-included directories found matching '.git*' 09:20:49 INFO - Successfully installed jsonschema functools32 09:20:49 INFO - Cleaning up... 09:20:49 INFO - Return code: 0 09:20:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:49 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 0x7fbff2833f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2384ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, '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-1476547946.232719-1156656160', '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 09:20:49 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 09:20:49 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 09:20:49 INFO - Using env: {'DISPLAY': ':0', 09:20:49 INFO - 'HOME': '/home/cltbld', 09:20:49 INFO - 'LANG': 'en_US.UTF-8', 09:20:49 INFO - 'LANGUAGE': 'en_US:en', 09:20:49 INFO - 'LOGNAME': 'cltbld', 09:20:49 INFO - 'MAIL': '/var/mail/cltbld', 09:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:49 INFO - 'MOZ_NO_REMOTE': '1', 09:20:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:49 INFO - 'NO_EM_RESTART': '1', 09:20:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:49 INFO - 'PWD': '/builds/slave/test', 09:20:49 INFO - 'SHELL': '/bin/bash', 09:20:49 INFO - 'SHLVL': '1', 09:20:49 INFO - 'TERM': 'linux', 09:20:49 INFO - 'TMOUT': '86400', 09:20:49 INFO - 'USER': 'cltbld', 09:20:49 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:49 INFO - '_': '/tools/buildbot/bin/python'} 09:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:20:49 INFO - Cleaning up... 09:20:49 INFO - Return code: 0 09:20:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:49 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 0x7fbff2833f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2384ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, '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-1476547946.232719-1156656160', '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 09:20:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:20:49 INFO - Using env: {'DISPLAY': ':0', 09:20:49 INFO - 'HOME': '/home/cltbld', 09:20:49 INFO - 'LANG': 'en_US.UTF-8', 09:20:49 INFO - 'LANGUAGE': 'en_US:en', 09:20:49 INFO - 'LOGNAME': 'cltbld', 09:20:49 INFO - 'MAIL': '/var/mail/cltbld', 09:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:49 INFO - 'MOZ_NO_REMOTE': '1', 09:20:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:49 INFO - 'NO_EM_RESTART': '1', 09:20:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:49 INFO - 'PWD': '/builds/slave/test', 09:20:49 INFO - 'SHELL': '/bin/bash', 09:20:49 INFO - 'SHLVL': '1', 09:20:49 INFO - 'TERM': 'linux', 09:20:49 INFO - 'TMOUT': '86400', 09:20:49 INFO - 'USER': 'cltbld', 09:20:49 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:49 INFO - '_': '/tools/buildbot/bin/python'} 09:20:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:50 INFO - Downloading/unpacking blobuploader==1.2.4 09:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:51 INFO - Downloading blobuploader-1.2.4.tar.gz 09:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:20:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:20:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:52 INFO - Downloading docopt-0.6.1.tar.gz 09:20:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:20:52 INFO - Installing collected packages: blobuploader, requests, docopt 09:20:52 INFO - Running setup.py install for blobuploader 09:20:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:20:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:20:52 INFO - Running setup.py install for requests 09:20:53 INFO - Running setup.py install for docopt 09:20:53 INFO - Successfully installed blobuploader requests docopt 09:20:53 INFO - Cleaning up... 09:20:53 INFO - Return code: 0 09:20:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:53 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 0x7fbff2833f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2384ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, '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-1476547946.232719-1156656160', '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 09:20:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:20:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:20:53 INFO - Using env: {'DISPLAY': ':0', 09:20:53 INFO - 'HOME': '/home/cltbld', 09:20:53 INFO - 'LANG': 'en_US.UTF-8', 09:20:53 INFO - 'LANGUAGE': 'en_US:en', 09:20:53 INFO - 'LOGNAME': 'cltbld', 09:20:53 INFO - 'MAIL': '/var/mail/cltbld', 09:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:53 INFO - 'MOZ_NO_REMOTE': '1', 09:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:53 INFO - 'NO_EM_RESTART': '1', 09:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:53 INFO - 'PWD': '/builds/slave/test', 09:20:53 INFO - 'SHELL': '/bin/bash', 09:20:53 INFO - 'SHLVL': '1', 09:20:53 INFO - 'TERM': 'linux', 09:20:53 INFO - 'TMOUT': '86400', 09:20:53 INFO - 'USER': 'cltbld', 09:20:53 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:53 INFO - '_': '/tools/buildbot/bin/python'} 09:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:53 INFO - Running setup.py (path:/tmp/pip-7DMiEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:53 INFO - Running setup.py (path:/tmp/pip-nxH57r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:53 INFO - Running setup.py (path:/tmp/pip-55rz9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:53 INFO - Running setup.py (path:/tmp/pip-rTBI_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:53 INFO - Running setup.py (path:/tmp/pip-bolXmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:53 INFO - Running setup.py (path:/tmp/pip-cvMYYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:54 INFO - Running setup.py (path:/tmp/pip-tCemMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:54 INFO - Running setup.py (path:/tmp/pip-QKL_6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:54 INFO - Running setup.py (path:/tmp/pip-VsWajw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:54 INFO - Running setup.py (path:/tmp/pip-3ve_Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:54 INFO - Running setup.py (path:/tmp/pip-TvMXr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:54 INFO - Running setup.py (path:/tmp/pip-I0DsTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:54 INFO - Running setup.py (path:/tmp/pip-sW9w7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:54 INFO - Running setup.py (path:/tmp/pip-18_hRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:54 INFO - Running setup.py (path:/tmp/pip-RhrHsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:54 INFO - Running setup.py (path:/tmp/pip-luKwM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:54 INFO - Running setup.py (path:/tmp/pip-l8A3_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:20:54 INFO - Running setup.py install for manifestparser 09:20:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:20:54 INFO - Running setup.py install for mozcrash 09:20:54 INFO - Running setup.py install for mozdebug 09:20:55 INFO - Running setup.py install for mozdevice 09:20:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:20:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:20:55 INFO - Running setup.py install for mozfile 09:20:55 INFO - Running setup.py install for mozhttpd 09:20:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:20:55 INFO - Running setup.py install for mozinfo 09:20:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:20:55 INFO - Running setup.py install for mozInstall 09:20:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:20:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:20:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:20:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:20:55 INFO - Running setup.py install for mozleak 09:20:55 INFO - Running setup.py install for mozlog 09:20:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:20:55 INFO - Running setup.py install for moznetwork 09:20:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:20:55 INFO - Running setup.py install for mozprocess 09:20:56 INFO - Running setup.py install for mozprofile 09:20:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:20:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:20:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:20:56 INFO - Running setup.py install for mozrunner 09:20:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:20:56 INFO - Running setup.py install for mozscreenshot 09:20:56 INFO - Running setup.py install for moztest 09:20:56 INFO - Running setup.py install for mozversion 09:20:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:20:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:20:56 INFO - Cleaning up... 09:20:56 INFO - Return code: 0 09:20:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:56 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 0x7fbff2833f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2384ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, '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-1476547946.232719-1156656160', '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 09:20:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:20:56 INFO - Using env: {'DISPLAY': ':0', 09:20:56 INFO - 'HOME': '/home/cltbld', 09:20:56 INFO - 'LANG': 'en_US.UTF-8', 09:20:56 INFO - 'LANGUAGE': 'en_US:en', 09:20:56 INFO - 'LOGNAME': 'cltbld', 09:20:56 INFO - 'MAIL': '/var/mail/cltbld', 09:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:56 INFO - 'MOZ_NO_REMOTE': '1', 09:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:56 INFO - 'NO_EM_RESTART': '1', 09:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:56 INFO - 'PWD': '/builds/slave/test', 09:20:56 INFO - 'SHELL': '/bin/bash', 09:20:56 INFO - 'SHLVL': '1', 09:20:56 INFO - 'TERM': 'linux', 09:20:56 INFO - 'TMOUT': '86400', 09:20:56 INFO - 'USER': 'cltbld', 09:20:56 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:56 INFO - '_': '/tools/buildbot/bin/python'} 09:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:56 INFO - Running setup.py (path:/tmp/pip-GWwIlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:56 INFO - Running setup.py (path:/tmp/pip-p_Nxow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:56 INFO - Running setup.py (path:/tmp/pip-sTJULN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:57 INFO - Running setup.py (path:/tmp/pip-bIo9k5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:57 INFO - Running setup.py (path:/tmp/pip-j_rRij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:57 INFO - Running setup.py (path:/tmp/pip-Nm8lox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:57 INFO - Running setup.py (path:/tmp/pip-Uj3YBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:57 INFO - Running setup.py (path:/tmp/pip-WQQu53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:57 INFO - Running setup.py (path:/tmp/pip-T2xkDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:57 INFO - Running setup.py (path:/tmp/pip-vIQKtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:57 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)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:57 INFO - Running setup.py (path:/tmp/pip-y48qfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:57 INFO - Running setup.py (path:/tmp/pip-Arpu2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:57 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)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:57 INFO - Running setup.py (path:/tmp/pip-ctFNNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:57 INFO - Running setup.py (path:/tmp/pip-N0gaFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:57 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)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:57 INFO - Running setup.py (path:/tmp/pip-L1ZzG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:57 INFO - Running setup.py (path:/tmp/pip-BlCt2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:57 INFO - Running setup.py (path:/tmp/pip-KuL7kp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:20:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:20: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 09:20: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 09:20: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 09:20: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 09:20: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 09:20: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 09:20:59 INFO - Downloading blessings-1.6.tar.gz 09:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:21:00 INFO - Installing collected packages: blessings 09:21:00 INFO - Running setup.py install for blessings 09:21:00 INFO - Successfully installed blessings 09:21:00 INFO - Cleaning up... 09:21:00 INFO - Return code: 0 09:21:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:21:00 INFO - Reading from file tmpfile_stdout 09:21:00 INFO - Current package versions: 09:21:00 INFO - argparse == 1.2.1 09:21:00 INFO - blessings == 1.6 09:21:00 INFO - blobuploader == 1.2.4 09:21:00 INFO - docopt == 0.6.1 09:21:00 INFO - functools32 == 3.2.3-2 09:21:00 INFO - jsonschema == 2.5.1 09:21:00 INFO - manifestparser == 1.1 09:21:00 INFO - mozInstall == 1.12 09:21:00 INFO - mozcrash == 0.17 09:21:00 INFO - mozdebug == 0.1 09:21:00 INFO - mozdevice == 0.48 09:21:00 INFO - mozfile == 1.2 09:21:00 INFO - mozhttpd == 0.7 09:21:00 INFO - mozinfo == 0.9 09:21:00 INFO - mozleak == 0.1 09:21:00 INFO - mozlog == 3.4 09:21:00 INFO - moznetwork == 0.27 09:21:00 INFO - mozprocess == 0.23 09:21:00 INFO - mozprofile == 0.28 09:21:00 INFO - mozrunner == 6.12 09:21:00 INFO - mozscreenshot == 0.1 09:21:00 INFO - mozsystemmonitor == 0.3 09:21:00 INFO - moztest == 0.7 09:21:00 INFO - mozversion == 1.4 09:21:00 INFO - psutil == 3.1.1 09:21:00 INFO - requests == 1.2.3 09:21:00 INFO - wsgiref == 0.1.2 09:21:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:00 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 0x7fbff2833f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2384ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, '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-1476547946.232719-1156656160', '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 09:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:21:00 INFO - Using env: {'DISPLAY': ':0', 09:21:00 INFO - 'HOME': '/home/cltbld', 09:21:00 INFO - 'LANG': 'en_US.UTF-8', 09:21:00 INFO - 'LANGUAGE': 'en_US:en', 09:21:00 INFO - 'LOGNAME': 'cltbld', 09:21:00 INFO - 'MAIL': '/var/mail/cltbld', 09:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:00 INFO - 'MOZ_NO_REMOTE': '1', 09:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:00 INFO - 'NO_EM_RESTART': '1', 09:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:00 INFO - 'PWD': '/builds/slave/test', 09:21:00 INFO - 'SHELL': '/bin/bash', 09:21:00 INFO - 'SHLVL': '1', 09:21:00 INFO - 'TERM': 'linux', 09:21:00 INFO - 'TMOUT': '86400', 09:21:00 INFO - 'USER': 'cltbld', 09:21:00 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:00 INFO - '_': '/tools/buildbot/bin/python'} 09:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:21:00 INFO - Cleaning up... 09:21:00 INFO - Return code: 0 09:21:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbff2833f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2384ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, '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-1476547946.232719-1156656160', '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 09:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:21:00 INFO - Using env: {'DISPLAY': ':0', 09:21:00 INFO - 'HOME': '/home/cltbld', 09:21:00 INFO - 'LANG': 'en_US.UTF-8', 09:21:00 INFO - 'LANGUAGE': 'en_US:en', 09:21:00 INFO - 'LOGNAME': 'cltbld', 09:21:00 INFO - 'MAIL': '/var/mail/cltbld', 09:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:00 INFO - 'MOZ_NO_REMOTE': '1', 09:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:00 INFO - 'NO_EM_RESTART': '1', 09:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:00 INFO - 'PWD': '/builds/slave/test', 09:21:00 INFO - 'SHELL': '/bin/bash', 09:21:00 INFO - 'SHLVL': '1', 09:21:00 INFO - 'TERM': 'linux', 09:21:00 INFO - 'TMOUT': '86400', 09:21:00 INFO - 'USER': 'cltbld', 09:21:00 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:00 INFO - '_': '/tools/buildbot/bin/python'} 09:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:21:00 INFO - Cleaning up... 09:21:00 INFO - Return code: 0 09:21:00 INFO - Running post-action listener: _resource_record_post_action 09:21:00 INFO - Running post-action listener: _start_resource_monitoring 09:21:00 INFO - Starting resource monitoring. 09:21:00 INFO - [mozharness: 2016-10-15 16:21:00.894324Z] Finished create-virtualenv step (success) 09:21:00 INFO - [mozharness: 2016-10-15 16:21:00.894822Z] Running install step. 09:21:00 INFO - Running pre-action listener: _resource_record_pre_action 09:21:00 INFO - Running main action method: install 09:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:21:01 INFO - Reading from file tmpfile_stdout 09:21:01 INFO - Detecting whether we're running mozinstall >=1.0... 09:21:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:21:01 INFO - Reading from file tmpfile_stdout 09:21:01 INFO - Output received: 09:21:01 INFO - Usage: mozinstall [options] installer 09:21:01 INFO - Options: 09:21:01 INFO - -h, --help show this help message and exit 09:21:01 INFO - -d DEST, --destination=DEST 09:21:01 INFO - Directory to install application into. [default: 09:21:01 INFO - "/builds/slave/test"] 09:21:01 INFO - --app=APP Application being installed. [default: firefox] 09:21:01 INFO - mkdir: /builds/slave/test/build/application 09:21:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:21:17 INFO - Reading from file tmpfile_stdout 09:21:17 INFO - Output received: 09:21:17 INFO - /builds/slave/test/build/application/firefox/firefox 09:21:17 INFO - Running post-action listener: _resource_record_post_action 09:21:17 INFO - [mozharness: 2016-10-15 16:21:17.172457Z] Finished install step (success) 09:21:17 INFO - [mozharness: 2016-10-15 16:21:17.172702Z] Running run-tests step. 09:21:17 INFO - Running pre-action listener: _resource_record_pre_action 09:21:17 INFO - Running main action method: run_tests 09:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:21:17 INFO - Python 2.7.3 09:21:17 INFO - Return code: 0 09:21:17 INFO - grabbing minidump binary from tooltool 09:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:17 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 0x2381f60>, '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 0x2382d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23831d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:21:17 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 09:21:17 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 09:21:17 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 09:21:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:21:17 INFO - Return code: 0 09:21:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:21:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:21:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:21:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:21:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.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_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.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_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:21:17 INFO - Using env: {'DISPLAY': ':0', 09:21:17 INFO - 'HOME': '/home/cltbld', 09:21:17 INFO - 'LANG': 'en_US.UTF-8', 09:21:17 INFO - 'LANGUAGE': 'en_US:en', 09:21:17 INFO - 'LOGNAME': 'cltbld', 09:21:17 INFO - 'MAIL': '/var/mail/cltbld', 09:21:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:21:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:17 INFO - 'MOZ_NO_REMOTE': '1', 09:21:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:21:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:17 INFO - 'NO_EM_RESTART': '1', 09:21:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:17 INFO - 'PWD': '/builds/slave/test', 09:21:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:21:17 INFO - 'SHELL': '/bin/bash', 09:21:17 INFO - 'SHLVL': '1', 09:21:17 INFO - 'TERM': 'linux', 09:21:17 INFO - 'TMOUT': '86400', 09:21:17 INFO - 'USER': 'cltbld', 09:21:17 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1476547946.232719-1156656160', 09:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:17 INFO - '_': '/tools/buildbot/bin/python'} 09:21:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.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_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:21:17 INFO - mozversion application_buildid: 20161015062335 09:21:17 INFO - mozversion application_changeset: df83c59042d9b82f1d27b4afb9066744da22cd98 09:21:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:21:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:21:17 INFO - mozversion application_name: Firefox 09:21:17 INFO - mozversion application_remotingname: firefox-dev 09:21:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:21:17 INFO - mozversion application_vendor: Mozilla 09:21:17 INFO - mozversion application_version: 51.0a2 09:21:17 INFO - mozversion platform_buildid: 20161015062335 09:21:17 INFO - mozversion platform_changeset: df83c59042d9b82f1d27b4afb9066744da22cd98 09:21:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:21:17 INFO - mozversion platform_version: 51.0a2 09:21:17 INFO - using testdate: 1476548477 09:21:17 INFO - actual date: 1476548477 09:21:17 INFO - starting webserver on 'localhost:37648' 09:21:17 INFO - SUITE-START | Running 2 tests 09:21:17 INFO - TEST-START | dromaeo_css 09:21:17 INFO - operating with platform_type : linux_ 09:21:17 INFO - Initialising browser for dromaeo_css test... 09:21:17 INFO - TEST-INFO | started process 3749 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpafENGw/profile http://localhost:37648/getInfo.html) 09:21:21 INFO - PROCESS | 3749 | __metrics Screen width/height:1600/1200 09:21:21 INFO - PROCESS | 3749 | colorDepth:24 09:21:21 INFO - PROCESS | 3749 | Browser inner width/height: 1024/697 09:21:21 INFO - PROCESS | 3749 | __metrics 09:21:22 INFO - PROCESS | 3749 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:21:22 INFO - PROCESS | 3749 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:21:22 INFO - PROCESS | 3749 | console.error: PushService: 09:21:22 INFO - PROCESS | 3749 | stateChangeProcessEnqueue: Error transitioning state 09:21:22 INFO - PROCESS | 3749 | UnknownError 09:21:23 INFO - TEST-INFO | 3749: exit 0 09:21:23 INFO - Browser initialized. 09:21:23 INFO - Running cycle 1/1 for dromaeo_css test... 09:21:23 INFO - TEST-INFO | started process 3841 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpafENGw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:34 INFO - PROCESS | 3841 | RSS: Main: 144367616 09:21:34 INFO - PROCESS | 3841 | 09:22:25 INFO - PROCESS | 3841 | 1476548545990 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:24:34 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:37648/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37648/tests/dromaeo/cssquery-ext.html) 09:24:35 INFO - PROCESS | 3841 | RSS: Main: 225648640 09:24:35 INFO - PROCESS | 3841 | 09:27:38 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:37648/tests/dromaeo/cssquery-ext.html (next: http://localhost:37648/tests/dromaeo/cssquery-jquery.html) 09:27:38 INFO - PROCESS | 3841 | RSS: Main: 232341504 09:27:38 INFO - PROCESS | 3841 | 09:30:40 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:37648/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37648/tests/dromaeo/cssquery-mootools.html) 09:30:41 INFO - PROCESS | 3841 | RSS: Main: 173654016 09:30:41 INFO - PROCESS | 3841 | 09:33:43 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:37648/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37648/tests/dromaeo/cssquery-prototype.html) 09:33:43 INFO - PROCESS | 3841 | RSS: Main: 225820672 09:33:43 INFO - PROCESS | 3841 | 09:36:46 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:37648/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37648/tests/dromaeo/cssquery-yui.html) 09:36:47 INFO - PROCESS | 3841 | RSS: Main: 176193536 09:36:47 INFO - PROCESS | 3841 | 09:39:49 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:37648/tests/dromaeo/cssquery-yui.html (next: http://localhost:37648/tests/dromaeo/cssquery-dojo.html) 09:39:49 INFO - PROCESS | 3841 | RSS: Main: 265543680 09:39:49 INFO - PROCESS | 3841 | 09:39:49 INFO - PROCESS | 3841 | __start_tp_report 09:39:49 INFO - PROCESS | 3841 | _x_x_mozilla_page_load 09:39:49 INFO - PROCESS | 3841 | _x_x_mozilla_page_load_details 09:39:49 INFO - PROCESS | 3841 | |i|pagename|runs| 09:39:49 INFO - PROCESS | 3841 | |0;dojo.html;3108.145260053485;3148.0555833250028;3154.621445426549;3171.3815805917857;3193.856276467559;5462.344518657762;5539.612227144092;5547.528460080893;5563.109902415615;5602.915956260659;5479.726013699319;5488.341399032111;5502.0921547944545;5506.972465137679;5507.807226747064;4923.532264434873;4938.604911607063;4961.503849615027;4999.600031997432;5042;4975.9004819903585;5016.197408414654;5039.949600504003;5045.697258164503;5061.645684802065;5003.449620541737;5005.6746311489815;5020.824271150511;5078.746062696869;5090.541851233388;5068.087744206042;5091.694498330094;5093.949060509384;5164.612654050961;5185.55922746567;5011.624128190364;5069.61977851659;5120.206368012964;5125.820596279113;5134.948650513485;5384.300040994684;5415.729213539307;5422.186671999192;5432.75552600134;5457.454254574511;5883.117532370136;5893.0276504376725;5967.1347654589845;5980.820575382745;5985.8802823943715;5949.553783466257;6027.065804800263;6028.668423236721;6033.607815491979;6036.728347224342;5482.5613950884235;5512.3385193777;5531.225628412026;5572.359178694421;5573.164025396142;6104.572679912363;6131.63210207389;6134.601250918676;6138.600990935664;6145.9692701536205;5705.429457054262;5885;5890.617109887844;5894.616849904743;5899.351071382144;5822.184894114827;5885.411458854081;5927.1405646181065;5940.079287710325;6031.668258245751;5938.524918006549;5966.134910438049;5975.52195824333;5976.342602313742;5977.1333156692035;5770.278715160605;5938.109283607493;5985.9401405986255;5992.910106348409;6019.488343490765;4484.798184081724;4520.434945631796;4537.8411755588395;4543.045960348298;4608.677392582487;4029.174019326031;4058.776767277769;4063.7561746294728;4071.89820254496;4091.0386059276047;5631.1271752878365;5634.4365563443325;5636.97181514098;5641.633293835887;5665.12190610456;5833.387494313036;5880.882382352329;5885.676287804212;5968.910466343008;5977.103434484775;5553.333599967949;5559.277293951841;5570.108782594846;5614.943850561442;5616.719164041863;5153.072446959583;5157.045946499909;5160.8967820643375;5175.327207463006;5192.766325515286;6060.575759696779;6103.328633850273;6104.17593624855;6104.6337219766065;6108.786192483241;5761.769529218785;5816.389279125631;5917.5857689961285;5929.911051334319;6000.489958353589;6142.560166306883;6158.753649853956;6218.129461875432;6222.222222222041;6225.190725205694;5874.17761513397;5932.021216499229;5938.049912014057;5943.851403714942;5984.611000284967;5808.854778630568;5867.090600956859;6042.45617894374;6042.848928776816;6053.576249662658;5793.449622285876;5837;5864.765409383747;5926.051831706906;5968.045112781934;5907.232059832366;5907.3797251288;5913.70431478433;5916.112583112567;5920.822375328747;5658.066419040812;5854.414558544111;5861.6189947653265;5863.882722345615;5867.149263356704;5689.402612725769;5794.594378393472;5840.8247752567495;5846.707664616837;5947.553933454921;5716.857078573069;5728.083506638918;5776.249087618583;5777.884442311214;5784.797532086357 09:39:49 INFO - PROCESS | 3841 | |1;ext.html;603.381533927724;612.4610342898249;666.2438132719362;670.6009924095165;677.4749569083963;34747.478787818174;35315;37459.25081498375;37477.625223747724;37524.06189845255;38691.61308386915;46174.076518469614;46862.531374686245;46902.53097469033;52639.73680131604;9123.270138388929;9228.815423691556;9250.306227032966;9254.953725231364;9258.213051890598;8423.284020858235;9172.8624070639;9261.907380926206;9268.258539316872;9272.67545635903;8575.614097365602;10871.130309575254;11211.607593734221;11214.383208923506;11640.243384180001;11156.330620162817;11187.944060279646;11209.159313051554;11211.103111751041;11225.607103751412;8254.793630159294;8461;11086.2239643225;11146.442677866075;11147.888521114766;47162;48170.75914620439;48235.276470852616;48273.27590086152;48274;3818.770873747584;3865.9806700966315;3941.507311586052;4180.751021789865;4305.182015417061;33712.325753484794;34724.30551388958;34792;35081.12297192565;36091.63908360909;4073.674106071507;4235.280002399569;4287.871363859089;4400.163968845909;4448.977755111237;371605;373729;374425;375343;375819;64166.358336416975;65200.67399662971;65214;65232.34767652263;65249.34750652528;68963.31036689569;69084;69093.30906690868;69206;69209.30790692028;17987;18471.722924156147;18488;18511;18522.907385462986;14166.362513686909;14226.359813808403;14228.14631122136;14229.359678814479;14229.43082276718;17814.643707125786;18204.908975455037;19066.2373505061;19564.217431302874;20537.48656283575;7265.273472652692;7297.817554561178;7339.302766237199;7371.926280737124;7412.814679632945;1013.9087482126644;1059.819830628795;1122.685648018556;1124.1793490751634;1155.3645494977898;34329;34430.139246518534;34801;35105.47341789875;35121.24170635004;1145.6563031090807;1160.976780464386;1177.9587714429954;1200.3458115326994;1254.2411840836346;18022.5494362642;18090.819091809175;18094.3666971658;18181.636367272844;18290.359837405893;600986;604802;605482;605599;605969;62508.37491625117;62604.37395625986;63016.369836301965;63174.368256317764;63404.36595633985;1399.9440022399194;1437.9065360751727;1439.5249567642652;1450.7968884356198;1454.8545145485366;31907.202319942186;32871.506927396586;33884.32231355408;34184.316313674084;34476.13809654779;54987;55253;55276.72361638327;55360.169597455264;55450.72274638601;471447;472564;472677;475145;475772;30901.227469312547;31738.682613173572;31981.68018319787;31982.68017319797;32001.359972799946;40685;49829;51673;55654.44345556493;56054;43117;44480.77759611181;44798.10403791971;45025;46176.07647847092;43874.34188487239;44944.77527612341;45016.32475512935;45365.319520207886;45900.311495328264;40230.798846005586;46551;47429.051418972114;48630.513694862595;48788;42649.080791365086;44733.32900006568;47684.04631907273;48005;49851.75074124606;33638.663613363555;36373.818130909174;36889.63110368862;37018;37388.25223495569 09:39:49 INFO - PROCESS | 3841 | |2;jquery.html;397.81203381402287;410.49099117094795;424.44185895547355;428.26552462526763;445.55889669227474;15077.472288469906;20633.587328253445;20641.07115179817;21556.245531406403;21801.67297490539;20239.291624793135;20638.277660281892;20664.793352066474;20700;21040;5897.380775018604;5965.88068238635;5994.400559944003;6000;6008;5942.524598032147;6022.969885150568;6036.51707863372;6046.697665116749;6046.939530604682;5805.216295800055;6050.606710563821;6095.695215239242;6173.691315434233;6178.474829639486;5965.791197308116;6028;6114.785982490614;6154.538409619274;6156.969215153918;5931;5942.405759424067;5942.673152976585;5944.167816505693;5948.643081415129;14440.277986100653;14957.850421495754;14964.476243331432;14964.625884352869;15072.397104115822;2470.938226544333;2499.2877030046347;2645.0610033438043;2715.3347429879805;2736.3022429280604;10681.679549613524;10927.781444371147;10990.780184396268;11027.889721102847;11053;1979.7822239553782;1980.2178139634764;2079.95840083199;2086.7182930304516;2110.387987483628;69702;72313;72459;86358;91293.54353228258;8818.647254109892;9821.36161149523;10231.539580718885;10729.302595331279;11047.613333533449;14756.48352307664;14789.408423663148;14861.554153375417;14905.70188596222;14948;8032.759017229492;8085.312748416332;8708.17272359125;8760.4305720128;8811.383203175716;8258.545779982158;8274.669013239403;8335.95832020836;8598.259413224865;8620.655173793104;4423.955760442419;4490.842820501332;4526.388937493396;4534.206513860061;4609.654275929319;1898.221729091066;1913.158210387425;1915.3104882242383;1921.1834970137636;2055.722477465553;918.4902379179485;985.9359141655913;987.8221920054316;990.9950450247702;1099.961501347449;20074.996250187432;20493.2827351042;20716.378508644764;21093;21303.467413314793;1041.6302212714377;1044.2220545693356;1060.5174645536263;1066.7919755647729;1072.3565860483648;10802;11504.663647542286;11625.76748465026;11635.53457861676;11666.88333116675;535541;539379;540014;543657;547161;99393;103818.48090759497;106215;107391;108964.45517772519;903.1645727701849;933.2906990687127;981.0728861226073;981.7840075183448;990.9653162139291;18427.90786046061;18803.529911752317;19031.143598538376;19087.236510539424;19093.61812763765;44130;44677.329840051774;44742.10515789601;45464.54535454603;46256.76871615755;62498.37501625108;63458.365416345245;63545.36454635394;63808;64880.67559662172;8684.956575217084;9048.502332371783;9342.392744471588;9385.38994965325;9465.66870159541;7886.0536735592095;12212.08409369283;12577.182483138567;13817.86182138206;14417.351219194952;12226.449809758404;12343.259404435763;12761.808572871229;12974.28641424693;13070.934645327094;11489.885101148882;11671.416429178676;12073.637790866289;12179.147459677737;12528.123031387715;11919.76160476803;12134.089943254468;12182.634520964386;12244;12658.683532911382;8702.129787021247;8954.597043133204;9376.95311523438;9489.715308540755;9599.520023998912;12155.149139560146;12179.573714919936;12274.631761047183;12286.938565307117;12313.753724925631 09:39:49 INFO - PROCESS | 3841 | |3;mootools.html;1525.9847401525985;1539.9076055436672;1695.448979081798;1800.2349001674286;1812.7009043507821;9019.54902254887;9699.224062075034;9704.175145112666;9710.70867873964;9720.319577629569;8095.554744489051;8321.251087402132;9018.549072546379;9241.722748317545;9483.668071617478;2559.29619354678;2582.406046609281;2595.6625638666947;2603.0889188783867;2604.0495219245026;2514.446821699223;2581.793456523483;2587.883545240459;2589.8187126901125;2600.0769726747017;2552.8723563821827;2563.8333508321994;2582.7288134745863;2583.108827454525;2584.4572639745675;2489.2158969924417;2506.1228570000444;2508.285138735461;2524.734902835194;2534.9239522814346;2473.4063824682125;2478.2317481580767;2521.5839386501207;2523.7854782343525;2525.936851578725;9519.381240219363;9612.711618651452;9632.325737198398;9633.08485693858;9642.276829237835;5536.806211782568;5661.207430959669;5674.716264186815;5678.5741069419955;5682.914756278659;3474.3919814032697;3527.7883327000463;3640.9453858192146;3656.3784156693428;3659.4693769403552;1239.4670291774532;1294.0488740775522;1297.6690943809267;1303.4786085565754;1325.1916331038155;12455.75088498234;12546.560870369492;12548.1216314858;12563.560275390317;12584.05619578526;4785.808567657325;4839.41926968766;4945.604351651859;4955.380577427822;5013.097642424399;10813.567457301777;10827.783444331228;10834.133269338434;10844.186685998582;10863.293885897392;5507.944920550824;5534.640248383842;5540.279763630702;5543;5546.805861794898;4618.1918164321105;4758.381410416624;4844.563989240914;4864;5048.040872234264;4277.973286411239;4290.50659174199;4344.587264209895;4392.472903251631;4392.890177745582;1422.50923431416;1453.382312517197;1465.0916431812345;1467.83119941206;1475.2107622421952;910.2308549275851;1000.9949950250202;1005.9195264378832;1020.2705065877784;1025.2669341420772;6353.936460635427;6362.586431881913;6374.872502549923;6489.480841532666;6524.608523488511;505.31277462650763;509.5210502127988;525.4652350217598;529.8569386265764;531.5588061908607;4323.178596066738;4334.783260836945;4337.306031035019;4368.847090351823;4429.800658970354;11145.832812507819;11560.884391155982;12027.21823081497;12035.338056406807;12085.15403921717;11487.483063262172;11923.821142682868;11929.28424294545;11930.463129159209;11944.522219111312;8654.437461564978;8831.867521987175;8832.38173327861;8859.822803544022;8874.511901845332;6471.967640161769;6547.770828020996;6743.5953842769595;6771.221309549464;6883.931160688329;11055.28140670886;11068.28056176346;11078.667639970814;11096;11099.112071034137;10797;10950.45247737594;11152.553897843994;11241.269317494336;11479.827802583135;7217.422606191597;7417.332440080203;7427.4800763946005;8436.198561136927;8592.570371481526;8000.799980000314;9156.725298241063;9240.260779137787;9446.858297125686;9488.620455181715;8246.463979841292;8262.710805121791;8289.917100829156;8334.708285209988;8450.873236901329;8007.83984320322;8100.351971842357;8197.590120494071;8199.262066414052;8243.670253189804;7195.964020179866;7361.190269070505;7427.925720742724;7753.961230193813;7833.451658383859;5445.564354851682;5514.09017512106;5534.970587354118;5557.3331200256225;5718.056520674042;5415.6479828811;5593.8321850344555;5683.829485115453;5697.943020569741;5777.056127045738 09:39:49 INFO - PROCESS | 3841 | |4;prototype.html;345.05971228402603;345.3059350705082;351.63956944132246;356.42615389223346;357.4870061461801;5472.91790623141;5489.752961116749;5494.890102197958;5509.311336082989;5532.419095994923;5136.357955255593;5243.318368612075;5290.523852853243;5333.733313334327;5459.262999495067;4224.197402493516;4237.660987121023;4245.639120674747;4247.171801498716;4253.340732186501;4249.660027197817;4259.318509038554;4261;4289.721168124078;4289.806958686851;3969.8412063517426;4016.819243134066;4221.641160501345;4228.386883901832;4236.343366778139;4209.410682504452;4223.68322375823;4224.662027037845;4227.217964676544;4234.1955028544635;3760.191558814868;3762.9059273518105;3764.5294338207723;3769.1142581493327;3769.4157405602177;5118.334616499831;5264.789408423697;5285;5314.282571852789;5319.946800531984;4869.488703686133;4869.589553171168;4870.65905386623;4883.389576302962;4887.584555312803;5613.859653508654;5638.4361563843695;5641.294838145232;5647.486868343589;5672.4894759471845;3906.8437262509465;4099.85650502234;4127.711060225785;4168.6039826216465;4182.2890108681595;5850.986741827919;5870.765169393219;5871.2073870027425;5877.500412464946;5897.14491398749;4217.97891010543;4220.91558168841;4224.683148763794;4226.619604235633;4240.597143271384;4178.832846686159;4225.9577404226175;4249.447571815706;4267.4238977737605;4269.167512335065;4815.3017812417;4830.637702172351;4853.80584776605;4857.344258525124;4908.018396320679;5691.174779656926;5729.828105156852;5782.768689252467;5788.0449725795615;5796.797112101056;1397.3781667158016;1452.803871477337;1466.5893549806071;1475.6055527526385;1484.960527630663;949.7055912667096;966.6326795817685;992.4045572656348;1007.8286691262502;1012.0688966151158;5529.502344788908;5551.9722401387735;5565.7217139143695;5581.944180558143;5590.664560126405;5846.356900740915;5856.360219138648;5858.238429004202;5874.676892770854;5879.941200587939;5373.596980226499;5622.606417550732;5625.046850403571;5653.745581448845;5658.397137316881;3097.3650401667605;3129.984350078235;3137.576427182347;3139.952900706491;3146.559481672522;5811.709414529257;6043.8186854394435;6088.482478989247;6116.847078823065;6168.383161683796;5985.850353741104;6019;6020.57855950088;6030.18592490017;6031.60794548353;5780.682062486603;6068.544859135583;6092.847678808065;6093.146959425684;6111.786087487006;5896.6756828374855;5897.997340452048;5939.673317967553;5955.702214889325;5960.612560183574;5850.619709718854;5870.354261031198;5879.2944846618675;5890.352061273171;5890.4993075589055;5792.594518383673;6006.639601623741;6033.064874944391;6074.507158383859;6112;4261.360795880642;4271.786410679516;4273.850415235452;4289;4297.441332626739;5713.22871412354;5919.822405327847;5921.437463440966;5928.584999050025;5937.574982004201;5729.083346664514;5737.397573254748;5739.655620662774;5760.539156867524;5768.365479797303;5698.601097923106;5713.628614140068;5723.656580605177;5730.5129064030025;5749.511291540266;5319.654222475527;5335.359756829205;5702.600817942704;5729.71351432435;5730.426957304237;5720.19917211599;5752.28096487939;5756.539476841759;5760.913586296125;5778.930897783924;5523.917141242965;5628;5636.577256705681;5652.0674088774895;5661.264035675335 09:39:49 INFO - PROCESS | 3841 | |5;yui.html;828.449081360895;853.50923219149;853.7694822397957;879.982400351993;921.9539023048848;15798.684026319466;17171.742423863652;17808.37670681525;17809.198586063627;17850.464486065437;16301.51095467138;16497.505074847744;16504.092274924875;16519;16549.08980006102;711.9181294151161;731.3856360657047;847.9533625650587;849.0321034021206;869.9304055675531;856.6359297298624;863.1282404771194;864.2006144316514;867.6789587852503;869.4305230074311;917.1607978699482;920.2913756407562;920.3373571028849;921.2906062332;921.6866265469739;750.5984298400357;775.5230533222056;890.0387581412124;906.6554709210524;915.1260546178416;670.0518765946181;670.1723371636074;670.3162773970523;671.1576970901536;672.1430176524921;16330.836691633169;16365.67268654632;16412.261448234858;16469.670606587923;16482.34070637173;15350.616234594112;15480.690386192213;15586.844131558766;15699.450519231772;15988.360465581363;14380.56858294253;14427.422903083863;14505.347259373248;14862.479813206486;15377.461788837449;4823.782929768134;4825.517448255181;4881.219004959189;4968.1554135797005;4991.401031876198;85137.57431212867;85284;85614;85665;85689.14310856936;12339.211977779198;12740.554080607135;12886.226826390259;13081.672958176121;13215.33923303831;23197.188098416475;23338;23461.41346466352;23470.64794028091;23491;8371.121032291643;8477.23704866565;8685.826283474295;8708.129187081242;8737.257333126629;4921.680090794087;4972.0801651694555;5059.190529515334;5209.557187639018;5234;3717.4609681596016;3824.732268741217;3824.9426258606145;3832.9233415331537;3834.0989867381145;572.8739677270994;651.2705769538147;655.4330504114002;656.8719099775593;658.035977293258;650.3593959949446;666.4934649666288;758.4235980654743;784.4704824243613;828.1180542721982;10378.688639340831;10653.9467302663;10963.890361096446;11052.889471105185;11372.658820235407;659.3340725866909;660.244020596424;668.9096971908828;683.6684208159039;735.8491509240592;4918.778654960535;5107.463716309734;5197.506236907488;5211.973940130275;5219.295395121686;121434;122326.3883680576;124942;131393;135696.32151839376;64726.352736472036;65927;73152.63423682954;86254.56872715722;110820.44589777161;52304.21543676848;53624.731876341146;55636.443635563126;77293.61353193199;93254;10071;10389.339582361095;10497.580096796042;10776.503982968585;10829.512671929624;54629.45370546244;54969;54989.175162373394;56016;56793.43206568047;72980.63509682417;80742.59628701818;83160.58419707863;83441.16558834334;83507.5824620873;13013.544525941546;13558.254296013814;13773.311334433038;13845.376958037114;14140.43438262458;14903.925480372962;15231.31459084366;15580.532584022498;16006.279717412512;16083.195840207709;13008.154469959421;13292.734145317232;14500.274986250435;14558.4904528341;14959.476418325306;13693.178409295475;13701.177929324273;14068;14587.85412145865;15177.696446070795;14186.716265674835;14793.334299956743;14884.851151488347;14993.925030374778;15231.92384038117;12318.938405307916;12427.627171184879;12570.308633025272;12591.992640588547;13263.137896036727;12570.778034093351;12684.746305074032;13035.245129628787;13111.705451545751;13321.600351989457 09:39:49 INFO - PROCESS | 3841 | __end_tp_report 09:39:49 INFO - PROCESS | 3841 | __start_cc_report 09:39:49 INFO - PROCESS | 3841 | _x_x_mozilla_cycle_collect,1021 09:39:49 INFO - PROCESS | 3841 | __end_cc_report 09:39:49 INFO - PROCESS | 3841 | __startTimestamp1476549589942__endTimestamp 09:39:49 INFO - PROCESS | 3841 | 09:39:49 INFO - PROCESS | 3841 | ------- Summary: start ------- 09:39:49 INFO - PROCESS | 3841 | Number of tests: 6 09:39:49 INFO - PROCESS | 3841 | 09:39:49 INFO - PROCESS | 3841 | [#0] dojo.html Cycles:165 Average:5525.67 Median:5776.25 stddev:639.93 (11.1%) stddev-sans-first:613.14 09:39:49 INFO - PROCESS | 3841 | Values: 3108.1 3148.1 3154.6 3171.4 3193.9 5462.3 5539.6 5547.5 5563.1 5602.9 5479.7 5488.3 5502.1 5507.0 5507.8 4923.5 4938.6 4961.5 4999.6 5042.0 4975.9 5016.2 5039.9 5045.7 5061.6 5003.4 5005.7 5020.8 5078.7 5090.5 5068.1 5091.7 5093.9 5164.6 5185.6 5011.6 5069.6 5120.2 5125.8 5134.9 5384.3 5415.7 5422.2 5432.8 5457.5 5883.1 5893.0 5967.1 5980.8 5985.9 5949.6 6027.1 6028.7 6033.6 6036.7 5482.6 5512.3 5531.2 5572.4 5573.2 6104.6 6131.6 6134.6 6138.6 6146.0 5705.4 5885.0 5890.6 5894.6 5899.4 5822.2 5885.4 5927.1 5940.1 6031.7 5938.5 5966.1 5975.5 5976.3 5977.1 5770.3 5938.1 5985.9 5992.9 6019.5 4484.8 4520.4 4537.8 4543.0 4608.7 4029.2 4058.8 4063.8 4071.9 4091.0 5631.1 5634.4 5637.0 5641.6 5665.1 5833.4 5880.9 5885.7 5968.9 5977.1 5553.3 5559.3 5570.1 5614.9 5616.7 5153.1 5157.0 5160.9 5175.3 5192.8 6060.6 6103.3 6104.2 6104.6 6108.8 5761.8 5816.4 5917.6 5929.9 6000.5 6142.6 6158.8 6218.1 6222.2 6225.2 5874.2 5932.0 5938.0 5943.9 5984.6 5808.9 5867.1 6042.5 6042.8 6053.6 5793.4 5837.0 5864.8 5926.1 5968.0 5907.2 5907.4 5913.7 5916.1 5920.8 5658.1 5854.4 5861.6 5863.9 5867.1 5689.4 5794.6 5840.8 5846.7 5947.6 5716.9 5728.1 5776.2 5777.9 5784.8 09:39:49 INFO - PROCESS | 3841 | 09:39:49 INFO - PROCESS | 3841 | [#1] ext.html Cycles:180 Average:66108.97 Median:23442.18 stddev:130844.69 (558.2%) stddev-sans-first:131119.31 09:39:49 INFO - PROCESS | 3841 | Values: 603.4 612.5 666.2 670.6 677.5 34747.5 35315.0 37459.3 37477.6 37524.1 38691.6 46174.1 46862.5 46902.5 52639.7 9123.3 9228.8 9250.3 9255.0 9258.2 8423.3 9172.9 9261.9 9268.3 9272.7 8575.6 10871.1 11211.6 11214.4 11640.2 11156.3 11187.9 11209.2 11211.1 11225.6 8254.8 8461.0 11086.2 11146.4 11147.9 47162.0 48170.8 48235.3 48273.3 48274.0 3818.8 3866.0 3941.5 4180.8 4305.2 33712.3 34724.3 34792.0 35081.1 36091.6 4073.7 4235.3 4287.9 4400.2 4449.0 371605.0 373729.0 374425.0 375343.0 375819.0 64166.4 65200.7 65214.0 65232.3 65249.3 68963.3 69084.0 69093.3 69206.0 69209.3 17987.0 18471.7 18488.0 18511.0 18522.9 14166.4 14226.4 14228.1 14229.4 14229.4 17814.6 18204.9 19066.2 19564.2 20537.5 7265.3 7297.8 7339.3 7371.9 7412.8 1013.9 1059.8 1122.7 1124.2 1155.4 34329.0 34430.1 34801.0 35105.5 35121.2 1145.7 1161.0 1178.0 1200.3 1254.2 18022.5 18090.8 18094.4 18181.6 18290.4 600986.0 604802.0 605482.0 605599.0 605969.0 62508.4 62604.4 63016.4 63174.4 63404.4 1399.9 1437.9 1439.5 1450.8 1454.9 31907.2 32871.5 33884.3 34184.3 34476.1 54987.0 55253.0 55276.7 55360.2 55450.7 471447.0 472564.0 472677.0 475145.0 475772.0 30901.2 31738.7 31981.7 31982.7 32001.4 40685.0 49829.0 51673.0 55654.4 56054.0 43117.0 44480.8 44798.1 45025.0 46176.1 43874.3 44944.8 45016.3 45365.3 45900.3 40230.8 46551.0 47429.1 48630.5 48788.0 42649.1 44733.3 47684.0 48005.0 49851.8 33638.7 36373.8 36889.6 37018.0 37388.3 09:39:49 INFO - PROCESS | 3841 | 09:39:49 INFO - PROCESS | 3841 | [#2] jquery.html Cycles:180 Average:31031.83 Median:2725.82 stddev:89266.33 (3274.8%) stddev-sans-first:89487.11 09:39:49 INFO - PROCESS | 3841 | Values: 397.8 410.5 424.4 428.3 445.6 15077.5 20633.6 20641.1 21556.2 21801.7 20239.3 20638.3 20664.8 20700.0 21040.0 5897.4 5965.9 5994.4 6000.0 6008.0 5942.5 6023.0 6036.5 6046.7 6046.9 5805.2 6050.6 6095.7 6173.7 6178.5 5965.8 6028.0 6114.8 6154.5 6157.0 5931.0 5942.4 5942.7 5944.2 5948.6 14440.3 14957.9 14964.5 14964.6 15072.4 2470.9 2499.3 2645.1 2715.3 2736.3 10681.7 10927.8 10990.8 11027.9 11053.0 1979.8 1980.2 2080.0 2086.7 2110.4 69702.0 72313.0 72459.0 86358.0 91293.5 8818.6 9821.4 10231.5 10729.3 11047.6 14756.5 14789.4 14861.6 14905.7 14948.0 8032.8 8085.3 8708.2 8760.4 8811.4 8258.5 8274.7 8336.0 8598.3 8620.7 4424.0 4490.8 4526.4 4534.2 4609.7 1898.2 1913.2 1915.3 1921.2 2055.7 918.5 985.9 987.8 991.0 1100.0 20075.0 20493.3 20716.4 21093.0 21303.5 1041.6 1044.2 1060.5 1066.8 1072.4 10802.0 11504.7 11625.8 11635.5 11666.9 535541.0 539379.0 540014.0 543657.0 547161.0 99393.0 103818.5 106215.0 107391.0 108964.5 903.2 933.3 981.1 981.8 991.0 18427.9 18803.5 19031.1 19087.2 19093.6 44130.0 44677.3 44742.1 45464.5 46256.8 62498.4 63458.4 63545.4 63808.0 64880.7 8685.0 9048.5 9342.4 9385.4 9465.7 7886.1 12212.1 12577.2 13817.9 14417.4 12226.4 12343.3 12761.8 12974.3 13070.9 11489.9 11671.4 12073.6 12179.1 12528.1 11919.8 12134.1 12182.6 12244.0 12658.7 8702.1 8954.6 9377.0 9489.7 9599.5 12155.1 12179.6 12274.6 12286.9 12313.8 09:39:49 INFO - PROCESS | 3841 | 09:39:49 INFO - PROCESS | 3841 | [#3] mootools.html Cycles:180 Average:6196.41 Median:4772.09 stddev:3520.11 (73.8%) stddev-sans-first:3512.49 09:39:49 INFO - PROCESS | 3841 | Values: 1526.0 1539.9 1695.4 1800.2 1812.7 9019.5 9699.2 9704.2 9710.7 9720.3 8095.6 8321.3 9018.5 9241.7 9483.7 2559.3 2582.4 2595.7 2603.1 2604.0 2514.4 2581.8 2587.9 2589.8 2600.1 2552.9 2563.8 2582.7 2583.1 2584.5 2489.2 2506.1 2508.3 2524.7 2534.9 2473.4 2478.2 2521.6 2523.8 2525.9 9519.4 9612.7 9632.3 9633.1 9642.3 5536.8 5661.2 5674.7 5678.6 5682.9 3474.4 3527.8 3640.9 3656.4 3659.5 1239.5 1294.0 1297.7 1303.5 1325.2 12455.8 12546.6 12548.1 12563.6 12584.1 4785.8 4839.4 4945.6 4955.4 5013.1 10813.6 10827.8 10834.1 10844.2 10863.3 5507.9 5534.6 5540.3 5543.0 5546.8 4618.2 4758.4 4844.6 4864.0 5048.0 4278.0 4290.5 4344.6 4392.5 4392.9 1422.5 1453.4 1465.1 1467.8 1475.2 910.2 1001.0 1005.9 1020.3 1025.3 6353.9 6362.6 6374.9 6489.5 6524.6 505.3 509.5 525.5 529.9 531.6 4323.2 4334.8 4337.3 4368.8 4429.8 11145.8 11560.9 12027.2 12035.3 12085.2 11487.5 11923.8 11929.3 11930.5 11944.5 8654.4 8831.9 8832.4 8859.8 8874.5 6472.0 6547.8 6743.6 6771.2 6883.9 11055.3 11068.3 11078.7 11096.0 11099.1 10797.0 10950.5 11152.6 11241.3 11479.8 7217.4 7417.3 7427.5 8436.2 8592.6 8000.8 9156.7 9240.3 9446.9 9488.6 8246.5 8262.7 8289.9 8334.7 8450.9 8007.8 8100.4 8197.6 8199.3 8243.7 7196.0 7361.2 7427.9 7754.0 7833.5 5445.6 5514.1 5535.0 5557.3 5718.1 5415.6 5593.8 5683.8 5697.9 5777.1 09:39:49 INFO - PROCESS | 3841 | 09:39:49 INFO - PROCESS | 3841 | [#4] prototype.html Cycles:180 Average:4823.96 Median:5530.96 stddev:1416.99 (25.6%) stddev-sans-first:1380.51 09:39:49 INFO - PROCESS | 3841 | Values: 345.1 345.3 351.6 356.4 357.5 5472.9 5489.8 5494.9 5509.3 5532.4 5136.4 5243.3 5290.5 5333.7 5459.3 4224.2 4237.7 4245.6 4247.2 4253.3 4249.7 4259.3 4261.0 4289.7 4289.8 3969.8 4016.8 4221.6 4228.4 4236.3 4209.4 4223.7 4224.7 4227.2 4234.2 3760.2 3762.9 3764.5 3769.1 3769.4 5118.3 5264.8 5285.0 5314.3 5319.9 4869.5 4869.6 4870.7 4883.4 4887.6 5613.9 5638.4 5641.3 5647.5 5672.5 3906.8 4099.9 4127.7 4168.6 4182.3 5851.0 5870.8 5871.2 5877.5 5897.1 4218.0 4220.9 4224.7 4226.6 4240.6 4178.8 4226.0 4249.4 4267.4 4269.2 4815.3 4830.6 4853.8 4857.3 4908.0 5691.2 5729.8 5782.8 5788.0 5796.8 1397.4 1452.8 1466.6 1475.6 1485.0 949.7 966.6 992.4 1007.8 1012.1 5529.5 5552.0 5565.7 5581.9 5590.7 5846.4 5856.4 5858.2 5874.7 5879.9 5373.6 5622.6 5625.0 5653.7 5658.4 3097.4 3130.0 3137.6 3140.0 3146.6 5811.7 6043.8 6088.5 6116.8 6168.4 5985.9 6019.0 6020.6 6030.2 6031.6 5780.7 6068.5 6092.8 6093.1 6111.8 5896.7 5898.0 5939.7 5955.7 5960.6 5850.6 5870.4 5879.3 5890.4 5890.5 5792.6 6006.6 6033.1 6074.5 6112.0 4261.4 4271.8 4273.9 4289.0 4297.4 5713.2 5919.8 5921.4 5928.6 5937.6 5729.1 5737.4 5739.7 5760.5 5768.4 5698.6 5713.6 5723.7 5730.5 5749.5 5319.7 5335.4 5702.6 5729.7 5730.4 5720.2 5752.3 5756.5 5760.9 5778.9 5523.9 5628.0 5636.6 5652.1 5661.3 09:39:49 INFO - PROCESS | 3841 | 09:39:49 INFO - PROCESS | 3841 | [#5] yui.html Cycles:180 Average:21280.66 Median:4328.94 stddev:29901.49 (690.7%) stddev-sans-first:29945.93 09:39:49 INFO - PROCESS | 3841 | Values: 828.4 853.5 853.8 880.0 922.0 15798.7 17171.7 17808.4 17809.2 17850.5 16301.5 16497.5 16504.1 16519.0 16549.1 711.9 731.4 848.0 849.0 869.9 856.6 863.1 864.2 867.7 869.4 917.2 920.3 920.3 921.3 921.7 750.6 775.5 890.0 906.7 915.1 670.1 670.2 670.3 671.2 672.1 16330.8 16365.7 16412.3 16469.7 16482.3 15350.6 15480.7 15586.8 15699.5 15988.4 14380.6 14427.4 14505.3 14862.5 15377.5 4823.8 4825.5 4881.2 4968.2 4991.4 85137.6 85284.0 85614.0 85665.0 85689.1 12339.2 12740.6 12886.2 13081.7 13215.3 23197.2 23338.0 23461.4 23470.6 23491.0 8371.1 8477.2 8685.8 8708.1 8737.3 4921.7 4972.1 5059.2 5209.6 5234.0 3717.5 3824.7 3824.9 3832.9 3834.1 572.9 651.3 655.4 656.9 658.0 650.4 666.5 758.4 784.5 828.1 10378.7 10653.9 10963.9 11052.9 11372.7 659.3 660.2 668.9 683.7 735.8 4918.8 5107.5 5197.5 5212.0 5219.3 121434.0 122326.4 124942.0 131393.0 135696.3 64726.4 65927.0 73152.6 86254.6 110820.4 52304.2 53624.7 55636.4 77293.6 93254.0 10071.0 10389.3 10497.6 10776.5 10829.5 54629.5 54969.0 54989.2 56016.0 56793.4 72980.6 80742.6 83160.6 83441.2 83507.6 13013.5 13558.3 13773.3 13845.4 14140.4 14903.9 15231.3 15580.5 16006.3 16083.2 13008.2 13292.7 14500.3 14558.5 14959.5 13693.2 13701.2 14068.0 14587.9 15177.7 14186.7 14793.3 14884.9 14993.9 15231.9 12318.9 12427.6 12570.3 12592.0 13263.1 12570.8 12684.7 13035.2 13111.7 13321.6 09:39:49 INFO - PROCESS | 3841 | -------- Summary: end -------- 09:39:49 INFO - PROCESS | 3841 | 09:39:50 INFO - TEST-INFO | 3841: exit 0 09:39:50 INFO - TEST-OK | dromaeo_css | took 1113204ms 09:39:50 INFO - TEST-START | kraken 09:39:50 INFO - operating with platform_type : linux_ 09:39:50 INFO - Initialising browser for kraken test... 09:39:50 INFO - TEST-INFO | started process 4157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRkhFe7/profile http://localhost:37648/getInfo.html) 09:39:50 INFO - PROCESS | 4157 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 09:39:50 INFO - TEST-INFO | 4157: exit 127 09:39:50 INFO - Could not find __metrics(.*)__metrics in browser output 09:39:50 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! 09:39:50 INFO - TEST-UNEXPECTED-ERROR | kraken | browser failed to close after being initialized 09:39:50 ERROR - Traceback (most recent call last): 09:39:50 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 09:39:50 INFO - talos_results.add(mytest.runTest(browser_config, test)) 09:39:50 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 69, in runTest 09:39:50 INFO - with FFSetup(browser_config, test_config) as setup: 09:39:50 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 165, in __enter__ 09:39:50 INFO - self._run_profile() 09:39:50 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 141, in _run_profile 09:39:50 INFO - raise TalosError("browser failed to close after being initialized") 09:39:50 INFO - TalosError: browser failed to close after being initialized 09:39:50 INFO - TEST-INFO took 280ms 09:39:50 INFO - SUITE-END | took 1113s 09:39:51 ERROR - Return code: 2 09:39:51 WARNING - setting return code to 2 09:39:51 ERROR - # TBPL FAILURE # 09:39:51 INFO - Running post-action listener: _resource_record_post_action 09:39:51 INFO - [mozharness: 2016-10-15 16:39:51.231231Z] Finished run-tests step (success) 09:39:51 INFO - Running post-run listener: _resource_record_post_run 09:39:51 INFO - Total resource usage - Wall time: 1130s; CPU: 13.0%; Read bytes: 2134016; Write bytes: 295624704; Read time: 316; Write time: 317880 09:39:51 INFO - TinderboxPrint: CPU usage
13.0% 09:39:51 INFO - TinderboxPrint: I/O read bytes / time
2,134,016 / 316 09:39:51 INFO - TinderboxPrint: I/O write bytes / time
295,624,704 / 317,880 09:39:51 INFO - TinderboxPrint: CPU idle
7,842.8 (86.9%) 09:39:51 INFO - TinderboxPrint: CPU user
1,144.1 (12.7%) 09:39:51 INFO - TinderboxPrint: Swap in / out
0 / 0 09:39:51 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110813184; Read time: 0; Write time: 112748 09:39:51 INFO - run-tests - Wall time: 1114s; CPU: 13.0%; Read bytes: 262144; Write bytes: 184811520; Read time: 12; Write time: 205132 09:39:51 INFO - Running post-run listener: _upload_blobber_files 09:39:51 INFO - Blob upload gear active. 09:39:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:39:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:39:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:39:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:39:52 INFO - (blobuploader) - INFO - Open directory for files ... 09:39:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:39:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:39:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:55 INFO - (blobuploader) - INFO - Done attempting. 09:39:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:39:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:39:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:56 INFO - (blobuploader) - INFO - Done attempting. 09:39:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:39:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:39:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:57 INFO - (blobuploader) - INFO - Done attempting. 09:39:57 INFO - (blobuploader) - INFO - Iteration through files over. 09:39:57 INFO - Return code: 0 09:39:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:39:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:39:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6baef2d369931e088cb3fdb25c62b41bce03ab80f510d7d43cc817f589969077f214a678f0c41e12e7b26dad959470e2ffb811443d2bb1931b71ed55b2dfb2ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/affb776fbfb1f91142ae1f6a0cc49c426fa0de420a097bf2c4e7188cb68d3cb4db93ee057b9b250255be0966910d43c8a3cdfe667e4765ce97681168e5829cd6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/542c4df2d556deda3e9a44ec0fc900482d175214ea7afc596b7fec5d94dee95ded3019bbea32581d31e765370fdca2bf6e7db02049325ad828e713f7a7d2e7f9"} 09:39:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:39:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:39:57 INFO - Contents: 09:39:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6baef2d369931e088cb3fdb25c62b41bce03ab80f510d7d43cc817f589969077f214a678f0c41e12e7b26dad959470e2ffb811443d2bb1931b71ed55b2dfb2ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/affb776fbfb1f91142ae1f6a0cc49c426fa0de420a097bf2c4e7188cb68d3cb4db93ee057b9b250255be0966910d43c8a3cdfe667e4765ce97681168e5829cd6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/542c4df2d556deda3e9a44ec0fc900482d175214ea7afc596b7fec5d94dee95ded3019bbea32581d31e765370fdca2bf6e7db02049325ad828e713f7a7d2e7f9"} 09:39:57 INFO - Running post-run listener: copy_logs_to_upload_dir 09:39:57 INFO - Copying logs to upload dir... 09:39:57 INFO - mkdir: /builds/slave/test/build/upload/logs 09:39:57 INFO - Copying logs to upload dir... 09:39:57 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=1196.314826 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 56 secs) (at 2016-10-15 09:39:56.346803) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 09:39:56.350538) ========= 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-1476547946.232719-1156656160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6baef2d369931e088cb3fdb25c62b41bce03ab80f510d7d43cc817f589969077f214a678f0c41e12e7b26dad959470e2ffb811443d2bb1931b71ed55b2dfb2ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/affb776fbfb1f91142ae1f6a0cc49c426fa0de420a097bf2c4e7188cb68d3cb4db93ee057b9b250255be0966910d43c8a3cdfe667e4765ce97681168e5829cd6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/542c4df2d556deda3e9a44ec0fc900482d175214ea7afc596b7fec5d94dee95ded3019bbea32581d31e765370fdca2bf6e7db02049325ad828e713f7a7d2e7f9"} build_url:https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/aVTyldjJQdqgUaq_DdHyBA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6baef2d369931e088cb3fdb25c62b41bce03ab80f510d7d43cc817f589969077f214a678f0c41e12e7b26dad959470e2ffb811443d2bb1931b71ed55b2dfb2ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/affb776fbfb1f91142ae1f6a0cc49c426fa0de420a097bf2c4e7188cb68d3cb4db93ee057b9b250255be0966910d43c8a3cdfe667e4765ce97681168e5829cd6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/542c4df2d556deda3e9a44ec0fc900482d175214ea7afc596b7fec5d94dee95ded3019bbea32581d31e765370fdca2bf6e7db02049325ad828e713f7a7d2e7f9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 09:39:56.375001) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 09:39:56.375315) ========= 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-1476547946.232719-1156656160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 09:39:56.459827) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-15 09:39:56.460134) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-15 09:39:56.460428) ========= ========= Total master_lag: 0.20 =========