builder: larch_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-040 starttime: 1464993109.02 results: success (0) buildid: 20160603123008 builduid: c1b6961d1a1a450ea26ea67f1cfdf332 revision: f73e91c7bd1fe252c32004934f960b5bf27204cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:49.020627) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:49.021072) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:49.021387) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:49.050048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:49.050333) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-03 15:31:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-06-03 15:31:49 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121522 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:49.186397) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:49.186778) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019889 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:49.237235) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-03 15:31:49.237641) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev f73e91c7bd1fe252c32004934f960b5bf27204cf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev f73e91c7bd1fe252c32004934f960b5bf27204cf --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=9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-03 15:31:49,404 truncating revision to first 12 chars 2016-06-03 15:31:49,404 Setting DEBUG logging. 2016-06-03 15:31:49,405 attempt 1/10 2016-06-03 15:31:49,405 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/f73e91c7bd1f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 15:31:50,010 unpacking tar archive at: larch-f73e91c7bd1f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.287864 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-03 15:31:50.539718) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:50.540071) ========= script_repo_revision: f73e91c7bd1fe252c32004934f960b5bf27204cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:50.540552) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:50.540889) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 15:31:50.551197) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-06-03 15:31:50.551993) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] 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=9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:31:50 INFO - MultiFileLogger online at 20160603 15:31:50 in /builds/slave/test 15:31:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 15:31:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:31:50 INFO - {'append_to_log': False, 15:31:50 INFO - 'base_work_dir': '/builds/slave/test', 15:31:50 INFO - 'blob_upload_branch': 'Larch', 15:31:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:31:50 INFO - 'branch': 'Larch', 15:31:50 INFO - 'buildbot_json_path': 'buildprops.json', 15:31:50 INFO - 'config_files': ('talos/linux_config.py',), 15:31:50 INFO - 'default_actions': ('clobber', 15:31:50 INFO - 'read-buildbot-config', 15:31:50 INFO - 'download-and-extract', 15:31:50 INFO - 'populate-webroot', 15:31:50 INFO - 'create-virtualenv', 15:31:50 INFO - 'install', 15:31:50 INFO - 'run-tests'), 15:31:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:31:50 INFO - 'download_minidump_stackwalk': True, 15:31:50 INFO - 'download_symbols': 'ondemand', 15:31:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:31:50 INFO - 'tooltool.py': '/tools/tooltool.py', 15:31:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:31:50 INFO - '/tools/misc-python/virtualenv.py')}, 15:31:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:31:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:31:50 INFO - 'installer_path': 'installer.exe', 15:31:50 INFO - 'log_level': 'info', 15:31:50 INFO - 'log_name': 'talos', 15:31:50 INFO - 'log_to_console': True, 15:31:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:31:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:31:50 INFO - 'opt_config_files': (), 15:31:50 INFO - 'pip_index': False, 15:31:50 INFO - 'sps_profile': False, 15:31:50 INFO - 'sps_profile_interval': 0, 15:31:50 INFO - 'suite': 'dromaeojs-e10s', 15:31:50 INFO - 'system_bits': '32', 15:31:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:31:50 INFO - 'title': 'talos-linux64-ix-040', 15:31:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:31:50 INFO - 'use_talos_json': True, 15:31:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:31:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:31:50 INFO - 'work_dir': 'build'} 15:31:50 INFO - ##### 15:31:50 INFO - ##### Running clobber step. 15:31:50 INFO - ##### 15:31:50 INFO - Running pre-action listener: _resource_record_pre_action 15:31:50 INFO - Running main action method: clobber 15:31:50 INFO - rmtree: /builds/slave/test/build 15:31:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:31:51 INFO - Running post-action listener: _resource_record_post_action 15:31:51 INFO - ##### 15:31:51 INFO - ##### Finished clobber step (success) 15:31:51 INFO - ##### 15:31:51 INFO - ##### 15:31:51 INFO - ##### Running read-buildbot-config step. 15:31:51 INFO - ##### 15:31:51 INFO - Running pre-action listener: _resource_record_pre_action 15:31:51 INFO - Running main action method: read_buildbot_config 15:31:51 INFO - Using buildbot properties: 15:31:51 INFO - { 15:31:51 INFO - "project": "", 15:31:51 INFO - "product": "firefox", 15:31:51 INFO - "script_repo_revision": "production", 15:31:51 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs-e10s", 15:31:51 INFO - "repository": "", 15:31:51 INFO - "buildername": "Ubuntu HW 12.04 x64 larch pgo talos dromaeojs-e10s", 15:31:51 INFO - "buildid": "20160603123008", 15:31:51 INFO - "pgo_build": "True", 15:31:51 INFO - "basedir": "/builds/slave/test", 15:31:51 INFO - "buildnumber": 1, 15:31:51 INFO - "slavename": "talos-linux64-ix-040", 15:31:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:31:51 INFO - "platform": "ubuntu64_hw", 15:31:51 INFO - "scheduler": "tests-larch-linux64-pgo-talos", 15:31:51 INFO - "branch": "larch", 15:31:51 INFO - "repo_path": "projects/larch", 15:31:51 INFO - "revision": "f73e91c7bd1fe252c32004934f960b5bf27204cf", 15:31:51 INFO - "stage_platform": "linux64-pgo", 15:31:51 INFO - "builduid": "c1b6961d1a1a450ea26ea67f1cfdf332", 15:31:51 INFO - "slavebuilddir": "test" 15:31:51 INFO - } 15:31:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:31:51 INFO - Running post-action listener: _resource_record_post_action 15:31:51 INFO - ##### 15:31:51 INFO - ##### Finished read-buildbot-config step (success) 15:31:51 INFO - ##### 15:31:51 INFO - ##### 15:31:51 INFO - ##### Running download-and-extract step. 15:31:51 INFO - ##### 15:31:51 INFO - Running pre-action listener: _resource_record_pre_action 15:31:51 INFO - Running main action method: download_and_extract 15:31:51 INFO - mkdir: /builds/slave/test/build/tests 15:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:51 INFO - https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:31:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:31:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:31:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:31:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:31:59 INFO - Downloaded 1361 bytes. 15:31:59 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:31:59 INFO - Using the following test package requirements: 15:31:59 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:31:59 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:31:59 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:31:59 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:59 INFO - u'jsshell-linux-x86_64.zip'], 15:31:59 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:31:59 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:31:59 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:31:59 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:31:59 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:31:59 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:59 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:31:59 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:59 INFO - https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:31:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:31:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:31:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:31:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:32:06 INFO - Downloaded 24441693 bytes. 15:32:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:32:08 INFO - Return code: 0 15:32:08 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:08 INFO - https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:32:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:32:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:32:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:32:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:32:09 INFO - Downloaded 24441693 bytes. 15:32:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:32:10 INFO - Return code: 0 15:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:10 INFO - https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:32:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:32:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:32:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:32:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:32:12 INFO - Downloaded 10833377 bytes. 15:32:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:32:12 INFO - Return code: 0 15:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:12 INFO - https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:32:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:32:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:32:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:32:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:32:14 INFO - Downloaded 59194145 bytes. 15:32:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:32:14 INFO - mkdir: /builds/slave/test/properties 15:32:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:32:14 INFO - Writing to file /builds/slave/test/properties/build_url 15:32:14 INFO - Contents: 15:32:14 INFO - build_url:https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:32:14 INFO - Running post-action listener: _resource_record_post_action 15:32:14 INFO - Running post-action listener: set_extra_try_arguments 15:32:14 INFO - ##### 15:32:14 INFO - ##### Finished download-and-extract step (success) 15:32:14 INFO - ##### 15:32:14 INFO - ##### 15:32:14 INFO - ##### Running populate-webroot step. 15:32:14 INFO - ##### 15:32:14 INFO - Running pre-action listener: _resource_record_pre_action 15:32:14 INFO - Running main action method: populate_webroot 15:32:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:32:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:32:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:32:14 INFO - '--tppagecycles', 15:32:14 INFO - '7'], 15:32:14 INFO - 'tests': ['tsvgm']}}, 15:32:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:32:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:32:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:32:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:32:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:32:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:32:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:14 INFO - 'talos_options': ['--disable-e10s'], 15:32:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:14 INFO - 'talos_options': ['--disable-e10s'], 15:32:14 INFO - 'tests': ['damp', 'tps']}, 15:32:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:14 INFO - 'tests': ['damp', 'tps']}, 15:32:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:32:14 INFO - 'tests': ['dromaeo_dom']}, 15:32:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:32:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:32:14 INFO - 'tests': ['a11yr', 15:32:14 INFO - 'ts_paint', 15:32:14 INFO - 'tpaint', 15:32:14 INFO - 'sessionrestore', 15:32:14 INFO - 'sessionrestore_no_auto_restore', 15:32:14 INFO - 'tabpaint']}, 15:32:14 INFO - 'other-e10s': {'tests': ['a11yr', 15:32:14 INFO - 'ts_paint', 15:32:14 INFO - 'tpaint', 15:32:14 INFO - 'sessionrestore', 15:32:14 INFO - 'sessionrestore_no_auto_restore', 15:32:14 INFO - 'tabpaint']}, 15:32:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:32:14 INFO - 'ts_paint', 15:32:14 INFO - 'tpaint', 15:32:14 INFO - 'sessionrestore', 15:32:14 INFO - 'sessionrestore_no_auto_restore', 15:32:14 INFO - 'tabpaint']}, 15:32:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:32:14 INFO - 'ts_paint', 15:32:14 INFO - 'tpaint', 15:32:14 INFO - 'sessionrestore', 15:32:14 INFO - 'sessionrestore_no_auto_restore', 15:32:14 INFO - 'tabpaint']}, 15:32:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:32:14 INFO - 'tests': ['a11yr', 15:32:14 INFO - 'ts_paint', 15:32:14 INFO - 'tpaint', 15:32:14 INFO - 'sessionrestore', 15:32:14 INFO - 'sessionrestore_no_auto_restore', 15:32:14 INFO - 'tabpaint']}, 15:32:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:32:14 INFO - 'tests': ['a11yr', 15:32:14 INFO - 'ts_paint', 15:32:14 INFO - 'tpaint', 15:32:14 INFO - 'sessionrestore', 15:32:14 INFO - 'sessionrestore_no_auto_restore', 15:32:14 INFO - 'tabpaint']}, 15:32:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:32:14 INFO - 'tests': ['tsvgx', 15:32:14 INFO - 'tsvgr_opacity', 15:32:14 INFO - 'tart', 15:32:14 INFO - 'tscrollx', 15:32:14 INFO - 'cart']}, 15:32:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:32:14 INFO - 'tsvgr_opacity', 15:32:14 INFO - 'tart', 15:32:14 INFO - 'tscrollx', 15:32:14 INFO - 'cart']}, 15:32:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:14 INFO - 'talos_options': ['--disable-e10s'], 15:32:14 INFO - 'tests': ['tp5o']}, 15:32:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:14 INFO - 'tests': ['tp5o']}, 15:32:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:14 INFO - 'talos_options': ['--disable-e10s', 15:32:14 INFO - '--xperf_path', 15:32:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:14 INFO - 'tests': ['tp5n']}, 15:32:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:14 INFO - 'talos_options': ['--xperf_path', 15:32:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:14 INFO - 'tests': ['tp5n']}}, 15:32:14 INFO - 'talos.zip': {'path': '', 15:32:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:32:14 INFO - Running post-action listener: _resource_record_post_action 15:32:14 INFO - ##### 15:32:14 INFO - ##### Finished populate-webroot step (success) 15:32:14 INFO - ##### 15:32:14 INFO - ##### 15:32:14 INFO - ##### Running create-virtualenv step. 15:32:14 INFO - ##### 15:32:14 INFO - Running pre-action listener: _resource_record_pre_action 15:32:14 INFO - Running main action method: create_virtualenv 15:32:14 INFO - Creating virtualenv /builds/slave/test/build/venv 15:32:14 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 15:32:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:32:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:32:14 INFO - Using real prefix '/usr' 15:32:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:32:15 INFO - Installing distribute.............................................................................................................................................................................................done. 15:32:17 INFO - Installing pip.................done. 15:32:17 INFO - Return code: 0 15:32:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93ba354f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c84190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', '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 15:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:32:17 INFO - Using env: {'DISPLAY': ':0', 15:32:17 INFO - 'HOME': '/home/cltbld', 15:32:17 INFO - 'LANG': 'en_US.UTF-8', 15:32:17 INFO - 'LANGUAGE': 'en_US:en', 15:32:17 INFO - 'LOGNAME': 'cltbld', 15:32:17 INFO - 'MAIL': '/var/mail/cltbld', 15:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:17 INFO - 'MOZ_NO_REMOTE': '1', 15:32:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:17 INFO - 'NO_EM_RESTART': '1', 15:32:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:17 INFO - 'PWD': '/builds/slave/test', 15:32:17 INFO - 'SHELL': '/bin/bash', 15:32:17 INFO - 'SHLVL': '1', 15:32:17 INFO - 'TERM': 'linux', 15:32:17 INFO - 'TMOUT': '86400', 15:32:17 INFO - 'USER': 'cltbld', 15:32:17 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:17 INFO - '_': '/tools/buildbot/bin/python'} 15:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:17 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 15:32:17 INFO - Cleaning up... 15:32:17 INFO - Return code: 0 15:32:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f93ba354f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c84190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', '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 15:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:32:17 INFO - Using env: {'DISPLAY': ':0', 15:32:17 INFO - 'HOME': '/home/cltbld', 15:32:17 INFO - 'LANG': 'en_US.UTF-8', 15:32:17 INFO - 'LANGUAGE': 'en_US:en', 15:32:17 INFO - 'LOGNAME': 'cltbld', 15:32:17 INFO - 'MAIL': '/var/mail/cltbld', 15:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:17 INFO - 'MOZ_NO_REMOTE': '1', 15:32:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:17 INFO - 'NO_EM_RESTART': '1', 15:32:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:17 INFO - 'PWD': '/builds/slave/test', 15:32:17 INFO - 'SHELL': '/bin/bash', 15:32:17 INFO - 'SHLVL': '1', 15:32:17 INFO - 'TERM': 'linux', 15:32:17 INFO - 'TMOUT': '86400', 15:32:17 INFO - 'USER': 'cltbld', 15:32:17 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:17 INFO - '_': '/tools/buildbot/bin/python'} 15:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:17 INFO - Downloading/unpacking psutil>=3.1.1 15:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:32:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:19 INFO - Installing collected packages: psutil 15:32:19 INFO - Running setup.py install for psutil 15:32:19 INFO - building 'psutil._psutil_linux' extension 15:32:19 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 15:32:20 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 15:32:20 INFO - building 'psutil._psutil_posix' extension 15:32:20 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 15:32:20 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 15:32:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:20 INFO - Successfully installed psutil 15:32:20 INFO - Cleaning up... 15:32:20 INFO - Return code: 0 15:32:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93ba354f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c84190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', '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 15:32:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:32:20 INFO - Using env: {'DISPLAY': ':0', 15:32:20 INFO - 'HOME': '/home/cltbld', 15:32:20 INFO - 'LANG': 'en_US.UTF-8', 15:32:20 INFO - 'LANGUAGE': 'en_US:en', 15:32:20 INFO - 'LOGNAME': 'cltbld', 15:32:20 INFO - 'MAIL': '/var/mail/cltbld', 15:32:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:20 INFO - 'MOZ_NO_REMOTE': '1', 15:32:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:20 INFO - 'NO_EM_RESTART': '1', 15:32:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:20 INFO - 'PWD': '/builds/slave/test', 15:32:20 INFO - 'SHELL': '/bin/bash', 15:32:20 INFO - 'SHLVL': '1', 15:32:20 INFO - 'TERM': 'linux', 15:32:20 INFO - 'TMOUT': '86400', 15:32:20 INFO - 'USER': 'cltbld', 15:32:20 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:20 INFO - '_': '/tools/buildbot/bin/python'} 15:32:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:32:20 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 15:32:20 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 15:32:20 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 15:32:20 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 15:32:20 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 15:32:20 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 15:32:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:32:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:32:22 INFO - Installing collected packages: mozsystemmonitor 15:32:22 INFO - Running setup.py install for mozsystemmonitor 15:32:22 INFO - Successfully installed mozsystemmonitor 15:32:22 INFO - Cleaning up... 15:32:22 INFO - Return code: 0 15:32:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93ba354f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c84190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', '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 15:32:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:32:22 INFO - Using env: {'DISPLAY': ':0', 15:32:22 INFO - 'HOME': '/home/cltbld', 15:32:22 INFO - 'LANG': 'en_US.UTF-8', 15:32:22 INFO - 'LANGUAGE': 'en_US:en', 15:32:22 INFO - 'LOGNAME': 'cltbld', 15:32:22 INFO - 'MAIL': '/var/mail/cltbld', 15:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:22 INFO - 'MOZ_NO_REMOTE': '1', 15:32:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:22 INFO - 'NO_EM_RESTART': '1', 15:32:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:22 INFO - 'PWD': '/builds/slave/test', 15:32:22 INFO - 'SHELL': '/bin/bash', 15:32:22 INFO - 'SHLVL': '1', 15:32:22 INFO - 'TERM': 'linux', 15:32:22 INFO - 'TMOUT': '86400', 15:32:22 INFO - 'USER': 'cltbld', 15:32:22 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:22 INFO - '_': '/tools/buildbot/bin/python'} 15:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:22 INFO - Downloading/unpacking jsonschema==2.5.1 15:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:32:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:24 INFO - Downloading functools32-3.2.3-2.tar.gz 15:32:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:32:25 INFO - warning: no files found matching '*.txt' 15:32:25 INFO - no previously-included directories found matching 'build' 15:32:25 INFO - no previously-included directories found matching 'dist' 15:32:25 INFO - no previously-included directories found matching '.git*' 15:32:25 INFO - Installing collected packages: jsonschema, functools32 15:32:25 INFO - Running setup.py install for functools32 15:32:25 INFO - warning: no files found matching '*.txt' 15:32:25 INFO - no previously-included directories found matching 'build' 15:32:25 INFO - no previously-included directories found matching 'dist' 15:32:25 INFO - no previously-included directories found matching '.git*' 15:32:25 INFO - Successfully installed jsonschema functools32 15:32:25 INFO - Cleaning up... 15:32:25 INFO - Return code: 0 15:32:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f93ba354f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c84190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', '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 15:32:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:32:25 INFO - Using env: {'DISPLAY': ':0', 15:32:25 INFO - 'HOME': '/home/cltbld', 15:32:25 INFO - 'LANG': 'en_US.UTF-8', 15:32:25 INFO - 'LANGUAGE': 'en_US:en', 15:32:25 INFO - 'LOGNAME': 'cltbld', 15:32:25 INFO - 'MAIL': '/var/mail/cltbld', 15:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:25 INFO - 'MOZ_NO_REMOTE': '1', 15:32:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:25 INFO - 'NO_EM_RESTART': '1', 15:32:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:25 INFO - 'PWD': '/builds/slave/test', 15:32:25 INFO - 'SHELL': '/bin/bash', 15:32:25 INFO - 'SHLVL': '1', 15:32:25 INFO - 'TERM': 'linux', 15:32:25 INFO - 'TMOUT': '86400', 15:32:25 INFO - 'USER': 'cltbld', 15:32:25 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:25 INFO - '_': '/tools/buildbot/bin/python'} 15:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:25 INFO - Downloading/unpacking blobuploader==1.2.4 15:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:27 INFO - Downloading blobuploader-1.2.4.tar.gz 15:32:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:32:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:32:27 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 15:32:27 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 15:32:27 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 15:32:27 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 15:32:27 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 15:32:27 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 15:32:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:32:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:32:27 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 15:32:27 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 15:32:27 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 15:32:27 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 15:32:27 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 15:32:27 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 15:32:28 INFO - Downloading docopt-0.6.1.tar.gz 15:32:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:32:28 INFO - Installing collected packages: blobuploader, requests, docopt 15:32:28 INFO - Running setup.py install for blobuploader 15:32:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:32:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:32:28 INFO - Running setup.py install for requests 15:32:28 INFO - Running setup.py install for docopt 15:32:28 INFO - Successfully installed blobuploader requests docopt 15:32:28 INFO - Cleaning up... 15:32:28 INFO - Return code: 0 15:32:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:28 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 0x7f93ba354f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c84190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', '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 15:32:28 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 15:32:28 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 15:32:28 INFO - Using env: {'DISPLAY': ':0', 15:32:28 INFO - 'HOME': '/home/cltbld', 15:32:28 INFO - 'LANG': 'en_US.UTF-8', 15:32:28 INFO - 'LANGUAGE': 'en_US:en', 15:32:28 INFO - 'LOGNAME': 'cltbld', 15:32:28 INFO - 'MAIL': '/var/mail/cltbld', 15:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:28 INFO - 'MOZ_NO_REMOTE': '1', 15:32:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:28 INFO - 'NO_EM_RESTART': '1', 15:32:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:28 INFO - 'PWD': '/builds/slave/test', 15:32:28 INFO - 'SHELL': '/bin/bash', 15:32:28 INFO - 'SHLVL': '1', 15:32:28 INFO - 'TERM': 'linux', 15:32:28 INFO - 'TMOUT': '86400', 15:32:28 INFO - 'USER': 'cltbld', 15:32:28 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:28 INFO - '_': '/tools/buildbot/bin/python'} 15:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:32:28 INFO - Running setup.py (path:/tmp/pip-NM89F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:32:29 INFO - Running setup.py (path:/tmp/pip-AJnjWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:32:29 INFO - Running setup.py (path:/tmp/pip-UeH6TU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:32:29 INFO - Running setup.py (path:/tmp/pip-5QQUPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:32:29 INFO - Running setup.py (path:/tmp/pip-ZbsPCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:32:29 INFO - Running setup.py (path:/tmp/pip-Uzi9vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:32:29 INFO - Running setup.py (path:/tmp/pip-gpo3CU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:32:29 INFO - Running setup.py (path:/tmp/pip-37gdO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:32:29 INFO - Running setup.py (path:/tmp/pip-unBern-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:32:29 INFO - Running setup.py (path:/tmp/pip-KgBmFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:32:29 INFO - Running setup.py (path:/tmp/pip-2T0Hi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:32:29 INFO - Running setup.py (path:/tmp/pip-I8kmm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:32:29 INFO - Running setup.py (path:/tmp/pip-inVWbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:32:29 INFO - Running setup.py (path:/tmp/pip-QhoUXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:29 INFO - Running setup.py (path:/tmp/pip-Maj6VX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:32:30 INFO - Running setup.py (path:/tmp/pip-_m3ViU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:32:30 INFO - Running setup.py (path:/tmp/pip-pNC1bN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:32:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:32:30 INFO - Running setup.py install for manifestparser 15:32:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:32:30 INFO - Running setup.py install for mozcrash 15:32:30 INFO - Running setup.py install for mozdebug 15:32:30 INFO - Running setup.py install for mozdevice 15:32:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:32:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:32:30 INFO - Running setup.py install for mozfile 15:32:30 INFO - Running setup.py install for mozhttpd 15:32:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:32:30 INFO - Running setup.py install for mozinfo 15:32:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:32:30 INFO - Running setup.py install for mozInstall 15:32:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:32:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:32:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:32:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:32:30 INFO - Running setup.py install for mozleak 15:32:31 INFO - Running setup.py install for mozlog 15:32:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:32:31 INFO - Running setup.py install for moznetwork 15:32:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:32:31 INFO - Running setup.py install for mozprocess 15:32:31 INFO - Running setup.py install for mozprofile 15:32:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:32:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:32:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:32:31 INFO - Running setup.py install for mozrunner 15:32:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:32:31 INFO - Running setup.py install for mozscreenshot 15:32:31 INFO - Running setup.py install for moztest 15:32:31 INFO - Running setup.py install for mozversion 15:32:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:32:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:32:31 INFO - Cleaning up... 15:32:31 INFO - Return code: 0 15:32:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:31 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 0x7f93ba354f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c84190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', '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 15:32:31 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 15:32:31 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 15:32:31 INFO - Using env: {'DISPLAY': ':0', 15:32:31 INFO - 'HOME': '/home/cltbld', 15:32:31 INFO - 'LANG': 'en_US.UTF-8', 15:32:31 INFO - 'LANGUAGE': 'en_US:en', 15:32:31 INFO - 'LOGNAME': 'cltbld', 15:32:31 INFO - 'MAIL': '/var/mail/cltbld', 15:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:31 INFO - 'MOZ_NO_REMOTE': '1', 15:32:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:31 INFO - 'NO_EM_RESTART': '1', 15:32:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:31 INFO - 'PWD': '/builds/slave/test', 15:32:31 INFO - 'SHELL': '/bin/bash', 15:32:31 INFO - 'SHLVL': '1', 15:32:31 INFO - 'TERM': 'linux', 15:32:31 INFO - 'TMOUT': '86400', 15:32:31 INFO - 'USER': 'cltbld', 15:32:31 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:31 INFO - '_': '/tools/buildbot/bin/python'} 15:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:32:32 INFO - Running setup.py (path:/tmp/pip-8MP4hF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:32:32 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)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:32:32 INFO - Running setup.py (path:/tmp/pip-n5Be73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:32:32 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)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:32:32 INFO - Running setup.py (path:/tmp/pip-MLtE9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:32:32 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)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:32:32 INFO - Running setup.py (path:/tmp/pip-tcYCE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:32:32 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)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:32:32 INFO - Running setup.py (path:/tmp/pip-xdqB83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:32:32 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)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:32:32 INFO - Running setup.py (path:/tmp/pip-_Ne2ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:32:32 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)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:32:32 INFO - Running setup.py (path:/tmp/pip-EiL_YO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:32:32 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)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:32:32 INFO - Running setup.py (path:/tmp/pip-fbpOdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:32:32 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)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:32:32 INFO - Running setup.py (path:/tmp/pip-IcY6ZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:32:32 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)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:32:32 INFO - Running setup.py (path:/tmp/pip-xNl0i_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:32:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:32:32 INFO - Running setup.py (path:/tmp/pip-1EiH0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:32:32 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)) 15:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:32:32 INFO - Running setup.py (path:/tmp/pip-BWkyBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:32:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:32:33 INFO - Running setup.py (path:/tmp/pip-XOrvmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:32:33 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)) 15:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:32:33 INFO - Running setup.py (path:/tmp/pip-0wciHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:32:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:33 INFO - Running setup.py (path:/tmp/pip-kUz80I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:33 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)) 15:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:32:33 INFO - Running setup.py (path:/tmp/pip-WmRIBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:32:33 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)) 15:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:32:33 INFO - Running setup.py (path:/tmp/pip-Wp26h5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:32:33 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)) 15:32:33 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)) 15:32:33 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)) 15:32:33 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)) 15:32:33 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)) 15:32:33 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)) 15:32:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:32:33 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 15:32:33 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 15:32:33 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 15:32:33 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 15:32:33 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 15:32:33 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 15:32:35 INFO - Downloading blessings-1.6.tar.gz 15:32:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:32:35 INFO - Installing collected packages: blessings 15:32:35 INFO - Running setup.py install for blessings 15:32:35 INFO - Successfully installed blessings 15:32:35 INFO - Cleaning up... 15:32:35 INFO - Return code: 0 15:32:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:32:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:32:35 INFO - Reading from file tmpfile_stdout 15:32:35 INFO - Current package versions: 15:32:35 INFO - argparse == 1.2.1 15:32:35 INFO - blessings == 1.6 15:32:35 INFO - blobuploader == 1.2.4 15:32:35 INFO - docopt == 0.6.1 15:32:35 INFO - functools32 == 3.2.3-2 15:32:35 INFO - jsonschema == 2.5.1 15:32:35 INFO - manifestparser == 1.1 15:32:35 INFO - mozInstall == 1.12 15:32:35 INFO - mozcrash == 0.17 15:32:35 INFO - mozdebug == 0.1 15:32:35 INFO - mozdevice == 0.48 15:32:35 INFO - mozfile == 1.2 15:32:35 INFO - mozhttpd == 0.7 15:32:35 INFO - mozinfo == 0.9 15:32:35 INFO - mozleak == 0.1 15:32:35 INFO - mozlog == 3.2 15:32:35 INFO - moznetwork == 0.27 15:32:35 INFO - mozprocess == 0.22 15:32:35 INFO - mozprofile == 0.28 15:32:35 INFO - mozrunner == 6.11 15:32:35 INFO - mozscreenshot == 0.1 15:32:35 INFO - mozsystemmonitor == 0.3 15:32:35 INFO - moztest == 0.7 15:32:35 INFO - mozversion == 1.4 15:32:35 INFO - psutil == 3.1.1 15:32:35 INFO - requests == 1.2.3 15:32:35 INFO - wsgiref == 0.1.2 15:32:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:35 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 0x7f93ba354f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c84190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', '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 15:32:35 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 15:32:35 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 15:32:35 INFO - Using env: {'DISPLAY': ':0', 15:32:35 INFO - 'HOME': '/home/cltbld', 15:32:35 INFO - 'LANG': 'en_US.UTF-8', 15:32:35 INFO - 'LANGUAGE': 'en_US:en', 15:32:35 INFO - 'LOGNAME': 'cltbld', 15:32:35 INFO - 'MAIL': '/var/mail/cltbld', 15:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:35 INFO - 'MOZ_NO_REMOTE': '1', 15:32:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:35 INFO - 'NO_EM_RESTART': '1', 15:32:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:35 INFO - 'PWD': '/builds/slave/test', 15:32:35 INFO - 'SHELL': '/bin/bash', 15:32:35 INFO - 'SHLVL': '1', 15:32:35 INFO - 'TERM': 'linux', 15:32:35 INFO - 'TMOUT': '86400', 15:32:35 INFO - 'USER': 'cltbld', 15:32:35 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:35 INFO - '_': '/tools/buildbot/bin/python'} 15:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:35 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)) 15:32:35 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)) 15:32:35 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)) 15:32:35 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)) 15:32:35 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)) 15:32:35 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)) 15:32:35 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)) 15:32:35 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)) 15:32:35 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)) 15:32:35 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)) 15:32:35 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)) 15:32:35 INFO - Cleaning up... 15:32:35 INFO - Return code: 0 15:32:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:35 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 0x7f93ba354f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c84190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', '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 15:32:35 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 15:32:35 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 15:32:35 INFO - Using env: {'DISPLAY': ':0', 15:32:35 INFO - 'HOME': '/home/cltbld', 15:32:35 INFO - 'LANG': 'en_US.UTF-8', 15:32:35 INFO - 'LANGUAGE': 'en_US:en', 15:32:35 INFO - 'LOGNAME': 'cltbld', 15:32:35 INFO - 'MAIL': '/var/mail/cltbld', 15:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:35 INFO - 'MOZ_NO_REMOTE': '1', 15:32:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:35 INFO - 'NO_EM_RESTART': '1', 15:32:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:35 INFO - 'PWD': '/builds/slave/test', 15:32:35 INFO - 'SHELL': '/bin/bash', 15:32:35 INFO - 'SHLVL': '1', 15:32:35 INFO - 'TERM': 'linux', 15:32:36 INFO - 'TMOUT': '86400', 15:32:36 INFO - 'USER': 'cltbld', 15:32:36 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:36 INFO - '_': '/tools/buildbot/bin/python'} 15:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:32:36 INFO - Cleaning up... 15:32:36 INFO - Return code: 0 15:32:36 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93ba354f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c84190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', '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 15:32:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:32:36 INFO - Using env: {'DISPLAY': ':0', 15:32:36 INFO - 'HOME': '/home/cltbld', 15:32:36 INFO - 'LANG': 'en_US.UTF-8', 15:32:36 INFO - 'LANGUAGE': 'en_US:en', 15:32:36 INFO - 'LOGNAME': 'cltbld', 15:32:36 INFO - 'MAIL': '/var/mail/cltbld', 15:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:36 INFO - 'MOZ_NO_REMOTE': '1', 15:32:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:36 INFO - 'NO_EM_RESTART': '1', 15:32:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:36 INFO - 'PWD': '/builds/slave/test', 15:32:36 INFO - 'SHELL': '/bin/bash', 15:32:36 INFO - 'SHLVL': '1', 15:32:36 INFO - 'TERM': 'linux', 15:32:36 INFO - 'TMOUT': '86400', 15:32:36 INFO - 'USER': 'cltbld', 15:32:36 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:36 INFO - '_': '/tools/buildbot/bin/python'} 15:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:36 INFO - Downloading/unpacking flake8 15:32:36 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 15:32:36 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 15:32:36 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 15:32:36 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 15:32:36 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 15:32:36 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 15:32:38 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:32:38 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:32:38 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:32:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:32:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:32:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:32:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:32:39 INFO - no previously-included directories found matching 'docs/_build' 15:32:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:32:39 INFO - Running setup.py install for pep8 15:32:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:32:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:32:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:32:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:32:39 INFO - no previously-included directories found matching 'docs/_build' 15:32:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:32:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:32:39 INFO - Cleaning up... 15:32:39 INFO - Return code: 0 15:32:39 INFO - Running post-action listener: _resource_record_post_action 15:32:39 INFO - Running post-action listener: _start_resource_monitoring 15:32:39 INFO - Starting resource monitoring. 15:32:39 INFO - ##### 15:32:39 INFO - ##### Finished create-virtualenv step (success) 15:32:39 INFO - ##### 15:32:39 INFO - ##### 15:32:39 INFO - ##### Running install step. 15:32:39 INFO - ##### 15:32:39 INFO - Running pre-action listener: _resource_record_pre_action 15:32:39 INFO - Running main action method: install 15:32:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:32:39 INFO - Reading from file tmpfile_stdout 15:32:39 INFO - Detecting whether we're running mozinstall >=1.0... 15:32:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:32:39 INFO - Reading from file tmpfile_stdout 15:32:39 INFO - Output received: 15:32:39 INFO - Usage: mozinstall [options] installer 15:32:39 INFO - Options: 15:32:39 INFO - -h, --help show this help message and exit 15:32:39 INFO - -d DEST, --destination=DEST 15:32:39 INFO - Directory to install application into. [default: 15:32:39 INFO - "/builds/slave/test"] 15:32:39 INFO - --app=APP Application being installed. [default: firefox] 15:32:39 INFO - mkdir: /builds/slave/test/build/application 15:32:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:32:56 INFO - Reading from file tmpfile_stdout 15:32:56 INFO - Output received: 15:32:56 INFO - /builds/slave/test/build/application/firefox/firefox 15:32:56 INFO - Running post-action listener: _resource_record_post_action 15:32:56 INFO - ##### 15:32:56 INFO - ##### Finished install step (success) 15:32:56 INFO - ##### 15:32:56 INFO - ##### 15:32:56 INFO - ##### Running run-tests step. 15:32:56 INFO - ##### 15:32:56 INFO - Running pre-action listener: _resource_record_pre_action 15:32:56 INFO - Running main action method: run_tests 15:32:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:32:56 INFO - Python 2.7.3 15:32:56 INFO - Return code: 0 15:32:56 INFO - grabbing minidump binary from tooltool 15:32: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:56 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 0x2d16110>, '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 0x2c82230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c826c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:32:56 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 15:32:56 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 15:32:56 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 15:32:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:32:56 INFO - Return code: 0 15:32:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:32:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:32:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:32:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:32:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:32:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:32:57 INFO - Return code: 0 15:32:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:32:57 INFO - Using env: {'DISPLAY': ':0', 15:32:57 INFO - 'HOME': '/home/cltbld', 15:32:57 INFO - 'LANG': 'en_US.UTF-8', 15:32:57 INFO - 'LANGUAGE': 'en_US:en', 15:32:57 INFO - 'LOGNAME': 'cltbld', 15:32:57 INFO - 'MAIL': '/var/mail/cltbld', 15:32:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:32:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:57 INFO - 'MOZ_NO_REMOTE': '1', 15:32:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:57 INFO - 'NO_EM_RESTART': '1', 15:32:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:57 INFO - 'PWD': '/builds/slave/test', 15:32:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:32:57 INFO - 'SHELL': '/bin/bash', 15:32:57 INFO - 'SHLVL': '1', 15:32:57 INFO - 'TERM': 'linux', 15:32:57 INFO - 'TMOUT': '86400', 15:32:57 INFO - 'USER': 'cltbld', 15:32:57 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879', 15:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:57 INFO - '_': '/tools/buildbot/bin/python'} 15:32:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:32:57 INFO - mozversion application_buildid: 20160603123008 15:32:57 INFO - mozversion application_changeset: f73e91c7bd1fe252c32004934f960b5bf27204cf 15:32:57 INFO - mozversion application_display_name: Nightly 15:32:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:32:57 INFO - mozversion application_name: Firefox 15:32:57 INFO - mozversion application_remotingname: firefox 15:32:57 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 15:32:57 INFO - mozversion application_vendor: Mozilla 15:32:57 INFO - mozversion application_version: 49.0a1 15:32:57 INFO - mozversion platform_buildid: 20160603123008 15:32:57 INFO - mozversion platform_changeset: f73e91c7bd1fe252c32004934f960b5bf27204cf 15:32:57 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 15:32:57 INFO - mozversion platform_version: 49.0a1 15:32:57 INFO - using testdate: 1464993177 15:32:57 INFO - actual date: 1464993177 15:32:57 INFO - starting webserver on 'localhost:37658' 15:32:57 INFO - SUITE-START | Running 2 tests 15:32:57 INFO - TEST-START | dromaeo_css 15:32:57 INFO - operating with platform_type : linux_ 15:32:57 INFO - Initialising browser for dromaeo_css test... 15:32:57 INFO - TEST-INFO | started process 21433 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGYjhNv/profile http://localhost:37658/getInfo.html) 15:33:02 INFO - PROCESS | 21433 | __metrics Screen width/height:1600/1200 15:33:02 INFO - PROCESS | 21433 | colorDepth:24 15:33:02 INFO - PROCESS | 21433 | Browser inner width/height: 1024/697 15:33:02 INFO - PROCESS | 21433 | __metrics 15:33:03 ERROR - PROCESS | 21433 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2944: TypeError: this.tabs is undefined 15:33:03 INFO - TEST-INFO | 21433: exit 0 15:33:03 INFO - Browser initialized. 15:33:03 INFO - Running cycle 1/1 for dromaeo_css test... 15:33:03 INFO - TEST-INFO | started process 21553 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGYjhNv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:33:15 INFO - PROCESS | 21553 | RSS: Main: 167047168 15:33:15 INFO - PROCESS | 21553 | 15:34:05 INFO - PROCESS | 21553 | 1464993245246 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] 15:36:13 INFO - PROCESS | 21553 | Cycle 1(1): loaded http://localhost:37658/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37658/tests/dromaeo/cssquery-ext.html) 15:36:14 INFO - PROCESS | 21553 | RSS: Main: 170983424 15:36:14 INFO - PROCESS | 21553 | 15:39:16 INFO - PROCESS | 21553 | Cycle 1(1): loaded http://localhost:37658/tests/dromaeo/cssquery-ext.html (next: http://localhost:37658/tests/dromaeo/cssquery-jquery.html) 15:39:17 INFO - PROCESS | 21553 | RSS: Main: 169705472 15:39:17 INFO - PROCESS | 21553 | 15:42:18 INFO - PROCESS | 21553 | Cycle 1(1): loaded http://localhost:37658/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37658/tests/dromaeo/cssquery-mootools.html) 15:42:19 INFO - PROCESS | 21553 | RSS: Main: 170754048 15:42:19 INFO - PROCESS | 21553 | 15:45:21 INFO - PROCESS | 21553 | Cycle 1(1): loaded http://localhost:37658/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37658/tests/dromaeo/cssquery-prototype.html) 15:45:21 INFO - PROCESS | 21553 | RSS: Main: 168509440 15:45:21 INFO - PROCESS | 21553 | 15:48:24 INFO - PROCESS | 21553 | Cycle 1(1): loaded http://localhost:37658/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37658/tests/dromaeo/cssquery-yui.html) 15:48:25 INFO - PROCESS | 21553 | RSS: Main: 171077632 15:48:25 INFO - PROCESS | 21553 | 15:51:27 INFO - PROCESS | 21553 | Cycle 1(1): loaded http://localhost:37658/tests/dromaeo/cssquery-yui.html (next: http://localhost:37658/tests/dromaeo/cssquery-dojo.html) 15:51:28 INFO - PROCESS | 21553 | RSS: Main: 172187648 15:51:28 INFO - PROCESS | 21553 | 15:51:28 INFO - PROCESS | 21553 | __start_tp_report 15:51:28 INFO - PROCESS | 21553 | _x_x_mozilla_page_load 15:51:28 INFO - PROCESS | 21553 | _x_x_mozilla_page_load_details 15:51:28 INFO - PROCESS | 21553 | |i|pagename|runs| 15:51:28 INFO - PROCESS | 21553 | |0;dojo.html;3098.2409309719114;3149.4016136933983;3153.306272620023;3165.6359518655363;3186.984065079676;5395;5413.621046526745;5435.728213589319;5517.5585953123755;5536.640118392312;5257.7108259045735;5291.735413229333;5378.569714422846;5401.945980540193;5433.103537916249;4760.047990401925;4803.663743537953;4815.34992775976;4930.433000204968;4995.700257984515;4833.782479788418;5087.745612719368;5098.643094983353;5115.514026167527;5115.820946266881;4890.975545122287;4958.057968985881;4969.105560999019;5031.169856973595;5035.974820125913;5005.424376196711;5019.64862459628;5019.749012549358;5021.774020169101;5089.491050894918;5002.1496345621335;5013.172826483626;5015.27278544609;5016.849494515171;5067.265246539268;5162.328897243372;5350.224217488442;5365.463453654643;5405.243265942771;5438.864028399322;5831.096180092092;5864.736086876101;5886.70566471679;5891.587588868781;5901.203337549418;5729.942700573024;5946.286445626509;5946.940530594682;5955.04719244923;5971.820845374602;5335;5349.51854333108;5368.516833484966;5409.43200963901;5455.236266922634;5902.2032025676845;5914.9112763308585;5920.496757775637;5922.585419020627;5931.673757943268;5551.361593416728;5669.036263835075;5675.687837168995;5678.886422271531;5681.318241811009;5768.278965129359;5814.06974884025;5817.127430885316;5875.383084776123;5875.383084776123;5672.602917795714;5685.175649530877;5787.565932555075;5834.27071616048;5845.298564172242;5747.913781293284;5757.992351338499;5791.391903850035;5805.390434004453;5806;4359.738415695068;4389.407429996973;4432.069265454226;4458.152950939376;4466.3747075409465;3965.8017099144927;3967.444557761916;3973.5033120859894;4013.13717550728;4020.3165461871554;5347.385050719217;5637.154426835926;5654.208410822488;5701.429857014348;5706.800261990894;5804.506616937523;5856.560757943172;5866;5883.61756485827;5895.64626122434;5555.2222688823595;5576.358718747396;5583.190437386556;5610.410906854721;5612.298462692163;4762.9523704762505;4773.570378665867;4875.975620121948;4883.780229889663;4922.310876477296;5919.378465261172;5944.791932282349;6000.399960003964;6012.917674818487;6055.818325450244;5801.970990145022;5809.622374545641;5816.331121921034;5822.94177058224;5832.154337621106;5803.883922321615;5947.137665038632;5958.433948774859;5981.1028345746745;5983.222181116601;5768.221290125779;5771.307443106854;5774.307083150049;5775.48020678141;5840.474357307863;5845.298564172326;5882.411758824083;5892.35184129754;5897.764089436375;5912.231409916683;5648.096304591246;5682.37493875665;5724.427557244242;5733.856653583527;5749.885002299847;5483.835484935458;5724.65652060861;5794.391588883106;5815.534757219497;5873.647581144974;5655.802046928337;5700.686462244616;5704.914426283526;5714;5714.3714191438075;5531.280933478622;5776.046952252831;5778.191053252632;5828.154917537018;5843.444872737083;5719.5138413235345;5735.13972904051;5756.30924289088;5769.336526299361;5807.76768929255 15:51:28 INFO - PROCESS | 21553 | |1;ext.html;572.7708916433426;601.4977493792683;631.3402494393359;634.9587276827009;649.2792999770257;39976.00059998502;42932.14135717288;43344.78327608357;43349.349759753626;43438.348424773656;44405.555944440544;45154.32268515975;45168;45237.32144017842;45373.09253814922;8557.272631826287;8718.607662655195;8810.911890881072;8811.383203175781;8814.559272036404;8563.95718021409;8694.130586941286;8744.387892847502;8760.43057201283;8760.780980475476;8763.123687631218;8799.164079412445;8820.735377938672;8820.823583528325;8826.073262307462;8730.86903696445;8777.956110219471;8778.56107194641;8785.253253473462;8807.207351338411;8603.784905377353;8604.52675102869;8650.524221167832;8686.001109872383;8726.214640682369;41445.79277103595;41761.37357939603;41814.37278440826;41915.790421048005;41942.370864437056;3018.351054523266;3403.489476578533;3404.6425125361975;3539.557555305587;3641.198936234024;31273;31289.530657040163;31397.84301078503;31471.84264078688;32799.18002049944;4168.854090106832;4213.094184750294;4223.809928553223;4250.744955302661;4336.479622445327;287014;288040;288768;288783.55608222034;290566;35347.82326088353;35954.8202258987;36232.45651315233;36300.63699363025;36332.81833590868;35173;37238.62761372406;37312.81343593319;37321.62678373236;37380.62619373826;15621.218939053002;15733;15741.763873541906;15757.290921908312;15761.29074191664;12219.938900305619;12288.631341059783;12303.323317217639;12319.199252048587;12353.752924941451;8363.540005299767;8408.915910840813;8416.747497575083;8427.41008129425;8499.490030598185;3182.8567714452442;3247.821369824635;3248.7401007919784;3259.6414394416597;3280.9343813123605;1030.6598822388594;1036.79782442423;1101.9943702461444;1135.9889698168636;1150.016735690989;29866.104016879093;29878.40243195124;29885.701142988728;30315;32109.678903211137;1112.2547892911768;1132.7847708935276;1133.6145710458484;1134.6425875849081;1147.4778975566098;15792.21038948048;16369.83630163707;16576.585585360463;16781.077040762877;16834.747478787827;578021;578600;580350;584196;588482;59311;60135.39864601298;60647.393526064174;61002.38997609967;61145.38854611397;1402.9438822446987;1414.1797757300742;1439.8992070554962;1447.5657302809325;1449.8840092792552;29653;29745;29804.850975744983;29976.70023299739;31623.367532649678;51443.228351575504;55740;57118.42881571297;57327;62298.37701622926;513071;514668;515828;520927;521442;44352;44397.33403999007;45339.77330113328;45521;46199.53800461952;49653;49794;50539.2203428815;50617.74691126521;51546.484535154166;41603.7919810399;41922.58077419187;42356.36465452959;43297.567024330616;43550.56449435465;40095.799521002205;41527.377089344285;42109.78945105255;42178;42720.35919461273;34562.98366081036;41870.1625967485;42531.7873410631;42548.78725606352;42814.5718542823;36121.277574447835;38038;38063;38160.80919595384;38592.42111368276;26737;26987.460250795266;27107;27197.592036119077;27206.183814485597 15:51:28 INFO - PROCESS | 21553 | |2;jquery.html;398.97007724420666;401.620468657119;401.93368094264446;402.2979900074371;421.7331841352729;13953.720925581507;17861.106944652765;18542.536436589093;19380.418587442382;19407.02964851757;18312.63374732508;18417.44747657569;18673.62652746944;19113;19240.807591924076;5444.210589464526;5473.917891231625;5674.035413979613;5678.943210567903;5697.515711164556;5296.470352964692;5571.916421253684;5578.804741834037;5614.129809879475;5632.239647647577;5593;5619.5785316101255;5628.437156284359;5642.4357564243455;5688.175214593882;6016.217891674076;6381.425671689523;6403.583767055173;6422.068800023971;6433.806985790434;5480.862978425571;5572.888542229173;5598.916016259759;5642.7178641067785;5659.660420374791;13887.652808679763;14262.64343391413;14491.92754036223;23143.421414464603;23181.88409057961;2145.3992881993067;2240.171136679439;2254.2786308381324;2410.867402292873;2744.7118052604587;10144.13774829133;10387.532561034695;10390.948045259802;10635.255532112755;10747.355158690425;1885.3401309541687;1951.9414417567496;1952.2678995376734;1980.9995952044126;2156.9568608627737;80632.19367806365;81235.18764812335;84113.15886841176;85176;86742.5662871688;10860.13118950482;11100.278481898651;11147.275427097213;11181.83227251592;11977.341246231366;15742.606434839221;15820;15887.126208058437;15921.12433816128;15985.44050958211;8137.8372432552205;8451.112633173429;8460.111688272766;8542.914570854336;8555.14448555152;7953.920460795433;7977.082635496876;7985.760427187193;8083.232092951161;8140.83718325642;4769.57073863354;4771.928421073687;4810.062037902575;4901.166801643729;4921.163402221631;1903.3338331583834;1915.6647586672277;1994.7406837111082;2001.6897380905982;2035.2266138867135;892.1747383670079;985.2315194148575;1017.730461071814;1018.7351288665001;1022.9232807539464;18224.362147325046;18247.54381140482;18522.722159167624;18882.433526994213;19527.707084393747;941.4539567051094;998.6804222648683;1006.1347241372414;1010.5503051142318;1033.256055639938;10499.514553488232;10561.207909406825;10582.523786429629;10597.41714205726;10864.728381790519;508939;512091.87908121187;512423;515226;516286;76035;83615.58192209;85926.57036714777;91592.54203729072;94115;990.4841702076789;999.8650182225452;1001.3391161833155;1031.4533297352416;1037.7872536130076;16582.336706531605;17080.402185923434;17556.561085972953;17995.280188792825;18123.36568220106;44233.778831105636;44250.778746106065;45116;45191;45827;56116.71941640266;57679;58238.4176158233;59039;60285;7343.155537113087;7555.13115991668;7645.847083058196;8774.122587741174;9256.907430925605;9442.008589098046;12801.359932003548;12921.870781292067;12946;13754.587362379145;11878.524859005543;12172.939135304267;12175.14773965814;12247.448864801283;12266.938665306616;11549;11753;11760.82358764602;11791.882081179421;11922.940385298018;11713.414329283672;12174.75650487003;12223.266604003788;12409.689757756129;12415.317157556421;8913.866292005754;8950.910490895269;9081.09189081113;9208.309376796633;9235.722928312161;11811.114166437723;12002.879971200176;12052.216605920587;12237.20458170216;12346.13577049598 15:51:28 INFO - PROCESS | 21553 | |3;mootools.html;1487.9553613391595;1509.0945432740357;1640.876934229933;1799.5501124718821;1803.6933721267383;8117.837643247146;8525.957370213147;8553.914460855389;9102.089791020895;9110.772230694227;8598.656053757843;8767.693130740425;8821.603027863746;8896.06591307913;8936.687215947442;2313.895874685639;2354.6585745066955;2362.1024010875844;2365.31405892291;2365.8225633077586;2345.4019225097577;2365.988170059156;2374.525094981002;2377.4769550698907;2379;2322.082777302964;2344.6951896253463;2357.4460001899624;2378.048780487801;2384.6899903012586;2303.942401439969;2321.7446080931086;2331.8484298520543;2349.177787774274;2353.446939969106;2307.4462129089065;2325.616079462698;2327.1854850802083;2338.707661542307;2348.9647655285185;8800;8863.202311791969;8920.107989201093;8925;8933.821323573557;5185.637005409623;5239;5239.816606418795;5295.126304159809;5295.602829787781;3313.5029745538122;3318.668133186686;3408.4205685033603;3415.3681568909583;3551.6093229744706;1141;1165.7377090154732;1193.618042226488;1203.2419575667361;1218.5369559567396;11774.764504709858;11791.823122653168;11828.408579570987;11843.289402635783;11918.821217681741;4517;4608.101420223024;4676.625869930397;4677.158111539956;4721.5750582447745;9973.351732137387;9981.401115932922;9991.500424978722;10021.649242276486;10028.247881408923;5214.13966695491;5253.658512196695;5265.341832270966;5270.130428479335;5272.657277276965;4350.521442641307;4609.124266389375;4676;4717.127331443694;4773.546513081252;3964.444977703124;4007.8196481157834;4041.555428902818;4095.365218391154;4127.9174416511505;1273.5287943460794;1286.1897004886862;1306.73865226955;1312.6783937935077;1315.7763180259378;876.3164731509432;992.1417973452911;994.5474808961905;1006.1598565197995;1027.121810851726;5992.910106348409;6085.574009819358;6100.115483254903;6117.265928088658;6237.157983672237;469.71112765648877;470.5905861900169;475.8453502611665;486.6398864839993;488.726313264573;4196.349565817242;4221.319000898239;4228.154369126187;4352.934705979413;4374.671899607542;10848.728781780519;11126.276792008493;11232.157588180919;11243.437828108561;11279.210455268052;10920.672379828458;11064.612738554111;11100;11106.944465277622;11138.33170009802;7956.880646790303;8228.094909559944;8292.2951549119;8298.460600060977;8404.915950840414;6075.301340345917;6170.352113028068;6174.197354343906;6274.780382686584;6297.1813664223355;9704.805903882025;9887.901120988698;10008.949955250177;10016.348937319051;10094.242931780293;9852.408855468695;9940.552675129782;10111.3427627204;10128.139108175888;10170.135538479017;7138.571685698875;7491.625418728932;7624.733134340271;7986.600669966362;8135.267825895698;8486.278666313432;8544.615492302737;8565.314774818124;8603.91396086031;8620.784480387789;7414.666340014604;7600.201978792147;7653.349465295512;7739.3034626883855;7791.454598178073;7406.851862962818;7476.887846682195;7505.286997735207;7538.472306938462;7678.078630564368;7018.2630823762765;7019.508634395544;7029.191642961125;7052.753153639598;7106.822329441805;4987.975060124676;5036.496350364934;5075.187969924795;5111.080005599028;5142.04872098663;5103.157978933583;5183.222516622386;5273.525382715574;5290.9735451322495;5338.733063346739 15:51:28 INFO - PROCESS | 21553 | |4;prototype.html;341.13011819859355;344.3594913460964;344.4660775797513;345.2697544692974;345.7337849855613;5657.603967722261;5690.5447564194865;5730.713464326788;5731.885362292752;5762.337331206907;5592.832215033545;5613.775448982046;5622.606417550773;5626.342877129868;5637.8590535236635;4167.854125105622;4226.809793559297;4328.3074708046715;4337.327714204303;4355.303151495761;4308.159908817782;4336.501302350222;4365.323374876899;4387.605115539601;4391.670624703144;4292.957070429287;4326.97836510817;4331.523532411432;4333.306670932651;4336.522982471925;4194.140201258735;4239.491261048678;4299.54854740254;4304.385855304261;4313.1804957058375;3767.9434808477895;3800.1069748609057;3812.8284227209842;3831.697222944212;3843.442700808395;5309.761060752276;5352.464753524655;5359.356877174725;5393.2449457076045;5415.241866138744;4874.975625121887;4905.705657660552;4949.208126699711;4969.204927211665;4984.700917944899;5798.739056742456;5820.156077368758;5837.387074357174;5875.618084824473;5881.294244690622;4207.768572728499;4262.65898728101;4266.765327906964;4276.251655960225;4276.422682937795;5884.558658100616;6086.482648974842;6106.053561697989;6117.143599896018;6187.597806142587;4331.826726930951;4337.869863904088;4348.543238015301;4361.800504861125;4363.083752411965;4396.3625274335045;4406.730533775109;4413.492448368414;4427.933580996288;4438.866833994985;4787.305840653085;4957.504249575014;4960.578350840146;4992.475790042066;4999.5500404963;5834.299884013945;5887.470127688529;5891.587588868823;5902.2622172228475;5909.172715819789;1507.8416766239607;1510.8338082810883;1533.8159420869567;1539.9307031183623;1540.468538354266;963.2342287881152;991.7124034030195;1005.3712984964404;1007.7329507680471;1011.2870426349554;5538.75075621598;5621.606487545914;5621.887562248732;5634.915476267859;5638;6016.308124565731;6030.849228769316;6050.878982420327;6068.848278793065;6080.935836228642;5474.479924407255;5658.490735833795;5796.88406231873;5797.88404231913;5806.7772386326;3236.8057916525086;3243.383757086146;3248.9675103249137;3270.4603740383045;3277.410066188109;6116.969415152896;6214.1921550198185;6264.21697287839;6264.780732674334;6367.471806766435;6177.444030037318;6190.226221722285;6228;6242.595416031357;6246.593971391936;6046.879062418727;6259.311475737665;6262.655553944577;6286.654234017173;6307.306196318493;5994.520438364833;6094.51243900478;6105.419985101408;6106.938930610637;6111.908321375094;6004.808244990634;6026.939730602638;6052.425019623129;6069.541835556606;6111.5110791137495;6021.668808215591;6306.211723534559;6347.68261586928;6357.1735674362035;6365.395287447685;4241.257779888569;4287.871363859089;4291.592298731615;4315.676324275754;4343.283358245981;5918.81623675258;6226.225525725146;6237.96881015592;6241.625502469685;6267.373262673878;5974;5976.223090998143;5989.401059893976;5994.580379373402;6009.579329446897;5898.967680655954;5923.526117910471;5929.347771745191;5936.486196019987;5956.731947062306;5697;5784.698442850325;5812.447817457335;5819.563532734977;5860.326062502867;5878.706064696663;5911.408859114054;5916.615419997686;5916.970415147896;5917.497012753965;5508.3114610673665;5756.625819321731;5771.769129234784;5845.473907348359;5857.27210472903 15:51:28 INFO - PROCESS | 21553 | |5;yui.html;781.5427974634838;781.847539729753;803.3533005930224;826.89663792026;870.7953630896739;14243.643908902282;14271.286435678227;15131.470398536054;15340.61648458789;15522.301496432645;15051.62370940727;15672.84327156728;15692.05847649139;15813.762793558106;15814.683706325866;791.3669064748178;803.028335713787;804.4127786715701;805.1706742055677;811.0834756724893;806.1817255485687;812.0174588747623;813.5972693516688;817.889584906036;818.1409520004001;845.5391811462737;852.8294341131767;854.7948492361851;855.2089317381428;855.3114742632179;835.7367429259795;838.8825564420986;842.4566154830162;846.4159729786428;846.4709556527201;616.4236438929615;619.7737825693634;620.3176505843581;620.8603064310539;621.0156901311427;13730;13745;13757.862421375758;13778.379972901244;13867.167969921837;14837.925810370987;14888.404463821435;15106.244687765568;15184.240787960558;15422.99750516224;11907.226030308002;11996.160268781188;12313;12636.178648387826;12707.174033687874;3937.606239376068;3939.409088636699;3970.7816070116132;4102.815373308208;4110.7328023678665;78167.44739460156;79416.60291698505;79919;80468;80627.19372806256;11274.041706455044;11534.826977595343;11577.76844463106;11784.116191285517;11945.58190463347;21942.45143871384;22070.66893996591;22157.778422215895;22178.778212217672;22251.777482225294;8263.917360826435;8278.8344233115;8313.875291870627;8318.585840407146;8692.652293908173;4808.182608956486;4858.295547145644;4880.511948805091;4889.3888263967;5177.171652535576;3392.9660703393142;3518.3139287838626;3520.383932811799;3523.1016090896655;3523.9119022024656;613.2518327640272;618.0543768034913;622.5175488996065;634.0299342006737;641.0224407778176;686.6429456682597;769.9807504812425;788.0661416221795;799.0443192013508;799.7121036426998;10425.165986721044;10433.582656693647;10469.790604187874;10656.840147397796;10658.413787241776;644.6035688051815;657.5857209957696;687.8246047258017;756.9894191254737;766.6856588798565;4317.913641727211;4632.745199014019;4679.125739674479;4824.56578907893;4881.316615673809;74805;79560;85635;129816.3509182448;130184;105471.47264263629;105530;105806.47096764467;106488.46755766172;108162.45918770356;92206.53896730473;94079;96119.51940240254;97487.51256243815;98979.50510247402;9199.724008279762;9320.207782338306;9374.718758437259;9501.714948551555;9762;53550.46449535455;53557;53854.461455384946;54799.72600136974;55373;70636;78549.60725196337;79466;82039.58980205061;83674.58162709148;11878.762424751629;11917.642470725892;12180.147389682637;12250.877491224974;12431.191972521758;13596;14026.08830426019;14069.718605628035;14101.576952691436;14201.976384635767;12544.560940367044;12945.223286602833;13077;13132.146410483288;13725.588232353046;12371.51541814988;12377.62867113988;12640.494380224687;12678.429470673658;12996.220226786423;12683.175593586388;12721.328392048037;13099.148555343872;13341.19952802835;13367.398467069199;10685;10814.513346899252;10970.890291096986;11091.168162388014;11182.944085279521;10690.792762171473;10806;10877.78244435091;10949.890501095206;10987.505562249878 15:51:28 INFO - PROCESS | 21553 | __end_tp_report 15:51:28 INFO - PROCESS | 21553 | __start_cc_report 15:51:28 INFO - PROCESS | 21553 | _x_x_mozilla_cycle_collect,353 15:51:28 INFO - PROCESS | 21553 | __end_cc_report 15:51:28 INFO - PROCESS | 21553 | __startTimestamp1464994288030__endTimestamp 15:51:28 INFO - PROCESS | 21553 | 15:51:28 INFO - PROCESS | 21553 | ------- Summary: start ------- 15:51:28 INFO - PROCESS | 21553 | Number of tests: 6 15:51:28 INFO - PROCESS | 21553 | 15:51:28 INFO - PROCESS | 21553 | [#0] dojo.html Cycles:165 Average:5416.34 Median:5681.32 stddev:620.60 (10.9%) stddev-sans-first:595.26 15:51:28 INFO - PROCESS | 21553 | Values: 3098.2 3149.4 3153.3 3165.6 3187.0 5395.0 5413.6 5435.7 5517.6 5536.6 5257.7 5291.7 5378.6 5401.9 5433.1 4760.0 4803.7 4815.3 4930.4 4995.7 4833.8 5087.7 5098.6 5115.5 5115.8 4891.0 4958.1 4969.1 5031.2 5036.0 5005.4 5019.6 5019.7 5021.8 5089.5 5002.1 5013.2 5015.3 5016.8 5067.3 5162.3 5350.2 5365.5 5405.2 5438.9 5831.1 5864.7 5886.7 5891.6 5901.2 5729.9 5946.3 5946.9 5955.0 5971.8 5335.0 5349.5 5368.5 5409.4 5455.2 5902.2 5914.9 5920.5 5922.6 5931.7 5551.4 5669.0 5675.7 5678.9 5681.3 5768.3 5814.1 5817.1 5875.4 5875.4 5672.6 5685.2 5787.6 5834.3 5845.3 5747.9 5758.0 5791.4 5805.4 5806.0 4359.7 4389.4 4432.1 4458.2 4466.4 3965.8 3967.4 3973.5 4013.1 4020.3 5347.4 5637.2 5654.2 5701.4 5706.8 5804.5 5856.6 5866.0 5883.6 5895.6 5555.2 5576.4 5583.2 5610.4 5612.3 4763.0 4773.6 4876.0 4883.8 4922.3 5919.4 5944.8 6000.4 6012.9 6055.8 5802.0 5809.6 5816.3 5822.9 5832.2 5803.9 5947.1 5958.4 5981.1 5983.2 5768.2 5771.3 5774.3 5775.5 5840.5 5845.3 5882.4 5892.4 5897.8 5912.2 5648.1 5682.4 5724.4 5733.9 5749.9 5483.8 5724.7 5794.4 5815.5 5873.6 5655.8 5700.7 5704.9 5714.0 5714.4 5531.3 5776.0 5778.2 5828.2 5843.4 5719.5 5735.1 5756.3 5769.3 5807.8 15:51:28 INFO - PROCESS | 21553 | 15:51:28 INFO - PROCESS | 21553 | [#1] ext.html Cycles:180 Average:61024.99 Median:41919.19 stddev:127997.47 (305.3%) stddev-sans-first:128276.06 15:51:28 INFO - PROCESS | 21553 | Values: 572.8 601.5 631.3 635.0 649.3 39976.0 42932.1 43344.8 43349.3 43438.3 44405.6 45154.3 45168.0 45237.3 45373.1 8557.3 8718.6 8810.9 8811.4 8814.6 8564.0 8694.1 8744.4 8760.4 8760.8 8763.1 8799.2 8820.7 8820.8 8826.1 8730.9 8778.0 8778.6 8785.3 8807.2 8603.8 8604.5 8650.5 8686.0 8726.2 41445.8 41761.4 41814.4 41915.8 41942.4 3018.4 3403.5 3404.6 3539.6 3641.2 31273.0 31289.5 31397.8 31471.8 32799.2 4168.9 4213.1 4223.8 4250.7 4336.5 287014.0 288040.0 288768.0 288783.6 290566.0 35347.8 35954.8 36232.5 36300.6 36332.8 35173.0 37238.6 37312.8 37321.6 37380.6 15621.2 15733.0 15741.8 15757.3 15761.3 12219.9 12288.6 12303.3 12319.2 12353.8 8363.5 8408.9 8416.7 8427.4 8499.5 3182.9 3247.8 3248.7 3259.6 3280.9 1030.7 1036.8 1102.0 1136.0 1150.0 29866.1 29878.4 29885.7 30315.0 32109.7 1112.3 1132.8 1133.6 1134.6 1147.5 15792.2 16369.8 16576.6 16781.1 16834.7 578021.0 578600.0 580350.0 584196.0 588482.0 59311.0 60135.4 60647.4 61002.4 61145.4 1402.9 1414.2 1439.9 1447.6 1449.9 29653.0 29745.0 29804.9 29976.7 31623.4 51443.2 55740.0 57118.4 57327.0 62298.4 513071.0 514668.0 515828.0 520927.0 521442.0 44352.0 44397.3 45339.8 45521.0 46199.5 49653.0 49794.0 50539.2 50617.7 51546.5 41603.8 41922.6 42356.4 43297.6 43550.6 40095.8 41527.4 42109.8 42178.0 42720.4 34563.0 41870.2 42531.8 42548.8 42814.6 36121.3 38038.0 38063.0 38160.8 38592.4 26737.0 26987.5 27107.0 27197.6 27206.2 15:51:28 INFO - PROCESS | 21553 | 15:51:28 INFO - PROCESS | 21553 | [#2] jquery.html Cycles:180 Average:29505.58 Median:12796.38 stddev:84431.41 (659.8%) stddev-sans-first:84639.98 15:51:28 INFO - PROCESS | 21553 | Values: 399.0 401.6 401.9 402.3 421.7 13953.7 17861.1 18542.5 19380.4 19407.0 18312.6 18417.4 18673.6 19113.0 19240.8 5444.2 5473.9 5674.0 5678.9 5697.5 5296.5 5571.9 5578.8 5614.1 5632.2 5593.0 5619.6 5628.4 5642.4 5688.2 6016.2 6381.4 6403.6 6422.1 6433.8 5480.9 5572.9 5598.9 5642.7 5659.7 13887.7 14262.6 14491.9 23143.4 23181.9 2145.4 2240.2 2254.3 2410.9 2744.7 10144.1 10387.5 10390.9 10635.3 10747.4 1885.3 1951.9 1952.3 1981.0 2157.0 80632.2 81235.2 84113.2 85176.0 86742.6 10860.1 11100.3 11147.3 11181.8 11977.3 15742.6 15820.0 15887.1 15921.1 15985.4 8137.8 8451.1 8460.1 8542.9 8555.1 7953.9 7977.1 7985.8 8083.2 8140.8 4769.6 4771.9 4810.1 4901.2 4921.2 1903.3 1915.7 1994.7 2001.7 2035.2 892.2 985.2 1017.7 1018.7 1022.9 18224.4 18247.5 18522.7 18882.4 19527.7 941.5 998.7 1006.1 1010.6 1033.3 10499.5 10561.2 10582.5 10597.4 10864.7 508939.0 512091.9 512423.0 515226.0 516286.0 76035.0 83615.6 85926.6 91592.5 94115.0 990.5 999.9 1001.3 1031.5 1037.8 16582.3 17080.4 17556.6 17995.3 18123.4 44233.8 44250.8 45116.0 45191.0 45827.0 56116.7 57679.0 58238.4 59039.0 60285.0 7343.2 7555.1 7645.8 8774.1 9256.9 9442.0 12801.4 12921.9 12946.0 13754.6 11878.5 12172.9 12175.1 12247.4 12266.9 11549.0 11753.0 11760.8 11791.9 11922.9 11713.4 12174.8 12223.3 12409.7 12415.3 8913.9 8950.9 9081.1 9208.3 9235.7 11811.1 12002.9 12052.2 12237.2 12346.1 15:51:28 INFO - PROCESS | 21553 | 15:51:28 INFO - PROCESS | 21553 | [#3] mootools.html Cycles:180 Average:5792.22 Median:2598.71 stddev:3272.27 (125.9%) stddev-sans-first:3265.47 15:51:28 INFO - PROCESS | 21553 | Values: 1488.0 1509.1 1640.9 1799.6 1803.7 8117.8 8526.0 8553.9 9102.1 9110.8 8598.7 8767.7 8821.6 8896.1 8936.7 2313.9 2354.7 2362.1 2365.3 2365.8 2345.4 2366.0 2374.5 2377.5 2379.0 2322.1 2344.7 2357.4 2378.0 2384.7 2303.9 2321.7 2331.8 2349.2 2353.4 2307.4 2325.6 2327.2 2338.7 2349.0 8800.0 8863.2 8920.1 8925.0 8933.8 5185.6 5239.0 5239.8 5295.1 5295.6 3313.5 3318.7 3408.4 3415.4 3551.6 1141.0 1165.7 1193.6 1203.2 1218.5 11774.8 11791.8 11828.4 11843.3 11918.8 4517.0 4608.1 4676.6 4677.2 4721.6 9973.4 9981.4 9991.5 10021.6 10028.2 5214.1 5253.7 5265.3 5270.1 5272.7 4350.5 4609.1 4676.0 4717.1 4773.5 3964.4 4007.8 4041.6 4095.4 4127.9 1273.5 1286.2 1306.7 1312.7 1315.8 876.3 992.1 994.5 1006.2 1027.1 5992.9 6085.6 6100.1 6117.3 6237.2 469.7 470.6 475.8 486.6 488.7 4196.3 4221.3 4228.2 4352.9 4374.7 10848.7 11126.3 11232.2 11243.4 11279.2 10920.7 11064.6 11100.0 11106.9 11138.3 7956.9 8228.1 8292.3 8298.5 8404.9 6075.3 6170.4 6174.2 6274.8 6297.2 9704.8 9887.9 10008.9 10016.3 10094.2 9852.4 9940.6 10111.3 10128.1 10170.1 7138.6 7491.6 7624.7 7986.6 8135.3 8486.3 8544.6 8565.3 8603.9 8620.8 7414.7 7600.2 7653.3 7739.3 7791.5 7406.9 7476.9 7505.3 7538.5 7678.1 7018.3 7019.5 7029.2 7052.8 7106.8 4988.0 5036.5 5075.2 5111.1 5142.0 5103.2 5183.2 5273.5 5291.0 5338.7 15:51:28 INFO - PROCESS | 21553 | 15:51:28 INFO - PROCESS | 21553 | [#4] prototype.html Cycles:180 Average:4969.23 Median:5658.05 stddev:1470.64 (26.0%) stddev-sans-first:1433.15 15:51:28 INFO - PROCESS | 21553 | Values: 341.1 344.4 344.5 345.3 345.7 5657.6 5690.5 5730.7 5731.9 5762.3 5592.8 5613.8 5622.6 5626.3 5637.9 4167.9 4226.8 4328.3 4337.3 4355.3 4308.2 4336.5 4365.3 4387.6 4391.7 4293.0 4327.0 4331.5 4333.3 4336.5 4194.1 4239.5 4299.5 4304.4 4313.2 3767.9 3800.1 3812.8 3831.7 3843.4 5309.8 5352.5 5359.4 5393.2 5415.2 4875.0 4905.7 4949.2 4969.2 4984.7 5798.7 5820.2 5837.4 5875.6 5881.3 4207.8 4262.7 4266.8 4276.3 4276.4 5884.6 6086.5 6106.1 6117.1 6187.6 4331.8 4337.9 4348.5 4361.8 4363.1 4396.4 4406.7 4413.5 4427.9 4438.9 4787.3 4957.5 4960.6 4992.5 4999.6 5834.3 5887.5 5891.6 5902.3 5909.2 1507.8 1510.8 1533.8 1539.9 1540.5 963.2 991.7 1005.4 1007.7 1011.3 5538.8 5621.6 5621.9 5634.9 5638.0 6016.3 6030.8 6050.9 6068.8 6080.9 5474.5 5658.5 5796.9 5797.9 5806.8 3236.8 3243.4 3249.0 3270.5 3277.4 6117.0 6214.2 6264.2 6264.8 6367.5 6177.4 6190.2 6228.0 6242.6 6246.6 6046.9 6259.3 6262.7 6286.7 6307.3 5994.5 6094.5 6105.4 6106.9 6111.9 6004.8 6026.9 6052.4 6069.5 6111.5 6021.7 6306.2 6347.7 6357.2 6365.4 4241.3 4287.9 4291.6 4315.7 4343.3 5918.8 6226.2 6238.0 6241.6 6267.4 5974.0 5976.2 5989.4 5994.6 6009.6 5899.0 5923.5 5929.3 5936.5 5956.7 5697.0 5784.7 5812.4 5819.6 5860.3 5878.7 5911.4 5916.6 5917.0 5917.5 5508.3 5756.6 5771.8 5845.5 5857.3 15:51:28 INFO - PROCESS | 21553 | 15:51:28 INFO - PROCESS | 21553 | [#5] yui.html Cycles:180 Average:21090.87 Median:4106.77 stddev:30683.63 (747.1%) stddev-sans-first:30731.81 15:51:28 INFO - PROCESS | 21553 | Values: 781.5 781.8 803.4 826.9 870.8 14243.6 14271.3 15131.5 15340.6 15522.3 15051.6 15672.8 15692.1 15813.8 15814.7 791.4 803.0 804.4 805.2 811.1 806.2 812.0 813.6 817.9 818.1 845.5 852.8 854.8 855.2 855.3 835.7 838.9 842.5 846.4 846.5 616.4 619.8 620.3 620.9 621.0 13730.0 13745.0 13757.9 13778.4 13867.2 14837.9 14888.4 15106.2 15184.2 15423.0 11907.2 11996.2 12313.0 12636.2 12707.2 3937.6 3939.4 3970.8 4102.8 4110.7 78167.4 79416.6 79919.0 80468.0 80627.2 11274.0 11534.8 11577.8 11784.1 11945.6 21942.5 22070.7 22157.8 22178.8 22251.8 8263.9 8278.8 8313.9 8318.6 8692.7 4808.2 4858.3 4880.5 4889.4 5177.2 3393.0 3518.3 3520.4 3523.1 3523.9 613.3 618.1 622.5 634.0 641.0 686.6 770.0 788.1 799.0 799.7 10425.2 10433.6 10469.8 10656.8 10658.4 644.6 657.6 687.8 757.0 766.7 4317.9 4632.7 4679.1 4824.6 4881.3 74805.0 79560.0 85635.0 129816.4 130184.0 105471.5 105530.0 105806.5 106488.5 108162.5 92206.5 94079.0 96119.5 97487.5 98979.5 9199.7 9320.2 9374.7 9501.7 9762.0 53550.5 53557.0 53854.5 54799.7 55373.0 70636.0 78549.6 79466.0 82039.6 83674.6 11878.8 11917.6 12180.1 12250.9 12431.2 13596.0 14026.1 14069.7 14101.6 14202.0 12544.6 12945.2 13077.0 13132.1 13725.6 12371.5 12377.6 12640.5 12678.4 12996.2 12683.2 12721.3 13099.1 13341.2 13367.4 10685.0 10814.5 10970.9 11091.2 11182.9 10690.8 10806.0 10877.8 10949.9 10987.5 15:51:28 INFO - PROCESS | 21553 | -------- Summary: end -------- 15:51:28 INFO - PROCESS | 21553 | 15:51:28 INFO - TEST-INFO | 21553: exit 0 15:51:28 INFO - TEST-OK | dromaeo_css | took 1111111ms 15:51:28 INFO - TEST-START | kraken 15:51:28 INFO - operating with platform_type : linux_ 15:51:28 INFO - Initialising browser for kraken test... 15:51:28 INFO - TEST-INFO | started process 21793 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp26pzrV/profile http://localhost:37658/getInfo.html) 15:51:33 INFO - PROCESS | 21793 | __metrics Screen width/height:1600/1200 15:51:33 INFO - PROCESS | 21793 | colorDepth:24 15:51:33 INFO - PROCESS | 21793 | Browser inner width/height: 1024/697 15:51:33 INFO - PROCESS | 21793 | __metrics 15:51:34 ERROR - PROCESS | 21793 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2944: TypeError: this.tabs is undefined 15:51:34 INFO - TEST-INFO | 21793: exit 0 15:51:34 INFO - Browser initialized. 15:51:34 INFO - Running cycle 1/1 for kraken test... 15:51:34 INFO - TEST-INFO | started process 21910 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp26pzrV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:46 INFO - PROCESS | 21910 | RSS: Main: 168050688 15:51:46 INFO - PROCESS | 21910 | 15:51:49 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37658/tests/kraken/driver.html?testName=audio-beat-detection) 15:51:50 INFO - PROCESS | 21910 | RSS: Main: 173264896 15:51:50 INFO - PROCESS | 21910 | 15:51:52 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37658/tests/kraken/driver.html?testName=audio-dft) 15:51:53 INFO - PROCESS | 21910 | RSS: Main: 172490752 15:51:53 INFO - PROCESS | 21910 | 15:51:56 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37658/tests/kraken/driver.html?testName=audio-fft) 15:51:57 INFO - PROCESS | 21910 | RSS: Main: 166797312 15:51:57 INFO - PROCESS | 21910 | 15:51:59 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37658/tests/kraken/driver.html?testName=audio-oscillator) 15:51:59 INFO - PROCESS | 21910 | RSS: Main: 167440384 15:51:59 INFO - PROCESS | 21910 | 15:52:01 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37658/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:52:02 INFO - PROCESS | 21910 | RSS: Main: 167944192 15:52:02 INFO - PROCESS | 21910 | 15:52:05 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37658/tests/kraken/driver.html?testName=imaging-darkroom) 15:52:06 INFO - PROCESS | 21910 | RSS: Main: 168030208 15:52:06 INFO - PROCESS | 21910 | 15:52:09 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37658/tests/kraken/driver.html?testName=imaging-desaturate) 15:52:10 INFO - PROCESS | 21910 | RSS: Main: 166604800 15:52:10 INFO - PROCESS | 21910 | 15:52:13 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37658/tests/kraken/driver.html?testName=json-parse-financial) 15:52:13 INFO - PROCESS | 21910 | RSS: Main: 167550976 15:52:13 INFO - PROCESS | 21910 | 15:52:15 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37658/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:52:16 INFO - PROCESS | 21910 | RSS: Main: 167825408 15:52:16 INFO - PROCESS | 21910 | 15:52:17 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37658/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:52:18 INFO - PROCESS | 21910 | RSS: Main: 168067072 15:52:18 INFO - PROCESS | 21910 | 15:52:20 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37658/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:52:21 INFO - PROCESS | 21910 | RSS: Main: 168292352 15:52:21 INFO - PROCESS | 21910 | 15:52:23 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:52:24 INFO - PROCESS | 21910 | RSS: Main: 168341504 15:52:24 INFO - PROCESS | 21910 | 15:52:27 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:52:27 INFO - PROCESS | 21910 | RSS: Main: 168013824 15:52:27 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | Cycle 1(1): loaded http://localhost:37658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37658/tests/kraken/driver.html?testName=ai-astar) 15:52:29 INFO - PROCESS | 21910 | RSS: Main: 168665088 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | __start_tp_report 15:52:29 INFO - PROCESS | 21910 | _x_x_mozilla_page_load 15:52:29 INFO - PROCESS | 21910 | _x_x_mozilla_page_load_details 15:52:29 INFO - PROCESS | 21910 | |i|pagename|runs| 15:52:29 INFO - PROCESS | 21910 | |0;ai-astar;112;106;110;111;111;111;111;111;112;110 15:52:29 INFO - PROCESS | 21910 | |1;audio-beat-detection;162;133;152;172;137;158;150;127;182;134 15:52:29 INFO - PROCESS | 21910 | |2;audio-dft;161;161;162;160;162;166;169;161;161;161 15:52:29 INFO - PROCESS | 21910 | |3;audio-fft;74;75;74;170;75;76;74;77;167;75 15:52:29 INFO - PROCESS | 21910 | |4;audio-oscillator;84;85;142;86;85;84;146;85;84;139 15:52:29 INFO - PROCESS | 21910 | |5;imaging-gaussian-blur;117;117;118;118;118;117;117;117;118;118 15:52:29 INFO - PROCESS | 21910 | |6;imaging-darkroom;149;148;146;146;148;147;147;148;147;146 15:52:29 INFO - PROCESS | 21910 | |7;imaging-desaturate;97;96;95;95;95;96;96;96;96;96 15:52:29 INFO - PROCESS | 21910 | |8;json-parse-financial;53;54;55;55;56;55;61;58;57;55 15:52:29 INFO - PROCESS | 21910 | |9;json-stringify-tinderbox;62;63;62;63;66;64;63;62;64;63 15:52:29 INFO - PROCESS | 21910 | |10;stanford-crypto-aes;83;82;84;88;85;81;81;90;108;83 15:52:29 INFO - PROCESS | 21910 | |11;stanford-crypto-ccm;134;125;124;130;123;129;133;130;139;123 15:52:29 INFO - PROCESS | 21910 | |12;stanford-crypto-pbkdf2;161;171;163;163;164;158;165;162;164;159 15:52:29 INFO - PROCESS | 21910 | |13;stanford-crypto-sha256-iterative;66;65;63;64;63;61;64;64;63;61 15:52:29 INFO - PROCESS | 21910 | __end_tp_report 15:52:29 INFO - PROCESS | 21910 | __start_cc_report 15:52:29 INFO - PROCESS | 21910 | _x_x_mozilla_cycle_collect,875 15:52:29 INFO - PROCESS | 21910 | __end_cc_report 15:52:29 INFO - PROCESS | 21910 | __startTimestamp1464994349835__endTimestamp 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | ------- Summary: start ------- 15:52:29 INFO - PROCESS | 21910 | Number of tests: 14 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:1.72 (1.5%) stddev-sans-first:1.73 15:52:29 INFO - PROCESS | 21910 | Values: 112.0 106.0 110.0 111.0 111.0 111.0 111.0 111.0 112.0 110.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#1] audio-beat-detection Cycles:10 Average:150.70 Median:155.00 stddev:18.13 (11.7%) stddev-sans-first:18.76 15:52:29 INFO - PROCESS | 21910 | Values: 162.0 133.0 152.0 172.0 137.0 158.0 150.0 127.0 182.0 134.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#2] audio-dft Cycles:10 Average:162.40 Median:161.50 stddev:2.84 (1.8%) stddev-sans-first:2.96 15:52:29 INFO - PROCESS | 21910 | Values: 161.0 161.0 162.0 160.0 162.0 166.0 169.0 161.0 161.0 161.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#3] audio-fft Cycles:10 Average:93.70 Median:75.00 stddev:39.44 (52.6%) stddev-sans-first:41.18 15:52:29 INFO - PROCESS | 21910 | Values: 74.0 75.0 74.0 170.0 75.0 76.0 74.0 77.0 167.0 75.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#4] audio-oscillator Cycles:10 Average:102.00 Median:84.50 stddev:27.89 (33.0%) stddev-sans-first:28.81 15:52:29 INFO - PROCESS | 21910 | Values: 84.0 85.0 142.0 86.0 85.0 84.0 146.0 85.0 84.0 139.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 15:52:29 INFO - PROCESS | 21910 | Values: 117.0 117.0 118.0 118.0 118.0 117.0 117.0 117.0 118.0 118.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.50 stddev:1.03 (0.7%) stddev-sans-first:0.87 15:52:29 INFO - PROCESS | 21910 | Values: 149.0 148.0 146.0 146.0 148.0 147.0 147.0 148.0 147.0 146.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 15:52:29 INFO - PROCESS | 21910 | Values: 97.0 96.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:55.50 stddev:2.28 (4.1%) stddev-sans-first:2.17 15:52:29 INFO - PROCESS | 21910 | Values: 53.0 54.0 55.0 55.0 56.0 55.0 61.0 58.0 57.0 55.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:1.23 (2.0%) stddev-sans-first:1.22 15:52:29 INFO - PROCESS | 21910 | Values: 62.0 63.0 62.0 63.0 66.0 64.0 63.0 62.0 64.0 63.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:83.50 stddev:8.10 (9.7%) stddev-sans-first:8.49 15:52:29 INFO - PROCESS | 21910 | Values: 83.0 82.0 84.0 88.0 85.0 81.0 81.0 90.0 108.0 83.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#11] stanford-crypto-ccm Cycles:10 Average:129.00 Median:130.00 stddev:5.33 (4.1%) stddev-sans-first:5.34 15:52:29 INFO - PROCESS | 21910 | Values: 134.0 125.0 124.0 130.0 123.0 129.0 133.0 130.0 139.0 123.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.00 Median:163.50 stddev:3.59 (2.2%) stddev-sans-first:3.73 15:52:29 INFO - PROCESS | 21910 | Values: 161.0 171.0 163.0 163.0 164.0 158.0 165.0 162.0 164.0 159.0 15:52:29 INFO - PROCESS | 21910 | 15:52:29 INFO - PROCESS | 21910 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:64.00 stddev:1.58 (2.5%) stddev-sans-first:1.36 15:52:29 INFO - PROCESS | 21910 | Values: 66.0 65.0 63.0 64.0 63.0 61.0 64.0 64.0 63.0 61.0 15:52:29 INFO - PROCESS | 21910 | -------- Summary: end -------- 15:52:29 INFO - PROCESS | 21910 | 15:52:30 INFO - TEST-INFO | 21910: exit 0 15:52:30 INFO - TEST-OK | kraken | took 62065ms 15:52:30 INFO - SUITE-END | took 1173s 15:52:30 INFO - Completed test suite (00:19:33) 15:52:31 INFO - javascript benchmark 15:52:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7269.454290326632, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3098.2409309719114, 3149.4016136933983, 3153.306272620023, 3165.6359518655363, 3186.984065079676, 5395.0, 5413.621046526745, 5435.728213589319, 5517.5585953123755, 5536.640118392312, 5257.7108259045735, 5291.735413229333, 5378.569714422846, 5401.945980540193, 5433.103537916249, 4760.047990401925, 4803.663743537953, 4815.34992775976, 4930.433000204968, 4995.700257984515, 4833.782479788418, 5087.745612719368, 5098.643094983353, 5115.514026167527, 5115.820946266881, 4890.975545122287, 4958.057968985881, 4969.105560999019, 5031.169856973595, 5035.974820125913, 5005.424376196711, 5019.64862459628, 5019.749012549358, 5021.774020169101, 5089.491050894918, 5002.1496345621335, 5013.172826483626, 5015.27278544609, 5016.849494515171, 5067.265246539268, 5162.328897243372, 5350.224217488442, 5365.463453654643, 5405.243265942771, 5438.864028399322, 5831.096180092092, 5864.736086876101, 5886.70566471679, 5891.587588868781, 5901.203337549418, 5729.942700573024, 5946.286445626509, 5946.940530594682, 5955.04719244923, 5971.820845374602, 5335.0, 5349.51854333108, 5368.516833484966, 5409.43200963901, 5455.236266922634, 5902.2032025676845, 5914.9112763308585, 5920.496757775637, 5922.585419020627, 5931.673757943268, 5551.361593416728, 5669.036263835075, 5675.687837168995, 5678.886422271531, 5681.318241811009, 5768.278965129359, 5814.06974884025, 5817.127430885316, 5875.383084776123, 5875.383084776123, 5672.602917795714, 5685.175649530877, 5787.565932555075, 5834.27071616048, 5845.298564172242, 5747.913781293284, 5757.992351338499, 5791.391903850035, 5805.390434004453, 5806.0, 4359.738415695068, 4389.407429996973, 4432.069265454226, 4458.152950939376, 4466.3747075409465, 3965.8017099144927, 3967.444557761916, 3973.5033120859894, 4013.13717550728, 4020.3165461871554, 5347.385050719217, 5637.154426835926, 5654.208410822488, 5701.429857014348, 5706.800261990894, 5804.506616937523, 5856.560757943172, 5866.0, 5883.61756485827, 5895.64626122434, 5555.2222688823595, 5576.358718747396, 5583.190437386556, 5610.410906854721, 5612.298462692163, 4762.9523704762505, 4773.570378665867, 4875.975620121948, 4883.780229889663, 4922.310876477296, 5919.378465261172, 5944.791932282349, 6000.399960003964, 6012.917674818487, 6055.818325450244, 5801.970990145022, 5809.622374545641, 5816.331121921034, 5822.94177058224, 5832.154337621106, 5803.883922321615, 5947.137665038632, 5958.433948774859, 5981.1028345746745, 5983.222181116601, 5768.221290125779, 5771.307443106854, 5774.307083150049, 5775.48020678141, 5840.474357307863, 5845.298564172326, 5882.411758824083, 5892.35184129754, 5897.764089436375, 5912.231409916683, 5648.096304591246, 5682.37493875665, 5724.427557244242, 5733.856653583527, 5749.885002299847, 5483.835484935458, 5724.65652060861, 5794.391588883106, 5815.534757219497, 5873.647581144974, 5655.802046928337, 5700.686462244616, 5704.914426283526, 5714.0, 5714.3714191438075, 5531.280933478622, 5776.046952252831, 5778.191053252632, 5828.154917537018, 5843.444872737083, 5719.5138413235345, 5735.13972904051, 5756.30924289088, 5769.336526299361, 5807.76768929255], "value": 5374.1826554474665, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.7708916433426, 601.4977493792683, 631.3402494393359, 634.9587276827009, 649.2792999770257, 39976.00059998502, 42932.14135717288, 43344.78327608357, 43349.349759753626, 43438.348424773656, 44405.555944440544, 45154.32268515975, 45168.0, 45237.32144017842, 45373.09253814922, 8557.272631826287, 8718.607662655195, 8810.911890881072, 8811.383203175781, 8814.559272036404, 8563.95718021409, 8694.130586941286, 8744.387892847502, 8760.43057201283, 8760.780980475476, 8763.123687631218, 8799.164079412445, 8820.735377938672, 8820.823583528325, 8826.073262307462, 8730.86903696445, 8777.956110219471, 8778.56107194641, 8785.253253473462, 8807.207351338411, 8603.784905377353, 8604.52675102869, 8650.524221167832, 8686.001109872383, 8726.214640682369, 41445.79277103595, 41761.37357939603, 41814.37278440826, 41915.790421048005, 41942.370864437056, 3018.351054523266, 3403.489476578533, 3404.6425125361975, 3539.557555305587, 3641.198936234024, 31273.0, 31289.530657040163, 31397.84301078503, 31471.84264078688, 32799.18002049944, 4168.854090106832, 4213.094184750294, 4223.809928553223, 4250.744955302661, 4336.479622445327, 287014.0, 288040.0, 288768.0, 288783.55608222034, 290566.0, 35347.82326088353, 35954.8202258987, 36232.45651315233, 36300.63699363025, 36332.81833590868, 35173.0, 37238.62761372406, 37312.81343593319, 37321.62678373236, 37380.62619373826, 15621.218939053002, 15733.0, 15741.763873541906, 15757.290921908312, 15761.29074191664, 12219.938900305619, 12288.631341059783, 12303.323317217639, 12319.199252048587, 12353.752924941451, 8363.540005299767, 8408.915910840813, 8416.747497575083, 8427.41008129425, 8499.490030598185, 3182.8567714452442, 3247.821369824635, 3248.7401007919784, 3259.6414394416597, 3280.9343813123605, 1030.6598822388594, 1036.79782442423, 1101.9943702461444, 1135.9889698168636, 1150.016735690989, 29866.104016879093, 29878.40243195124, 29885.701142988728, 30315.0, 32109.678903211137, 1112.2547892911768, 1132.7847708935276, 1133.6145710458484, 1134.6425875849081, 1147.4778975566098, 15792.21038948048, 16369.83630163707, 16576.585585360463, 16781.077040762877, 16834.747478787827, 578021.0, 578600.0, 580350.0, 584196.0, 588482.0, 59311.0, 60135.39864601298, 60647.393526064174, 61002.38997609967, 61145.38854611397, 1402.9438822446987, 1414.1797757300742, 1439.8992070554962, 1447.5657302809325, 1449.8840092792552, 29653.0, 29745.0, 29804.850975744983, 29976.70023299739, 31623.367532649678, 51443.228351575504, 55740.0, 57118.42881571297, 57327.0, 62298.37701622926, 513071.0, 514668.0, 515828.0, 520927.0, 521442.0, 44352.0, 44397.33403999007, 45339.77330113328, 45521.0, 46199.53800461952, 49653.0, 49794.0, 50539.2203428815, 50617.74691126521, 51546.484535154166, 41603.7919810399, 41922.58077419187, 42356.36465452959, 43297.567024330616, 43550.56449435465, 40095.799521002205, 41527.377089344285, 42109.78945105255, 42178.0, 42720.35919461273, 34562.98366081036, 41870.1625967485, 42531.7873410631, 42548.78725606352, 42814.5718542823, 36121.277574447835, 38038.0, 38063.0, 38160.80919595384, 38592.42111368276, 26737.0, 26987.460250795266, 27107.0, 27197.592036119077, 27206.183814485597], "value": 19003.744080564768, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.97007724420666, 401.620468657119, 401.93368094264446, 402.2979900074371, 421.7331841352729, 13953.720925581507, 17861.106944652765, 18542.536436589093, 19380.418587442382, 19407.02964851757, 18312.63374732508, 18417.44747657569, 18673.62652746944, 19113.0, 19240.807591924076, 5444.210589464526, 5473.917891231625, 5674.035413979613, 5678.943210567903, 5697.515711164556, 5296.470352964692, 5571.916421253684, 5578.804741834037, 5614.129809879475, 5632.239647647577, 5593.0, 5619.5785316101255, 5628.437156284359, 5642.4357564243455, 5688.175214593882, 6016.217891674076, 6381.425671689523, 6403.583767055173, 6422.068800023971, 6433.806985790434, 5480.862978425571, 5572.888542229173, 5598.916016259759, 5642.7178641067785, 5659.660420374791, 13887.652808679763, 14262.64343391413, 14491.92754036223, 23143.421414464603, 23181.88409057961, 2145.3992881993067, 2240.171136679439, 2254.2786308381324, 2410.867402292873, 2744.7118052604587, 10144.13774829133, 10387.532561034695, 10390.948045259802, 10635.255532112755, 10747.355158690425, 1885.3401309541687, 1951.9414417567496, 1952.2678995376734, 1980.9995952044126, 2156.9568608627737, 80632.19367806365, 81235.18764812335, 84113.15886841176, 85176.0, 86742.5662871688, 10860.13118950482, 11100.278481898651, 11147.275427097213, 11181.83227251592, 11977.341246231366, 15742.606434839221, 15820.0, 15887.126208058437, 15921.12433816128, 15985.44050958211, 8137.8372432552205, 8451.112633173429, 8460.111688272766, 8542.914570854336, 8555.14448555152, 7953.920460795433, 7977.082635496876, 7985.760427187193, 8083.232092951161, 8140.83718325642, 4769.57073863354, 4771.928421073687, 4810.062037902575, 4901.166801643729, 4921.163402221631, 1903.3338331583834, 1915.6647586672277, 1994.7406837111082, 2001.6897380905982, 2035.2266138867135, 892.1747383670079, 985.2315194148575, 1017.730461071814, 1018.7351288665001, 1022.9232807539464, 18224.362147325046, 18247.54381140482, 18522.722159167624, 18882.433526994213, 19527.707084393747, 941.4539567051094, 998.6804222648683, 1006.1347241372414, 1010.5503051142318, 1033.256055639938, 10499.514553488232, 10561.207909406825, 10582.523786429629, 10597.41714205726, 10864.728381790519, 508939.0, 512091.87908121187, 512423.0, 515226.0, 516286.0, 76035.0, 83615.58192209, 85926.57036714777, 91592.54203729072, 94115.0, 990.4841702076789, 999.8650182225452, 1001.3391161833155, 1031.4533297352416, 1037.7872536130076, 16582.336706531605, 17080.402185923434, 17556.561085972953, 17995.280188792825, 18123.36568220106, 44233.778831105636, 44250.778746106065, 45116.0, 45191.0, 45827.0, 56116.71941640266, 57679.0, 58238.4176158233, 59039.0, 60285.0, 7343.155537113087, 7555.13115991668, 7645.847083058196, 8774.122587741174, 9256.907430925605, 9442.008589098046, 12801.359932003548, 12921.870781292067, 12946.0, 13754.587362379145, 11878.524859005543, 12172.939135304267, 12175.14773965814, 12247.448864801283, 12266.938665306616, 11549.0, 11753.0, 11760.82358764602, 11791.882081179421, 11922.940385298018, 11713.414329283672, 12174.75650487003, 12223.266604003788, 12409.689757756129, 12415.317157556421, 8913.866292005754, 8950.910490895269, 9081.09189081113, 9208.309376796633, 9235.722928312161, 11811.114166437723, 12002.879971200176, 12052.216605920587, 12237.20458170216, 12346.13577049598], "value": 9222.431324060513, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1487.9553613391595, 1509.0945432740357, 1640.876934229933, 1799.5501124718821, 1803.6933721267383, 8117.837643247146, 8525.957370213147, 8553.914460855389, 9102.089791020895, 9110.772230694227, 8598.656053757843, 8767.693130740425, 8821.603027863746, 8896.06591307913, 8936.687215947442, 2313.895874685639, 2354.6585745066955, 2362.1024010875844, 2365.31405892291, 2365.8225633077586, 2345.4019225097577, 2365.988170059156, 2374.525094981002, 2377.4769550698907, 2379.0, 2322.082777302964, 2344.6951896253463, 2357.4460001899624, 2378.048780487801, 2384.6899903012586, 2303.942401439969, 2321.7446080931086, 2331.8484298520543, 2349.177787774274, 2353.446939969106, 2307.4462129089065, 2325.616079462698, 2327.1854850802083, 2338.707661542307, 2348.9647655285185, 8800.0, 8863.202311791969, 8920.107989201093, 8925.0, 8933.821323573557, 5185.637005409623, 5239.0, 5239.816606418795, 5295.126304159809, 5295.602829787781, 3313.5029745538122, 3318.668133186686, 3408.4205685033603, 3415.3681568909583, 3551.6093229744706, 1141.0, 1165.7377090154732, 1193.618042226488, 1203.2419575667361, 1218.5369559567396, 11774.764504709858, 11791.823122653168, 11828.408579570987, 11843.289402635783, 11918.821217681741, 4517.0, 4608.101420223024, 4676.625869930397, 4677.158111539956, 4721.5750582447745, 9973.351732137387, 9981.401115932922, 9991.500424978722, 10021.649242276486, 10028.247881408923, 5214.13966695491, 5253.658512196695, 5265.341832270966, 5270.130428479335, 5272.657277276965, 4350.521442641307, 4609.124266389375, 4676.0, 4717.127331443694, 4773.546513081252, 3964.444977703124, 4007.8196481157834, 4041.555428902818, 4095.365218391154, 4127.9174416511505, 1273.5287943460794, 1286.1897004886862, 1306.73865226955, 1312.6783937935077, 1315.7763180259378, 876.3164731509432, 992.1417973452911, 994.5474808961905, 1006.1598565197995, 1027.121810851726, 5992.910106348409, 6085.574009819358, 6100.115483254903, 6117.265928088658, 6237.157983672237, 469.71112765648877, 470.5905861900169, 475.8453502611665, 486.6398864839993, 488.726313264573, 4196.349565817242, 4221.319000898239, 4228.154369126187, 4352.934705979413, 4374.671899607542, 10848.728781780519, 11126.276792008493, 11232.157588180919, 11243.437828108561, 11279.210455268052, 10920.672379828458, 11064.612738554111, 11100.0, 11106.944465277622, 11138.33170009802, 7956.880646790303, 8228.094909559944, 8292.2951549119, 8298.460600060977, 8404.915950840414, 6075.301340345917, 6170.352113028068, 6174.197354343906, 6274.780382686584, 6297.1813664223355, 9704.805903882025, 9887.901120988698, 10008.949955250177, 10016.348937319051, 10094.242931780293, 9852.408855468695, 9940.552675129782, 10111.3427627204, 10128.139108175888, 10170.135538479017, 7138.571685698875, 7491.625418728932, 7624.733134340271, 7986.600669966362, 8135.267825895698, 8486.278666313432, 8544.615492302737, 8565.314774818124, 8603.91396086031, 8620.784480387789, 7414.666340014604, 7600.201978792147, 7653.349465295512, 7739.3034626883855, 7791.454598178073, 7406.851862962818, 7476.887846682195, 7505.286997735207, 7538.472306938462, 7678.078630564368, 7018.2630823762765, 7019.508634395544, 7029.191642961125, 7052.753153639598, 7106.822329441805, 4987.975060124676, 5036.496350364934, 5075.187969924795, 5111.080005599028, 5142.04872098663, 5103.157978933583, 5183.222516622386, 5273.525382715574, 5290.9735451322495, 5338.733063346739], "value": 4585.67679141035, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.13011819859355, 344.3594913460964, 344.4660775797513, 345.2697544692974, 345.7337849855613, 5657.603967722261, 5690.5447564194865, 5730.713464326788, 5731.885362292752, 5762.337331206907, 5592.832215033545, 5613.775448982046, 5622.606417550773, 5626.342877129868, 5637.8590535236635, 4167.854125105622, 4226.809793559297, 4328.3074708046715, 4337.327714204303, 4355.303151495761, 4308.159908817782, 4336.501302350222, 4365.323374876899, 4387.605115539601, 4391.670624703144, 4292.957070429287, 4326.97836510817, 4331.523532411432, 4333.306670932651, 4336.522982471925, 4194.140201258735, 4239.491261048678, 4299.54854740254, 4304.385855304261, 4313.1804957058375, 3767.9434808477895, 3800.1069748609057, 3812.8284227209842, 3831.697222944212, 3843.442700808395, 5309.761060752276, 5352.464753524655, 5359.356877174725, 5393.2449457076045, 5415.241866138744, 4874.975625121887, 4905.705657660552, 4949.208126699711, 4969.204927211665, 4984.700917944899, 5798.739056742456, 5820.156077368758, 5837.387074357174, 5875.618084824473, 5881.294244690622, 4207.768572728499, 4262.65898728101, 4266.765327906964, 4276.251655960225, 4276.422682937795, 5884.558658100616, 6086.482648974842, 6106.053561697989, 6117.143599896018, 6187.597806142587, 4331.826726930951, 4337.869863904088, 4348.543238015301, 4361.800504861125, 4363.083752411965, 4396.3625274335045, 4406.730533775109, 4413.492448368414, 4427.933580996288, 4438.866833994985, 4787.305840653085, 4957.504249575014, 4960.578350840146, 4992.475790042066, 4999.5500404963, 5834.299884013945, 5887.470127688529, 5891.587588868823, 5902.2622172228475, 5909.172715819789, 1507.8416766239607, 1510.8338082810883, 1533.8159420869567, 1539.9307031183623, 1540.468538354266, 963.2342287881152, 991.7124034030195, 1005.3712984964404, 1007.7329507680471, 1011.2870426349554, 5538.75075621598, 5621.606487545914, 5621.887562248732, 5634.915476267859, 5638.0, 6016.308124565731, 6030.849228769316, 6050.878982420327, 6068.848278793065, 6080.935836228642, 5474.479924407255, 5658.490735833795, 5796.88406231873, 5797.88404231913, 5806.7772386326, 3236.8057916525086, 3243.383757086146, 3248.9675103249137, 3270.4603740383045, 3277.410066188109, 6116.969415152896, 6214.1921550198185, 6264.21697287839, 6264.780732674334, 6367.471806766435, 6177.444030037318, 6190.226221722285, 6228.0, 6242.595416031357, 6246.593971391936, 6046.879062418727, 6259.311475737665, 6262.655553944577, 6286.654234017173, 6307.306196318493, 5994.520438364833, 6094.51243900478, 6105.419985101408, 6106.938930610637, 6111.908321375094, 6004.808244990634, 6026.939730602638, 6052.425019623129, 6069.541835556606, 6111.5110791137495, 6021.668808215591, 6306.211723534559, 6347.68261586928, 6357.1735674362035, 6365.395287447685, 4241.257779888569, 4287.871363859089, 4291.592298731615, 4315.676324275754, 4343.283358245981, 5918.81623675258, 6226.225525725146, 6237.96881015592, 6241.625502469685, 6267.373262673878, 5974.0, 5976.223090998143, 5989.401059893976, 5994.580379373402, 6009.579329446897, 5898.967680655954, 5923.526117910471, 5929.347771745191, 5936.486196019987, 5956.731947062306, 5697.0, 5784.698442850325, 5812.447817457335, 5819.563532734977, 5860.326062502867, 5878.706064696663, 5911.408859114054, 5916.615419997686, 5916.970415147896, 5917.497012753965, 5508.3114610673665, 5756.625819321731, 5771.769129234784, 5845.473907348359, 5857.27210472903], "value": 4498.470197554245, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.5427974634838, 781.847539729753, 803.3533005930224, 826.89663792026, 870.7953630896739, 14243.643908902282, 14271.286435678227, 15131.470398536054, 15340.61648458789, 15522.301496432645, 15051.62370940727, 15672.84327156728, 15692.05847649139, 15813.762793558106, 15814.683706325866, 791.3669064748178, 803.028335713787, 804.4127786715701, 805.1706742055677, 811.0834756724893, 806.1817255485687, 812.0174588747623, 813.5972693516688, 817.889584906036, 818.1409520004001, 845.5391811462737, 852.8294341131767, 854.7948492361851, 855.2089317381428, 855.3114742632179, 835.7367429259795, 838.8825564420986, 842.4566154830162, 846.4159729786428, 846.4709556527201, 616.4236438929615, 619.7737825693634, 620.3176505843581, 620.8603064310539, 621.0156901311427, 13730.0, 13745.0, 13757.862421375758, 13778.379972901244, 13867.167969921837, 14837.925810370987, 14888.404463821435, 15106.244687765568, 15184.240787960558, 15422.99750516224, 11907.226030308002, 11996.160268781188, 12313.0, 12636.178648387826, 12707.174033687874, 3937.606239376068, 3939.409088636699, 3970.7816070116132, 4102.815373308208, 4110.7328023678665, 78167.44739460156, 79416.60291698505, 79919.0, 80468.0, 80627.19372806256, 11274.041706455044, 11534.826977595343, 11577.76844463106, 11784.116191285517, 11945.58190463347, 21942.45143871384, 22070.66893996591, 22157.778422215895, 22178.778212217672, 22251.777482225294, 8263.917360826435, 8278.8344233115, 8313.875291870627, 8318.585840407146, 8692.652293908173, 4808.182608956486, 4858.295547145644, 4880.511948805091, 4889.3888263967, 5177.171652535576, 3392.9660703393142, 3518.3139287838626, 3520.383932811799, 3523.1016090896655, 3523.9119022024656, 613.2518327640272, 618.0543768034913, 622.5175488996065, 634.0299342006737, 641.0224407778176, 686.6429456682597, 769.9807504812425, 788.0661416221795, 799.0443192013508, 799.7121036426998, 10425.165986721044, 10433.582656693647, 10469.790604187874, 10656.840147397796, 10658.413787241776, 644.6035688051815, 657.5857209957696, 687.8246047258017, 756.9894191254737, 766.6856588798565, 4317.913641727211, 4632.745199014019, 4679.125739674479, 4824.56578907893, 4881.316615673809, 74805.0, 79560.0, 85635.0, 129816.3509182448, 130184.0, 105471.47264263629, 105530.0, 105806.47096764467, 106488.46755766172, 108162.45918770356, 92206.53896730473, 94079.0, 96119.51940240254, 97487.51256243815, 98979.50510247402, 9199.724008279762, 9320.207782338306, 9374.718758437259, 9501.714948551555, 9762.0, 53550.46449535455, 53557.0, 53854.461455384946, 54799.72600136974, 55373.0, 70636.0, 78549.60725196337, 79466.0, 82039.58980205061, 83674.58162709148, 11878.762424751629, 11917.642470725892, 12180.147389682637, 12250.877491224974, 12431.191972521758, 13596.0, 14026.08830426019, 14069.718605628035, 14101.576952691436, 14201.976384635767, 12544.560940367044, 12945.223286602833, 13077.0, 13132.146410483288, 13725.588232353046, 12371.51541814988, 12377.62867113988, 12640.494380224687, 12678.429470673658, 12996.220226786423, 12683.175593586388, 12721.328392048037, 13099.148555343872, 13341.19952802835, 13367.398467069199, 10685.0, 10814.513346899252, 10970.890291096986, 11091.168162388014, 11182.944085279521, 10690.792762171473, 10806.0, 10877.78244435091, 10949.890501095206, 10987.505562249878], "value": 7594.598530897969, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 110.0, 111.0, 111.0, 111.0, 111.0, 111.0, 112.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 133.0, 152.0, 172.0, 137.0, 158.0, 150.0, 127.0, 182.0, 134.0], "value": 150.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 160.0, 162.0, 166.0, 169.0, 161.0, 161.0, 161.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 170.0, 75.0, 76.0, 74.0, 77.0, 167.0, 75.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 142.0, 86.0, 85.0, 84.0, 146.0, 85.0, 84.0, 139.0], "value": 102.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 146.0, 146.0, 148.0, 147.0, 147.0, 148.0, 147.0, 146.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 55.0, 55.0, 56.0, 55.0, 61.0, 58.0, 57.0, 55.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 66.0, 64.0, 63.0, 62.0, 64.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 84.0, 88.0, 85.0, 81.0, 81.0, 90.0, 108.0, 83.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 125.0, 124.0, 130.0, 123.0, 129.0, 133.0, 130.0, 139.0, 123.0], "value": 129.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 171.0, 163.0, 163.0, 164.0, 158.0, 165.0, 162.0, 164.0, 159.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 63.0, 64.0, 63.0, 61.0, 64.0, 64.0, 63.0, 61.0], "value": 63.4, "unit": "score"}]}]} 15:52:31 INFO - Return code: 0 15:52:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:52:31 ERROR - # TBPL SUCCESS # 15:52:31 INFO - Running post-action listener: _resource_record_post_action 15:52:31 INFO - ##### 15:52:31 INFO - ##### Finished run-tests step (success) 15:52:31 INFO - ##### 15:52:31 INFO - Running post-run listener: _resource_record_post_run 15:52:31 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 541102080; Read time: 0; Write time: 485400 15:52:31 INFO - TinderboxPrint: CPU usage
13.5% 15:52:31 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:52:31 INFO - TinderboxPrint: I/O write bytes / time
541,102,080 / 485,400 15:52:31 INFO - TinderboxPrint: CPU idle
8,222.7 (86.5%) 15:52:31 INFO - TinderboxPrint: CPU user
1,223.6 (12.9%) 15:52:31 INFO - TinderboxPrint: Swap in / out
0 / 0 15:52:31 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 248455168; Read time: 0; Write time: 290288 15:52:31 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 292646912; Read time: 0; Write time: 195112 15:52:31 INFO - Running post-run listener: _upload_blobber_files 15:52:31 INFO - Blob upload gear active. 15:52:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:52:31 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 15:52:31 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:52:31 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:52:32 INFO - (blobuploader) - INFO - Open directory for files ... 15:52:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:52:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:52:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:32 INFO - (blobuploader) - INFO - Done attempting. 15:52:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:52:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:52:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:36 INFO - (blobuploader) - INFO - Done attempting. 15:52:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:52:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:52:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:37 INFO - (blobuploader) - INFO - Done attempting. 15:52:37 INFO - (blobuploader) - INFO - Iteration through files over. 15:52:37 INFO - Return code: 0 15:52:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:52:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:52:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d03d14a7009f2d96e22db9cd7bd246e966b640e023e9c4a68d2a3d7b26eaecc25b16381b348a564e67440f5fcfa7f7663bdbfd29521fd0e010ba3e934f17e099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b407e16d189b50de387f339000d64047574696b3031dfce9df2b48c939bcc1e2496bd49fbd89ce83e452045d807d6532fe47c78b65461216f7f0d3ca24531cca", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0316d1774c48b2437e65309bfffe75bb1a06d24850e783337c8cf676fef150161a1c158e99e50e2a076894cc1dea4ef6a962c1feb5be4b13519efdb516736762"} 15:52:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:52:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:52:37 INFO - Contents: 15:52:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d03d14a7009f2d96e22db9cd7bd246e966b640e023e9c4a68d2a3d7b26eaecc25b16381b348a564e67440f5fcfa7f7663bdbfd29521fd0e010ba3e934f17e099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b407e16d189b50de387f339000d64047574696b3031dfce9df2b48c939bcc1e2496bd49fbd89ce83e452045d807d6532fe47c78b65461216f7f0d3ca24531cca", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0316d1774c48b2437e65309bfffe75bb1a06d24850e783337c8cf676fef150161a1c158e99e50e2a076894cc1dea4ef6a962c1feb5be4b13519efdb516736762"} 15:52:37 INFO - Running post-run listener: copy_logs_to_upload_dir 15:52:37 INFO - Copying logs to upload dir... 15:52:37 INFO - mkdir: /builds/slave/test/build/upload/logs 15:52:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.157554 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-06-03 15:52:37.723945) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:37.727060) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d03d14a7009f2d96e22db9cd7bd246e966b640e023e9c4a68d2a3d7b26eaecc25b16381b348a564e67440f5fcfa7f7663bdbfd29521fd0e010ba3e934f17e099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b407e16d189b50de387f339000d64047574696b3031dfce9df2b48c939bcc1e2496bd49fbd89ce83e452045d807d6532fe47c78b65461216f7f0d3ca24531cca", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0316d1774c48b2437e65309bfffe75bb1a06d24850e783337c8cf676fef150161a1c158e99e50e2a076894cc1dea4ef6a962c1feb5be4b13519efdb516736762"} build_url:https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011399 build_url: 'https://queue.taskcluster.net/v1/task/O1_6xS7RQ4WwQ9Xqfz2sCA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d03d14a7009f2d96e22db9cd7bd246e966b640e023e9c4a68d2a3d7b26eaecc25b16381b348a564e67440f5fcfa7f7663bdbfd29521fd0e010ba3e934f17e099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b407e16d189b50de387f339000d64047574696b3031dfce9df2b48c939bcc1e2496bd49fbd89ce83e452045d807d6532fe47c78b65461216f7f0d3ca24531cca", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0316d1774c48b2437e65309bfffe75bb1a06d24850e783337c8cf676fef150161a1c158e99e50e2a076894cc1dea4ef6a962c1feb5be4b13519efdb516736762"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:37.752009) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:37.752309) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1464992828.856056-985191879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004490 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:52:37.838254) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 15:52:37.838601) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 15:52:37.838904) ========= ========= Total master_lag: 0.17 =========