builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-043 starttime: 1465276084.61 results: success (0) buildid: 20160606193115 builduid: f7cd58f2475642de85b939e7c85d4def revision: 6ff433eee86b1f64f9ee0955ced57cb215e82dc5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:04.610057) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:04.610580) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:04.610907) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:04.688344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:04.688691) ========= 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=d2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-06 22:08:04-- 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% 224M=0s 2016-06-06 22:08:05 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.171610 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:04.874833) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:04.875160) ========= 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=d2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018025 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:04.924380) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:04.924752) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 6ff433eee86b1f64f9ee0955ced57cb215e82dc5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 6ff433eee86b1f64f9ee0955ced57cb215e82dc5 --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=d2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-06 22:08:05,224 truncating revision to first 12 chars 2016-06-06 22:08:05,224 Setting DEBUG logging. 2016-06-06 22:08:05,224 attempt 1/10 2016-06-06 22:08:05,224 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/6ff433eee86b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 22:08:05,752 unpacking tar archive at: mozilla-inbound-6ff433eee86b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.862493 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:05.801193) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:05.801572) ========= script_repo_revision: 6ff433eee86b1f64f9ee0955ced57cb215e82dc5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:05.802038) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:05.802351) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 22:08:05.813374) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-06-06 22:08:05.813778) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=d2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:08:06 INFO - MultiFileLogger online at 20160606 22:08:06 in /builds/slave/test 22:08:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:08:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:08:06 INFO - {'append_to_log': False, 22:08:06 INFO - 'base_work_dir': '/builds/slave/test', 22:08:06 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:08:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:08:06 INFO - 'branch': 'Mozilla-Inbound', 22:08:06 INFO - 'buildbot_json_path': 'buildprops.json', 22:08:06 INFO - 'config_files': ('talos/linux_config.py',), 22:08:06 INFO - 'default_actions': ('clobber', 22:08:06 INFO - 'read-buildbot-config', 22:08:06 INFO - 'download-and-extract', 22:08:06 INFO - 'populate-webroot', 22:08:06 INFO - 'create-virtualenv', 22:08:06 INFO - 'install', 22:08:06 INFO - 'run-tests'), 22:08:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:08:06 INFO - 'download_minidump_stackwalk': True, 22:08:06 INFO - 'download_symbols': 'ondemand', 22:08:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:08:06 INFO - 'tooltool.py': '/tools/tooltool.py', 22:08:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:08:06 INFO - '/tools/misc-python/virtualenv.py')}, 22:08:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:08:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:08:06 INFO - 'installer_path': 'installer.exe', 22:08:06 INFO - 'log_level': 'info', 22:08:06 INFO - 'log_name': 'talos', 22:08:06 INFO - 'log_to_console': True, 22:08:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:08:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:08:06 INFO - 'opt_config_files': (), 22:08:06 INFO - 'pip_index': False, 22:08:06 INFO - 'sps_profile': False, 22:08:06 INFO - 'sps_profile_interval': 0, 22:08:06 INFO - 'suite': 'dromaeojs-e10s', 22:08:06 INFO - 'system_bits': '32', 22:08:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:08:06 INFO - 'title': 'talos-linux64-ix-043', 22:08:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:08:06 INFO - 'use_talos_json': True, 22:08:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:08:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:08:06 INFO - 'work_dir': 'build'} 22:08:06 INFO - ##### 22:08:06 INFO - ##### Running clobber step. 22:08:06 INFO - ##### 22:08:06 INFO - Running pre-action listener: _resource_record_pre_action 22:08:06 INFO - Running main action method: clobber 22:08:06 INFO - rmtree: /builds/slave/test/build 22:08:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:08:06 INFO - Running post-action listener: _resource_record_post_action 22:08:06 INFO - ##### 22:08:06 INFO - ##### Finished clobber step (success) 22:08:06 INFO - ##### 22:08:06 INFO - ##### 22:08:06 INFO - ##### Running read-buildbot-config step. 22:08:06 INFO - ##### 22:08:06 INFO - Running pre-action listener: _resource_record_pre_action 22:08:06 INFO - Running main action method: read_buildbot_config 22:08:06 INFO - Using buildbot properties: 22:08:06 INFO - { 22:08:06 INFO - "project": "", 22:08:06 INFO - "product": "firefox", 22:08:06 INFO - "script_repo_revision": "production", 22:08:06 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 22:08:06 INFO - "repository": "", 22:08:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 22:08:06 INFO - "buildid": "20160606193115", 22:08:06 INFO - "pgo_build": "True", 22:08:06 INFO - "basedir": "/builds/slave/test", 22:08:06 INFO - "buildnumber": 814, 22:08:06 INFO - "slavename": "talos-linux64-ix-043", 22:08:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:08:06 INFO - "platform": "ubuntu64_hw", 22:08:06 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:08:06 INFO - "branch": "mozilla-inbound", 22:08:06 INFO - "repo_path": "integration/mozilla-inbound", 22:08:06 INFO - "revision": "6ff433eee86b1f64f9ee0955ced57cb215e82dc5", 22:08:06 INFO - "stage_platform": "linux64-pgo", 22:08:06 INFO - "builduid": "f7cd58f2475642de85b939e7c85d4def", 22:08:06 INFO - "slavebuilddir": "test" 22:08:06 INFO - } 22:08:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:08:06 INFO - Running post-action listener: _resource_record_post_action 22:08:06 INFO - ##### 22:08:06 INFO - ##### Finished read-buildbot-config step (success) 22:08:06 INFO - ##### 22:08:06 INFO - ##### 22:08:06 INFO - ##### Running download-and-extract step. 22:08:06 INFO - ##### 22:08:06 INFO - Running pre-action listener: _resource_record_pre_action 22:08:06 INFO - Running main action method: download_and_extract 22:08:06 INFO - mkdir: /builds/slave/test/build/tests 22:08:06 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')]} 22:08:06 INFO - https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:08:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:08:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:08:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:08:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:08:12 INFO - Downloaded 1361 bytes. 22:08:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:08:12 INFO - Using the following test package requirements: 22:08:12 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:08:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:08:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:08:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:12 INFO - u'jsshell-linux-x86_64.zip'], 22:08:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:08:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:08:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:08:12 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:08:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:08:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:08:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:08: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')]} 22:08:12 INFO - https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:08:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:08:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:08:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:08:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:08:17 INFO - Downloaded 24445808 bytes. 22:08:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:08:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:08:18 INFO - Return code: 0 22:08:18 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:08:18 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')]} 22:08:18 INFO - https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:08:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:08:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:08:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:08:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:08:21 INFO - Downloaded 24445808 bytes. 22:08:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:08:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:08:23 INFO - Return code: 0 22:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:23 INFO - https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:08:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:08:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:08:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:08:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:08:24 INFO - Downloaded 10833377 bytes. 22:08:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:08:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:08:24 INFO - Return code: 0 22:08:24 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')]} 22:08:24 INFO - https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:08:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:08:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:08:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:08:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:08:26 INFO - Downloaded 59255930 bytes. 22:08:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:08:26 INFO - mkdir: /builds/slave/test/properties 22:08:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:08:26 INFO - Writing to file /builds/slave/test/properties/build_url 22:08:26 INFO - Contents: 22:08:26 INFO - build_url:https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:08:27 INFO - Running post-action listener: _resource_record_post_action 22:08:27 INFO - Running post-action listener: set_extra_try_arguments 22:08:27 INFO - ##### 22:08:27 INFO - ##### Finished download-and-extract step (success) 22:08:27 INFO - ##### 22:08:27 INFO - ##### 22:08:27 INFO - ##### Running populate-webroot step. 22:08:27 INFO - ##### 22:08:27 INFO - Running pre-action listener: _resource_record_pre_action 22:08:27 INFO - Running main action method: populate_webroot 22:08:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:08:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:08:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:08:27 INFO - '--tppagecycles', 22:08:27 INFO - '7'], 22:08:27 INFO - 'tests': ['tsvgm']}}, 22:08:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:08:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:08:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:08:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:08:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:08:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:08:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:27 INFO - 'talos_options': ['--disable-e10s'], 22:08:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:27 INFO - 'talos_options': ['--disable-e10s'], 22:08:27 INFO - 'tests': ['damp', 'tps']}, 22:08:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:27 INFO - 'tests': ['damp', 'tps']}, 22:08:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:08:27 INFO - 'tests': ['dromaeo_dom']}, 22:08:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:08:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:08:27 INFO - 'tests': ['a11yr', 22:08:27 INFO - 'ts_paint', 22:08:27 INFO - 'tpaint', 22:08:27 INFO - 'sessionrestore', 22:08:27 INFO - 'sessionrestore_no_auto_restore', 22:08:27 INFO - 'tabpaint']}, 22:08:27 INFO - 'other-e10s': {'tests': ['a11yr', 22:08:27 INFO - 'ts_paint', 22:08:27 INFO - 'tpaint', 22:08:27 INFO - 'sessionrestore', 22:08:27 INFO - 'sessionrestore_no_auto_restore', 22:08:27 INFO - 'tabpaint']}, 22:08:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:08:27 INFO - 'ts_paint', 22:08:27 INFO - 'tpaint', 22:08:27 INFO - 'sessionrestore', 22:08:27 INFO - 'sessionrestore_no_auto_restore', 22:08:27 INFO - 'tabpaint']}, 22:08:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:08:27 INFO - 'ts_paint', 22:08:27 INFO - 'tpaint', 22:08:27 INFO - 'sessionrestore', 22:08:27 INFO - 'sessionrestore_no_auto_restore', 22:08:27 INFO - 'tabpaint']}, 22:08:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:08:27 INFO - 'tests': ['a11yr', 22:08:27 INFO - 'ts_paint', 22:08:27 INFO - 'tpaint', 22:08:27 INFO - 'sessionrestore', 22:08:27 INFO - 'sessionrestore_no_auto_restore', 22:08:27 INFO - 'tabpaint']}, 22:08:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:08:27 INFO - 'tests': ['a11yr', 22:08:27 INFO - 'ts_paint', 22:08:27 INFO - 'tpaint', 22:08:27 INFO - 'sessionrestore', 22:08:27 INFO - 'sessionrestore_no_auto_restore', 22:08:27 INFO - 'tabpaint']}, 22:08:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:08:27 INFO - 'tests': ['tsvgx', 22:08:27 INFO - 'tsvgr_opacity', 22:08:27 INFO - 'tart', 22:08:27 INFO - 'tscrollx', 22:08:27 INFO - 'cart']}, 22:08:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:08:27 INFO - 'tsvgr_opacity', 22:08:27 INFO - 'tart', 22:08:27 INFO - 'tscrollx', 22:08:27 INFO - 'cart']}, 22:08:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:27 INFO - 'talos_options': ['--disable-e10s'], 22:08:27 INFO - 'tests': ['tp5o']}, 22:08:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:27 INFO - 'tests': ['tp5o']}, 22:08:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:27 INFO - 'talos_options': ['--disable-e10s', 22:08:27 INFO - '--xperf_path', 22:08:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:27 INFO - 'tests': ['tp5n']}, 22:08:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:27 INFO - 'talos_options': ['--xperf_path', 22:08:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:27 INFO - 'tests': ['tp5n']}}, 22:08:27 INFO - 'talos.zip': {'path': '', 22:08:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:08:27 INFO - Running post-action listener: _resource_record_post_action 22:08:27 INFO - ##### 22:08:27 INFO - ##### Finished populate-webroot step (success) 22:08:27 INFO - ##### 22:08:27 INFO - ##### 22:08:27 INFO - ##### Running create-virtualenv step. 22:08:27 INFO - ##### 22:08:27 INFO - Running pre-action listener: _resource_record_pre_action 22:08:27 INFO - Running main action method: create_virtualenv 22:08:27 INFO - Creating virtualenv /builds/slave/test/build/venv 22:08:27 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 22:08:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:08:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:08:27 INFO - Using real prefix '/usr' 22:08:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:08:27 INFO - Installing distribute.............................................................................................................................................................................................done. 22:08:29 INFO - Installing pip.................done. 22:08:29 INFO - Return code: 0 22:08:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:29 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 0x7f4e8b0eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, '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': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', '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 22:08:29 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 22:08:29 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 22:08:29 INFO - Using env: {'DISPLAY': ':0', 22:08:29 INFO - 'HOME': '/home/cltbld', 22:08:29 INFO - 'LANG': 'en_US.UTF-8', 22:08:29 INFO - 'LANGUAGE': 'en_US:en', 22:08:29 INFO - 'LOGNAME': 'cltbld', 22:08:29 INFO - 'MAIL': '/var/mail/cltbld', 22:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:29 INFO - 'MOZ_NO_REMOTE': '1', 22:08:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:29 INFO - 'NO_EM_RESTART': '1', 22:08:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:29 INFO - 'PWD': '/builds/slave/test', 22:08:29 INFO - 'SHELL': '/bin/bash', 22:08:29 INFO - 'SHLVL': '1', 22:08:29 INFO - 'TERM': 'linux', 22:08:29 INFO - 'TMOUT': '86400', 22:08:29 INFO - 'USER': 'cltbld', 22:08:29 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:29 INFO - '_': '/tools/buildbot/bin/python'} 22:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:30 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 22:08:30 INFO - Cleaning up... 22:08:30 INFO - Return code: 0 22:08:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:08:30 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')]} 22:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:30 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')]} 22:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:30 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 0x7f4e8b0eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, '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': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', '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 22:08:30 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 22:08:30 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 22:08:30 INFO - Using env: {'DISPLAY': ':0', 22:08:30 INFO - 'HOME': '/home/cltbld', 22:08:30 INFO - 'LANG': 'en_US.UTF-8', 22:08:30 INFO - 'LANGUAGE': 'en_US:en', 22:08:30 INFO - 'LOGNAME': 'cltbld', 22:08:30 INFO - 'MAIL': '/var/mail/cltbld', 22:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:30 INFO - 'MOZ_NO_REMOTE': '1', 22:08:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:30 INFO - 'NO_EM_RESTART': '1', 22:08:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:30 INFO - 'PWD': '/builds/slave/test', 22:08:30 INFO - 'SHELL': '/bin/bash', 22:08:30 INFO - 'SHLVL': '1', 22:08:30 INFO - 'TERM': 'linux', 22:08:30 INFO - 'TMOUT': '86400', 22:08:30 INFO - 'USER': 'cltbld', 22:08:30 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:30 INFO - '_': '/tools/buildbot/bin/python'} 22:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:30 INFO - Downloading/unpacking psutil>=3.1.1 22:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:08:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:32 INFO - Installing collected packages: psutil 22:08:32 INFO - Running setup.py install for psutil 22:08:32 INFO - building 'psutil._psutil_linux' extension 22:08:32 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 22:08:32 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 22:08:32 INFO - building 'psutil._psutil_posix' extension 22:08:32 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 22:08:32 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 22:08:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:32 INFO - Successfully installed psutil 22:08:32 INFO - Cleaning up... 22:08:32 INFO - Return code: 0 22:08:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:08:32 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')]} 22:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:32 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')]} 22:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:32 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 0x7f4e8b0eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, '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': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', '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 22:08:32 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 22:08:32 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 22:08:32 INFO - Using env: {'DISPLAY': ':0', 22:08:32 INFO - 'HOME': '/home/cltbld', 22:08:32 INFO - 'LANG': 'en_US.UTF-8', 22:08:32 INFO - 'LANGUAGE': 'en_US:en', 22:08:32 INFO - 'LOGNAME': 'cltbld', 22:08:32 INFO - 'MAIL': '/var/mail/cltbld', 22:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:32 INFO - 'MOZ_NO_REMOTE': '1', 22:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:32 INFO - 'NO_EM_RESTART': '1', 22:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:32 INFO - 'PWD': '/builds/slave/test', 22:08:32 INFO - 'SHELL': '/bin/bash', 22:08:32 INFO - 'SHLVL': '1', 22:08:32 INFO - 'TERM': 'linux', 22:08:32 INFO - 'TMOUT': '86400', 22:08:32 INFO - 'USER': 'cltbld', 22:08:32 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:32 INFO - '_': '/tools/buildbot/bin/python'} 22:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:08:35 INFO - Installing collected packages: mozsystemmonitor 22:08:35 INFO - Running setup.py install for mozsystemmonitor 22:08:35 INFO - Successfully installed mozsystemmonitor 22:08:35 INFO - Cleaning up... 22:08:35 INFO - Return code: 0 22:08:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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')]} 22:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4e8b0eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, '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': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', '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 22:08: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==2.5.1'] in /builds/slave/test/build 22:08: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==2.5.1 22:08:35 INFO - Using env: {'DISPLAY': ':0', 22:08:35 INFO - 'HOME': '/home/cltbld', 22:08:35 INFO - 'LANG': 'en_US.UTF-8', 22:08:35 INFO - 'LANGUAGE': 'en_US:en', 22:08:35 INFO - 'LOGNAME': 'cltbld', 22:08:35 INFO - 'MAIL': '/var/mail/cltbld', 22:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:35 INFO - 'MOZ_NO_REMOTE': '1', 22:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:35 INFO - 'NO_EM_RESTART': '1', 22:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:35 INFO - 'PWD': '/builds/slave/test', 22:08:35 INFO - 'SHELL': '/bin/bash', 22:08:35 INFO - 'SHLVL': '1', 22:08:35 INFO - 'TERM': 'linux', 22:08:35 INFO - 'TMOUT': '86400', 22:08:35 INFO - 'USER': 'cltbld', 22:08:35 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:35 INFO - '_': '/tools/buildbot/bin/python'} 22:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:35 INFO - Downloading/unpacking jsonschema==2.5.1 22:08:35 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 22:08:35 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 22:08:35 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 22:08:35 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 22:08:35 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 22:08:35 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 22:08:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:08:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:08:37 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 22:08:37 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 22:08:37 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 22:08:37 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 22:08:37 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 22:08:37 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 22:08:37 INFO - Downloading functools32-3.2.3-2.tar.gz 22:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:08:37 INFO - warning: no files found matching '*.txt' 22:08:37 INFO - no previously-included directories found matching 'build' 22:08:37 INFO - no previously-included directories found matching 'dist' 22:08:37 INFO - no previously-included directories found matching '.git*' 22:08:37 INFO - Installing collected packages: jsonschema, functools32 22:08:37 INFO - Running setup.py install for functools32 22:08:37 INFO - warning: no files found matching '*.txt' 22:08:37 INFO - no previously-included directories found matching 'build' 22:08:37 INFO - no previously-included directories found matching 'dist' 22:08:37 INFO - no previously-included directories found matching '.git*' 22:08:37 INFO - Successfully installed jsonschema functools32 22:08:37 INFO - Cleaning up... 22:08:37 INFO - Return code: 0 22:08:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:37 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 0x7f4e8b0eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, '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': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', '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 22:08:37 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 22:08:37 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 22:08:37 INFO - Using env: {'DISPLAY': ':0', 22:08:37 INFO - 'HOME': '/home/cltbld', 22:08:37 INFO - 'LANG': 'en_US.UTF-8', 22:08:37 INFO - 'LANGUAGE': 'en_US:en', 22:08:37 INFO - 'LOGNAME': 'cltbld', 22:08:37 INFO - 'MAIL': '/var/mail/cltbld', 22:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:37 INFO - 'MOZ_NO_REMOTE': '1', 22:08:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:37 INFO - 'NO_EM_RESTART': '1', 22:08:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:37 INFO - 'PWD': '/builds/slave/test', 22:08:37 INFO - 'SHELL': '/bin/bash', 22:08:37 INFO - 'SHLVL': '1', 22:08:37 INFO - 'TERM': 'linux', 22:08:37 INFO - 'TMOUT': '86400', 22:08:37 INFO - 'USER': 'cltbld', 22:08:37 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:37 INFO - '_': '/tools/buildbot/bin/python'} 22:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:38 INFO - Downloading/unpacking blobuploader==1.2.4 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08:39 INFO - Downloading blobuploader-1.2.4.tar.gz 22:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:08:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:08:40 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 22:08:40 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 22:08:40 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 22:08:40 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 22:08:40 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 22:08:40 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 22:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:08:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:08:40 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 22:08:40 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 22:08:40 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 22:08:40 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 22:08:40 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 22:08:40 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 22:08:40 INFO - Downloading docopt-0.6.1.tar.gz 22:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:08:40 INFO - Installing collected packages: blobuploader, requests, docopt 22:08:40 INFO - Running setup.py install for blobuploader 22:08:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:08:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:08:40 INFO - Running setup.py install for requests 22:08:41 INFO - Running setup.py install for docopt 22:08:41 INFO - Successfully installed blobuploader requests docopt 22:08:41 INFO - Cleaning up... 22:08:41 INFO - Return code: 0 22:08:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:41 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 0x7f4e8b0eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, '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': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', '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 22:08:41 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 22:08:41 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 22:08:41 INFO - Using env: {'DISPLAY': ':0', 22:08:41 INFO - 'HOME': '/home/cltbld', 22:08:41 INFO - 'LANG': 'en_US.UTF-8', 22:08:41 INFO - 'LANGUAGE': 'en_US:en', 22:08:41 INFO - 'LOGNAME': 'cltbld', 22:08:41 INFO - 'MAIL': '/var/mail/cltbld', 22:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:41 INFO - 'MOZ_NO_REMOTE': '1', 22:08:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:41 INFO - 'NO_EM_RESTART': '1', 22:08:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:41 INFO - 'PWD': '/builds/slave/test', 22:08:41 INFO - 'SHELL': '/bin/bash', 22:08:41 INFO - 'SHLVL': '1', 22:08:41 INFO - 'TERM': 'linux', 22:08:41 INFO - 'TMOUT': '86400', 22:08:41 INFO - 'USER': 'cltbld', 22:08:41 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:41 INFO - '_': '/tools/buildbot/bin/python'} 22:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:08:41 INFO - Running setup.py (path:/tmp/pip-NZBZiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:08:41 INFO - Running setup.py (path:/tmp/pip-Kx1ubw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:08:41 INFO - Running setup.py (path:/tmp/pip-5i9kVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:08:41 INFO - Running setup.py (path:/tmp/pip-JsP7Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:08:41 INFO - Running setup.py (path:/tmp/pip-ycyXdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:08:41 INFO - Running setup.py (path:/tmp/pip-iNKZRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:08:41 INFO - Running setup.py (path:/tmp/pip-29v7RZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:08:42 INFO - Running setup.py (path:/tmp/pip-vkgjQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:08:42 INFO - Running setup.py (path:/tmp/pip-fiSUvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:08:42 INFO - Running setup.py (path:/tmp/pip-owrmhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:08:42 INFO - Running setup.py (path:/tmp/pip-Af4ngQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:08:42 INFO - Running setup.py (path:/tmp/pip-HEMOaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:08:42 INFO - Running setup.py (path:/tmp/pip-tpgTuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:08:42 INFO - Running setup.py (path:/tmp/pip-epiYUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:42 INFO - Running setup.py (path:/tmp/pip-_Acz5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:08:42 INFO - Running setup.py (path:/tmp/pip-k6D4m1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:08:42 INFO - Running setup.py (path:/tmp/pip-L1M07o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:08:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:08:42 INFO - Running setup.py install for manifestparser 22:08:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:08:42 INFO - Running setup.py install for mozcrash 22:08:42 INFO - Running setup.py install for mozdebug 22:08:43 INFO - Running setup.py install for mozdevice 22:08:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:08:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:08:43 INFO - Running setup.py install for mozfile 22:08:43 INFO - Running setup.py install for mozhttpd 22:08:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:08:43 INFO - Running setup.py install for mozinfo 22:08:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:08:43 INFO - Running setup.py install for mozInstall 22:08:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:08:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:08:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:08:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:08:43 INFO - Running setup.py install for mozleak 22:08:43 INFO - Running setup.py install for mozlog 22:08:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:08:43 INFO - Running setup.py install for moznetwork 22:08:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:08:43 INFO - Running setup.py install for mozprocess 22:08:43 INFO - Running setup.py install for mozprofile 22:08:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:08:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:08:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:08:44 INFO - Running setup.py install for mozrunner 22:08:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:08:44 INFO - Running setup.py install for mozscreenshot 22:08:44 INFO - Running setup.py install for moztest 22:08:44 INFO - Running setup.py install for mozversion 22:08:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:08:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:08:44 INFO - Cleaning up... 22:08:44 INFO - Return code: 0 22:08:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:44 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 0x7f4e8b0eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, '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': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', '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 22:08:44 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 22:08:44 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 22:08:44 INFO - Using env: {'DISPLAY': ':0', 22:08:44 INFO - 'HOME': '/home/cltbld', 22:08:44 INFO - 'LANG': 'en_US.UTF-8', 22:08:44 INFO - 'LANGUAGE': 'en_US:en', 22:08:44 INFO - 'LOGNAME': 'cltbld', 22:08:44 INFO - 'MAIL': '/var/mail/cltbld', 22:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:44 INFO - 'MOZ_NO_REMOTE': '1', 22:08:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:44 INFO - 'NO_EM_RESTART': '1', 22:08:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:44 INFO - 'PWD': '/builds/slave/test', 22:08:44 INFO - 'SHELL': '/bin/bash', 22:08:44 INFO - 'SHLVL': '1', 22:08:44 INFO - 'TERM': 'linux', 22:08:44 INFO - 'TMOUT': '86400', 22:08:44 INFO - 'USER': 'cltbld', 22:08:44 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:44 INFO - '_': '/tools/buildbot/bin/python'} 22:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:08:44 INFO - Running setup.py (path:/tmp/pip-zEWYLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:08:44 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)) 22:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:08:44 INFO - Running setup.py (path:/tmp/pip-uULtVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:08:44 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)) 22:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:08:44 INFO - Running setup.py (path:/tmp/pip-KJvD_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:08:44 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)) 22:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:08:44 INFO - Running setup.py (path:/tmp/pip-V9eh8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:08:45 INFO - Running setup.py (path:/tmp/pip-X8bfoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:08:45 INFO - Running setup.py (path:/tmp/pip-lgzaPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:08:45 INFO - Running setup.py (path:/tmp/pip-IfR8gM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:08:45 INFO - Running setup.py (path:/tmp/pip-ruXlnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:08:45 INFO - Running setup.py (path:/tmp/pip-KltuGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:08:45 INFO - Running setup.py (path:/tmp/pip-Ads_ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:08:45 INFO - Running setup.py (path:/tmp/pip-yyC6vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:08:45 INFO - Running setup.py (path:/tmp/pip-34Gdm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:08:45 INFO - Running setup.py (path:/tmp/pip-nk7nOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:08:45 INFO - Running setup.py (path:/tmp/pip-m2_B0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:45 INFO - Running setup.py (path:/tmp/pip-T1vPvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:08:45 INFO - Running setup.py (path:/tmp/pip-Sz8WfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:08:45 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)) 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:08:45 INFO - Running setup.py (path:/tmp/pip-m7ZTB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:08:46 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)) 22:08:46 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)) 22:08:46 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)) 22:08:46 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)) 22:08:46 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)) 22:08:46 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)) 22:08:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:08:46 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 22:08:46 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 22:08:46 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 22:08:46 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 22:08:46 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 22:08:46 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 22:08:47 INFO - Downloading blessings-1.6.tar.gz 22:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:08:47 INFO - Installing collected packages: blessings 22:08:47 INFO - Running setup.py install for blessings 22:08:48 INFO - Successfully installed blessings 22:08:48 INFO - Cleaning up... 22:08:48 INFO - Return code: 0 22:08:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:08:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:08:48 INFO - Reading from file tmpfile_stdout 22:08:48 INFO - Current package versions: 22:08:48 INFO - argparse == 1.2.1 22:08:48 INFO - blessings == 1.6 22:08:48 INFO - blobuploader == 1.2.4 22:08:48 INFO - docopt == 0.6.1 22:08:48 INFO - functools32 == 3.2.3-2 22:08:48 INFO - jsonschema == 2.5.1 22:08:48 INFO - manifestparser == 1.1 22:08:48 INFO - mozInstall == 1.12 22:08:48 INFO - mozcrash == 0.17 22:08:48 INFO - mozdebug == 0.1 22:08:48 INFO - mozdevice == 0.48 22:08:48 INFO - mozfile == 1.2 22:08:48 INFO - mozhttpd == 0.7 22:08:48 INFO - mozinfo == 0.9 22:08:48 INFO - mozleak == 0.1 22:08:48 INFO - mozlog == 3.2 22:08:48 INFO - moznetwork == 0.27 22:08:48 INFO - mozprocess == 0.22 22:08:48 INFO - mozprofile == 0.28 22:08:48 INFO - mozrunner == 6.11 22:08:48 INFO - mozscreenshot == 0.1 22:08:48 INFO - mozsystemmonitor == 0.3 22:08:48 INFO - moztest == 0.7 22:08:48 INFO - mozversion == 1.4 22:08:48 INFO - psutil == 3.1.1 22:08:48 INFO - requests == 1.2.3 22:08:48 INFO - wsgiref == 0.1.2 22:08:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:48 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 0x7f4e8b0eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, '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': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', '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 22:08:48 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 22:08:48 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 22:08:48 INFO - Using env: {'DISPLAY': ':0', 22:08:48 INFO - 'HOME': '/home/cltbld', 22:08:48 INFO - 'LANG': 'en_US.UTF-8', 22:08:48 INFO - 'LANGUAGE': 'en_US:en', 22:08:48 INFO - 'LOGNAME': 'cltbld', 22:08:48 INFO - 'MAIL': '/var/mail/cltbld', 22:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:48 INFO - 'MOZ_NO_REMOTE': '1', 22:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:48 INFO - 'NO_EM_RESTART': '1', 22:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:48 INFO - 'PWD': '/builds/slave/test', 22:08:48 INFO - 'SHELL': '/bin/bash', 22:08:48 INFO - 'SHLVL': '1', 22:08:48 INFO - 'TERM': 'linux', 22:08:48 INFO - 'TMOUT': '86400', 22:08:48 INFO - 'USER': 'cltbld', 22:08:48 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:48 INFO - '_': '/tools/buildbot/bin/python'} 22:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:48 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)) 22:08:48 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)) 22:08:48 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)) 22:08:48 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)) 22:08:48 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)) 22:08:48 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)) 22:08:48 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)) 22:08:48 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)) 22:08:48 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)) 22:08:48 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)) 22:08:48 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)) 22:08:48 INFO - Cleaning up... 22:08:48 INFO - Return code: 0 22:08:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:48 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 0x7f4e8b0eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, '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': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', '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 22:08:48 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 22:08:48 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 22:08:48 INFO - Using env: {'DISPLAY': ':0', 22:08:48 INFO - 'HOME': '/home/cltbld', 22:08:48 INFO - 'LANG': 'en_US.UTF-8', 22:08:48 INFO - 'LANGUAGE': 'en_US:en', 22:08:48 INFO - 'LOGNAME': 'cltbld', 22:08:48 INFO - 'MAIL': '/var/mail/cltbld', 22:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:48 INFO - 'MOZ_NO_REMOTE': '1', 22:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:48 INFO - 'NO_EM_RESTART': '1', 22:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:48 INFO - 'PWD': '/builds/slave/test', 22:08:48 INFO - 'SHELL': '/bin/bash', 22:08:48 INFO - 'SHLVL': '1', 22:08:48 INFO - 'TERM': 'linux', 22:08:48 INFO - 'TMOUT': '86400', 22:08:48 INFO - 'USER': 'cltbld', 22:08:48 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:48 INFO - '_': '/tools/buildbot/bin/python'} 22:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:08:48 INFO - Cleaning up... 22:08:48 INFO - Return code: 0 22:08:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:48 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 0x7f4e8b0eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, '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': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', '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 22:08:48 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 22:08:48 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 22:08:48 INFO - Using env: {'DISPLAY': ':0', 22:08:48 INFO - 'HOME': '/home/cltbld', 22:08:48 INFO - 'LANG': 'en_US.UTF-8', 22:08:48 INFO - 'LANGUAGE': 'en_US:en', 22:08:48 INFO - 'LOGNAME': 'cltbld', 22:08:48 INFO - 'MAIL': '/var/mail/cltbld', 22:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:48 INFO - 'MOZ_NO_REMOTE': '1', 22:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:48 INFO - 'NO_EM_RESTART': '1', 22:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:48 INFO - 'PWD': '/builds/slave/test', 22:08:48 INFO - 'SHELL': '/bin/bash', 22:08:48 INFO - 'SHLVL': '1', 22:08:48 INFO - 'TERM': 'linux', 22:08:48 INFO - 'TMOUT': '86400', 22:08:48 INFO - 'USER': 'cltbld', 22:08:48 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:48 INFO - '_': '/tools/buildbot/bin/python'} 22:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:49 INFO - Downloading/unpacking flake8 22:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:08:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:08:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:08:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:08:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:08:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:08:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:08:51 INFO - no previously-included directories found matching 'docs/_build' 22:08:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:08:51 INFO - Running setup.py install for pep8 22:08:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:08:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:08:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:08:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:08:51 INFO - no previously-included directories found matching 'docs/_build' 22:08:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:08:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:08:51 INFO - Cleaning up... 22:08:51 INFO - Return code: 0 22:08:51 INFO - Running post-action listener: _resource_record_post_action 22:08:51 INFO - Running post-action listener: _start_resource_monitoring 22:08:51 INFO - Starting resource monitoring. 22:08:51 INFO - ##### 22:08:51 INFO - ##### Finished create-virtualenv step (success) 22:08:51 INFO - ##### 22:08:51 INFO - ##### 22:08:51 INFO - ##### Running install step. 22:08:51 INFO - ##### 22:08:51 INFO - Running pre-action listener: _resource_record_pre_action 22:08:51 INFO - Running main action method: install 22:08:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:08:52 INFO - Reading from file tmpfile_stdout 22:08:52 INFO - Detecting whether we're running mozinstall >=1.0... 22:08:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:08:52 INFO - Reading from file tmpfile_stdout 22:08:52 INFO - Output received: 22:08:52 INFO - Usage: mozinstall [options] installer 22:08:52 INFO - Options: 22:08:52 INFO - -h, --help show this help message and exit 22:08:52 INFO - -d DEST, --destination=DEST 22:08:52 INFO - Directory to install application into. [default: 22:08:52 INFO - "/builds/slave/test"] 22:08:52 INFO - --app=APP Application being installed. [default: firefox] 22:08:52 INFO - mkdir: /builds/slave/test/build/application 22:08:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:09:09 INFO - Reading from file tmpfile_stdout 22:09:09 INFO - Output received: 22:09:09 INFO - /builds/slave/test/build/application/firefox/firefox 22:09:09 INFO - Running post-action listener: _resource_record_post_action 22:09:09 INFO - ##### 22:09:09 INFO - ##### Finished install step (success) 22:09:09 INFO - ##### 22:09:09 INFO - ##### 22:09:09 INFO - ##### Running run-tests step. 22:09:09 INFO - ##### 22:09:09 INFO - Running pre-action listener: _resource_record_pre_action 22:09:09 INFO - Running main action method: run_tests 22:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:09:09 INFO - Python 2.7.3 22:09:09 INFO - Return code: 0 22:09:09 INFO - grabbing minidump binary from tooltool 22:09:09 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')]} 22:09:09 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 0x1b6a1c0>, '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 0x1b6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:09:09 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 22:09:09 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 22:09:09 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 22:09:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:09:09 INFO - Return code: 0 22:09:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:09:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:09:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:09:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:09:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:09:10 INFO - Return code: 0 22:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 22:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 22:09:10 INFO - Using env: {'DISPLAY': ':0', 22:09:10 INFO - 'HOME': '/home/cltbld', 22:09:10 INFO - 'LANG': 'en_US.UTF-8', 22:09:10 INFO - 'LANGUAGE': 'en_US:en', 22:09:10 INFO - 'LOGNAME': 'cltbld', 22:09:10 INFO - 'MAIL': '/var/mail/cltbld', 22:09:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:09:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:10 INFO - 'MOZ_NO_REMOTE': '1', 22:09:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:10 INFO - 'NO_EM_RESTART': '1', 22:09:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:10 INFO - 'PWD': '/builds/slave/test', 22:09:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:09:10 INFO - 'SHELL': '/bin/bash', 22:09:10 INFO - 'SHLVL': '1', 22:09:10 INFO - 'TERM': 'linux', 22:09:10 INFO - 'TMOUT': '86400', 22:09:10 INFO - 'USER': 'cltbld', 22:09:10 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762', 22:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:10 INFO - '_': '/tools/buildbot/bin/python'} 22:09:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 22:09:10 INFO - mozversion application_buildid: 20160606193115 22:09:10 INFO - mozversion application_changeset: 6ff433eee86b1f64f9ee0955ced57cb215e82dc5 22:09:10 INFO - mozversion application_display_name: Nightly 22:09:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:09:10 INFO - mozversion application_name: Firefox 22:09:10 INFO - mozversion application_remotingname: firefox 22:09:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:09:10 INFO - mozversion application_vendor: Mozilla 22:09:10 INFO - mozversion application_version: 50.0a1 22:09:10 INFO - mozversion platform_buildid: 20160606193115 22:09:10 INFO - mozversion platform_changeset: 6ff433eee86b1f64f9ee0955ced57cb215e82dc5 22:09:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:09:10 INFO - mozversion platform_version: 50.0a1 22:09:10 INFO - using testdate: 1465276150 22:09:10 INFO - actual date: 1465276150 22:09:10 INFO - starting webserver on 'localhost:42104' 22:09:10 INFO - SUITE-START | Running 2 tests 22:09:10 INFO - TEST-START | dromaeo_css 22:09:10 INFO - operating with platform_type : linux_ 22:09:10 INFO - Initialising browser for dromaeo_css test... 22:09:10 INFO - TEST-INFO | started process 30041 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzTjshW/profile http://localhost:42104/getInfo.html) 22:09:15 INFO - PROCESS | 30041 | __metrics Screen width/height:1600/1200 22:09:15 INFO - PROCESS | 30041 | colorDepth:24 22:09:15 INFO - PROCESS | 30041 | Browser inner width/height: 1024/697 22:09:15 INFO - PROCESS | 30041 | __metrics 22:09:16 ERROR - PROCESS | 30041 | 1465276156440 addons.xpi WARN Exception running bootstrap method shutdown on flyweb@mozilla.org: TypeError: gDiscoveryManagerInstance is undefined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@mozilla.org.xpi!/bootstrap.js:202:5) JS Stack trace: uninit@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@bootstrap.js:202:5 < shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@bootstrap.js:41:3 < this.XPIProvider.callBootstrapMethod@XPIProvider.jsm:4754:9 < this.XPIProvider.startup/<.observe@XPIProvider.jsm:2778:15 < permitUnload@remote-browser.xml:356:13 < CanCloseWindow@browser.js:6146:36 < canClose@browser.js:5002:12 < forceQuit@jar:file:///tmp/tmpzTjshW/profile/extensions/talos-powers@TalosPowersService.js:228:7 < forceQuit/whenDone@jar:file:///tmp/tmpzTjshW/profile/extensions/talos-powers@TalosPowersService.js:213:9 < promise callback*forceQuit@jar:file:///tmp/tmpzTjshW/profile/extensions/talos-powers@TalosPowersService.js:215:7 < receiveMessage@jar:file:///tmp/tmpzTjshW/profile/extensions/talos-powers@TalosPowersService.js:53:9 22:09:16 ERROR - PROCESS | 30041 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 22:09:17 INFO - TEST-INFO | 30041: exit 0 22:09:17 INFO - Browser initialized. 22:09:17 INFO - Running cycle 1/1 for dromaeo_css test... 22:09:17 INFO - TEST-INFO | started process 30161 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzTjshW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:09:29 INFO - PROCESS | 30161 | RSS: Main: 167800832 22:09:29 INFO - PROCESS | 30161 | 22:10:18 INFO - PROCESS | 30161 | 1465276218834 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] 22:12:26 INFO - PROCESS | 30161 | Cycle 1(1): loaded http://localhost:42104/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42104/tests/dromaeo/cssquery-ext.html) 22:12:27 INFO - PROCESS | 30161 | RSS: Main: 170205184 22:12:27 INFO - PROCESS | 30161 | 22:15:29 INFO - PROCESS | 30161 | Cycle 1(1): loaded http://localhost:42104/tests/dromaeo/cssquery-ext.html (next: http://localhost:42104/tests/dromaeo/cssquery-jquery.html) 22:15:30 INFO - PROCESS | 30161 | RSS: Main: 169906176 22:15:30 INFO - PROCESS | 30161 | 22:18:32 INFO - PROCESS | 30161 | Cycle 1(1): loaded http://localhost:42104/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42104/tests/dromaeo/cssquery-mootools.html) 22:18:32 INFO - PROCESS | 30161 | RSS: Main: 171298816 22:18:32 INFO - PROCESS | 30161 | 22:21:34 INFO - PROCESS | 30161 | Cycle 1(1): loaded http://localhost:42104/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42104/tests/dromaeo/cssquery-prototype.html) 22:21:35 INFO - PROCESS | 30161 | RSS: Main: 168435712 22:21:35 INFO - PROCESS | 30161 | 22:24:37 INFO - PROCESS | 30161 | Cycle 1(1): loaded http://localhost:42104/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42104/tests/dromaeo/cssquery-yui.html) 22:24:38 INFO - PROCESS | 30161 | RSS: Main: 170475520 22:24:38 INFO - PROCESS | 30161 | 22:27:40 INFO - PROCESS | 30161 | Cycle 1(1): loaded http://localhost:42104/tests/dromaeo/cssquery-yui.html (next: http://localhost:42104/tests/dromaeo/cssquery-dojo.html) 22:27:40 INFO - PROCESS | 30161 | RSS: Main: 172990464 22:27:40 INFO - PROCESS | 30161 | 22:27:40 INFO - PROCESS | 30161 | __start_tp_report 22:27:40 INFO - PROCESS | 30161 | _x_x_mozilla_page_load 22:27:40 INFO - PROCESS | 30161 | _x_x_mozilla_page_load_details 22:27:40 INFO - PROCESS | 30161 | |i|pagename|runs| 22:27:40 INFO - PROCESS | 30161 | |0;dojo.html;3196.4246435641576;3203.19920019995;3221.5328777327286;3223.983880080599;3237.352529494102;5394.921015796846;5422.539084177844;5437.3475182978045;5529.529989950859;5545.390007099226;5420.91868621971;5454.290942177511;5483.972580137094;5509.834704958857;5529.529989950849;4877.390326209215;4985.975070124644;5025.974870125644;5073.0361231366105;5110.386753589574;4933.284673722308;5080.237964305346;5081.923771143436;5111.51440613143;5119.155339368999;4868.172410690191;4947.604191664658;4954.628402869763;4975.855553222761;5006;5032.9245061323745;5047.747612619354;5055.671381360236;5060.2156665716875;5062.873428164288;5017.698938063728;5020.322256495363;5022.89954200914;5042.747862606855;5072.188449848043;5346.492083252085;5357.062514060063;5423.620346575742;5455.645383050089;5466.207399927028;5958.225430694026;5966.850828729273;5969.611975221596;5980.1328807323125;5993.61041532299;6024.246969128859;6035.7283922223605;6036.0945858121195;6040.426159514839;6043.305019922721;5362.758675859595;5427.701476418795;5432.809851655173;5440.91838622421;5477.890442191134;5904.114382842522;5937.10943358491;5939.10913362999;5940.524758019348;5945.791897283661;5657;5708.971455142698;5709.05800542914;5714.485696287354;5728.312602487728;5838;5838.941610583925;5896.056630939029;5920.348761636216;5943.405659434108;5705.229793977843;5784.248047753765;5876.1479585459865;5879.2944846618675;5885.529157667376;5845.210896528999;5856.297244330707;5860.384659610765;5952;5953.672548009815;4392.626626736763;4452.220861349251;4468.910621787546;4501.0547784964865;4504.864854054384;4007.6593489553134;4014.9397759033636;4020.276350256982;4021.959780402217;4049.4735684360844;5680.5455563554815;5684.431556844283;5692.060809966392;5726.427357264323;5756.280464941882;5805.477507024388;5918.585699001029;5974.581779275496;6002.729877155539;6024.216851809237;5670.234518340054;5694.088945768657;5715.714214289269;5717.256756621696;5724.77100915967;5004.199328107486;5145.897082058338;5146;5149.176131818891;5206.6095042871175;6056.576039677268;6088.878222435526;6095.408112664863;6102.428659372087;6133.907991380132;5868.972929737364;5877.324107727666;5879.382664820303;5881.235439392852;5885.588008839341;5919.556033297434;5927.407259274038;6064.272287325549;6064.423879731418;6071.483923866521;5649.774009039593;5726.7709291629535;5731.254936858182;5753.712314384347;5792.507636850914;5776.7400466979925;5800.738966746422;5816.360200377871;5843.065109582446;5852.122181672783;5659.462351076641;5664.461876121762;5726.942730572641;5790.189373487631;5820.126980952891;5615.157726341082;5671.291088613923;5671.801486947937;5679.488846003879;5900.5574581907385;5632.127020311858;5665.858353541194;5668.178114173339;5684.204211410323;5689.6586204827845;5588.273524441796;5715.599908006566;5775.220345253337;5797.072468405035;5815.854603634773;5618.381977982337;5659.717014149193;5687.60186786921;5693.5445164387575;5735.197072409949 22:27:40 INFO - PROCESS | 30161 | |1;ext.html;556.2462862820878;612.5558969746933;627.0531497438866;631.8199313195739;639.9616023038615;37188;41233.793831030875;41435.75692729223;41610.583894161085;41875.37186942194;33188.50217246737;33480.83259583705;33483.832580837065;33522;33565;8703.521306328164;8860.24687901529;8866.42368246062;8875.866861997076;8897.68858089967;8754.824903501925;8838.160374764388;8876.8668469973;8879.600417981206;8882.600282987249;8702.173293537104;8813.294936405073;8843.425177363482;8869.866951995726;8885.111488851095;8824;8838.646454141826;8847.911520884838;8874.866876996817;8891.06643802401;8790.164934331293;8851.60167792451;8853.955730221373;8896.466212027299;8911.0197878234;39629.22595418322;39892;40094.799526002476;40108.59891401078;40114.79942600268;3562.6259242779392;3636.3999940009867;3763.9435408468894;3840.2895464339185;4275.679324050709;31578;31608.696086361833;31785.841070794497;31896.840515797274;32578.6070021557;4087.89780255493;4130.132672138854;4211.515675697304;4215.536291007987;4338.240807858612;343361;344373.9999999975;344977;346222.26888865646;347509;33928.32143357119;35312.82343588266;35545;63159;63347;34869.476957845654;34994.300113998084;35066.29867402637;35095;35321.64678353235;15004.699906002037;15011;15134.545963621109;15174.165420901956;15217.923910380377;13029.804552931713;13062;13065.281409522377;13073.542426015043;13124.081314307907;8380.078191398941;8426.53654049021;8429.662813487514;8480.109588493242;8502.702405415781;3176.952345714816;3190.3938251732097;3192.0742984534227;3197.568328275653;3262.2986057997646;1040.9479526023667;1052.9947350263199;1080.8865069167782;1101.2346419238713;1125.9507695993213;33317;33328.50007249893;33335.83332083324;33339.333213335594;33490.66509334924;1102.9172812039128;1113.8663360396642;1116.0402054233352;1133.2463911498926;1152.1301417429954;17319.56701082483;17406.564835879206;17512.824871751374;17597.208125634377;17636.559086022953;577708;584057;585538;586150;587138;59543.70228148832;60404;60513.69743151256;60654.39345606576;60805.69597151986;1376.3256004557895;1386.473140206715;1395.8464568897414;1405.444849284527;1408.7393832141086;31140.53716427233;32259.838700806347;33070;33256.833715831264;33286.00141995744;53333;54507.18239226494;56221.15668264897;56342;62340.688296558226;521239;522771;523780;524441;525901;44686.55313446824;44692.10615787731;44837.32744008777;44981;46496.7675161622;48129.27806082981;49584.75207623939;50509.49490505048;50634;51043.48956510536;40951.38572921349;42762.78618606887;43529.347059793494;43836.561634384525;44590.77704611456;41690.79154604208;41940.580594194886;42187;42499.787501062296;43714.34428483639;41760.16479670451;42655.36016959686;42670.57329426666;42715.145697086504;45160.29053753459;34946.82526587351;36814.8159259202;37435.62564374322;37535;37565.248695026494;28667.42665146727;28860;28911.7108828909;28935;28991.855040724662 22:27:40 INFO - PROCESS | 30161 | |2;jquery.html;383.5493295377932;383.9731218814682;384.3945785388014;394.91509325495014;404.9898752531186;17140.314387424503;18715.3449629263;19500.219991200353;19509.414717558462;19580.608387832253;17563.56091097723;18331.45005649829;18436;18464.723029154575;18582.907085464554;5155.149400348947;5323.574114070884;5350.464953504638;5357.410684824686;5499.917501237484;5640.407757185498;5749.56878234132;5750.424957504258;5771.538276937835;5787.536997040227;5258.842234732964;5330.973345133269;5674.716264186774;5709.172170035343;5730.570207234453;5211.426743058299;5223.764930578133;5234.607404444663;5260.947390526103;5285.947140528584;4978.377702787152;5100.566451851597;5100.744962751847;5106.336176297094;5306.8407947761625;13123.86876131236;13415.329233538381;13432.731345373135;13448.596542103654;22103.668444973337;2075.4396312995405;2180.476685595446;2290.3243543154645;2334.941626459352;2422.87885605719;9476.526173691287;9636.469994150319;9895.109440150349;10207.081362677394;14049.64875878101;1896.3552392186584;1936.6417212815534;1958.9706154407695;1997.0392154914646;2007.0667139779925;71338.64330678365;72662.63668681675;73982.63008684976;82080.58959705223;84211.5789421049;10586.682399528027;10993.670189894316;11057.889421105847;11186.496607652674;11186.608468703556;15415.614609634624;15521.301541430663;15545;15546;15559.22203889801;8092.329531254672;8277;8465.915340846635;8493.830123397622;8590.957045214735;8191.95904020488;8205.261526462647;8209.179082091863;8244.83510329802;8336.124706905688;4698.882527936829;4724.362211101457;4726.078414709167;4815.662903596714;4851.393575803025;1935.2549268531716;1952.8535359847783;1964.3517639179036;1978.3965890403565;2024.7671517775645;926.2821313482106;939.1313035442189;1021.1568119544294;1055.773008803111;1082.6048492300254;16774.329026839034;17708.29166833338;18987.71518427225;19038.238470461303;19077.332293369665;992.5831150916632;1006.5872992073215;1014.8579198912158;1040.7606250562412;1073.5598404654054;10432.374057556572;10790;10838.403887786248;10848.294860834021;10859.076978456762;476979;482563;482691;485318;488249;75399.62300188464;91370.54314728516;96345.51827240818;98073;100559;999.3504222255447;1022.6574097677212;1056.4664844253757;1058.6453538064682;1072.2869291920786;16448.259828307993;16985.83014169892;17212.13939303055;17273.136343182538;17658;39705.60294397019;40535.797321013204;40601.79699101485;41569;41759.58240417557;57285;58907.410925890195;59453;59526;59763.701181493816;8049.195080491904;8181.959090204511;8337.83324333522;8519.574021299035;8718.346124040596;9064.818703626022;12797.424115914993;12944.741105177656;12998.870011299767;13200.20798752078;10986.121110311316;11034.896510349223;11170.329780213213;11353.262037967506;11475.942620286845;11104;11141.742470988687;11197.328160310082;11294.60468883585;11457.369844658291;11383.487743051417;11489;11618.94190529042;11699.590514331958;11921.463534141158;7874.251946065115;7959.681612735426;7961.547819437645;8086;8469.830603388022;8488.193621605938;10553.314034587727;11656.53373865036;11734.471948762155;11756.94121529387 22:27:40 INFO - PROCESS | 30161 | |3;mootools.html;1468.7576549869273;1514.492644963937;1612.2664187794555;1732.844044036038;1766.567191038195;7824.178461261571;8397.286230670392;8419.200175983271;8948.194662480375;9022.593983270752;8513.872291915626;8843.159899809523;8864.601092950817;8884.244839188661;8899.243564297027;2294.8393612447135;2310.410845234463;2310.572544079342;2315.073970411832;2324.6164382876805;2224.4661281292456;2232.6539386395134;2270.9886450567724;2291.415688999303;2291.919782807602;2237.1722462688826;2243.4727838957915;2255.4248666589997;2257.0971611355426;2258.1870526610405;2227.543353612514;2236.0496788864666;2268.421552504109;2269.126386341254;2269.1263863412623;2245.9663105053437;2278.8176945844293;2280.771922807706;2284.257616274701;2286.416963674269;8689.913100869036;8790.912090879074;8791.78020549485;8802.515861627608;8808.427452215585;5116.130257856174;5174.3790745110455;5223.869403264911;5224.399194092652;5229.294045303874;3177.9682203177904;3231.2245061185145;3239.4492936200904;3487.7384196185135;3503.597086335053;1200.4717924113363;1200.4897918384652;1976;2001.0795034284247;2093.3824521765964;12086.093542984312;12189.451474683661;12277.324747138904;12333.62999110028;12348.950339221177;4398.186335527937;4408.53710360414;4485.349624304457;4503.639708823286;4535.092981403733;9638.518074096408;9676.080772326617;9685.467299298607;9712.22302215821;9772.951135244279;5117.232415137684;5162.148245539444;5173.71544565045;5268.604854635918;5290.788368465295;4323.2650449423045;4704.388429504143;4765.213739732974;4828.517148285214;4857.587105096036;3768.924621507609;3807.9428808567895;3867.41988701697;3889.669378102836;3951.2492626401468;1122.5341483284346;1124.6063877642923;1127.4644543841773;1144.7195437117794;1156.7050402147568;840.9369297302727;898.9865152022594;914.7621618379136;957.3681370295571;970.9029097090234;5897.793577224862;5920.792772253037;6102.4507794298725;6113.113598528189;6200.224971878515;413.5760845133702;426.2923546912111;429.428859616709;433.8741764888147;434.98260069596864;4074.083331250445;4118.876433706993;4122.690798190148;4141.668666506672;4194.853180138742;10509.947450262853;10853.457327133612;10881.673549793519;10894.291871028357;10896.891031089746;10576.36541807494;10663.786724265627;10710.839337409789;10745.301555398873;10787.083097936764;8144.75565733029;8190.344772418312;8256.009278886451;8298.917010829935;8322.126176751475;5704.885902282014;5862.3844496328975;5933.169356290209;5946.464818166386;5971.58198926071;9663.371880827724;9955.104040636377;10005.349652272578;10062.446565438971;10114.089731924161;10179.898201018192;10186.949065254626;10236.436995964996;10370.688879333633;10418.843717344094;7582.810429739301;7771.494852834548;7787.493812902143;7939.28546430824;8071.677132914852;8642.740717778477;8678.088800675838;8689.13108689126;8706.956465217634;8732.91267087321;7372.557646541225;7524.097108347033;7703.614819259126;7916.960415197887;7972.043354797462;7221.602811845399;7498.32515073646;7505.437092217996;7531.88702169457;7580.469367144247;7084.574925504487;7122.430205583644;7151.141862976476;7152.177499587614;7163.677634506564;5177.508136727005;5259.500347466985;5263.23683065961;5291.7618707159045;5382.219578161066;5158.638895277295;5194.35070616173;5222.112240919128;5328.626996110235;5345.572354211576 22:27:40 INFO - PROCESS | 30161 | |4;prototype.html;317.90303957293025;318.83579956322495;325.35254842862713;327.71325090545787;327.9639239683633;5523;5625.493705566499;5644.971775141129;5645.915311270324;5657;5500.779968801253;5526.309211348582;5528.668279903209;5538.224648549196;5554.472325129106;4210.368444733299;4230.640395566381;4288.313869780835;4297.507445681513;4305.67707421943;4255.276602977486;4269.316909294514;4282.50751163617;4289.485261768592;4305.138972205556;4259.446271984653;4263.637590804786;4275.5296917339065;4286.164197981395;4303.440552728141;4002.8198731057173;4073.551909289976;4177.289860723684;4269.701120921537;4275.572442755715;3684.2263124743836;3692.723045771551;3700.833462494194;3711.795851228181;3723.6276372362818;5261.053010458154;5316.627836051478;5328.227407025959;5345.064613692627;5350.117230656937;4814.855554333376;4857.149998750205;4866.902661946741;4867.732274724889;4876.609871210295;5694.487496125369;5694.715264236772;5738.856528586777;5749.3388260350175;5776.36459989405;4103.281925662997;4120.7321524100835;4145.150244199962;4147;4164.083901541656;5616.859578510529;6001.909971350433;6029.276486821565;6085.778690777878;6105.877882442371;4121.258173528734;4126.319157339006;4149.875503734893;4150.875473735793;4163.708540402204;4194.6853985951175;4209.473815773028;4222.978885105616;4239.173361194599;4242.0455397535925;4735.431748190171;4765.761711914391;4834.129856625771;4865.245886887538;4893;5707.6575405475805;5739.16782066598;5784.161296611967;5799.88400231993;5804.912926306109;1418.6453386653336;1441.654003039284;1448.630599197219;1453.1716921354725;1457.6283047822737;877.8595424732013;910.9160099481728;916.2578311567652;921.4885738415244;924.1497822003772;5033.370828646419;5043.52086551777;5052.368453943257;5066.46802085783;5090.440051594322;5306.893862122736;5419.078756611386;5446.8757184698;5456.963176996358;5464.3442786865035;5121.436641969381;5126.102931986888;5166.501714502752;5261.421243663194;5550.250716153268;3091.27355071558;3160.4627213373783;3205.230744621321;3205.8717651293687;3214.5821043264227;6141.416565426277;6155.230596175478;6159.29168145669;6162.073015317778;6197.411245931629;5974.492168165754;6098.817035488943;6163.476104531165;6192.59748116371;6193.142057382767;6167.753289868445;6176.8764624707255;6183.5671502994355;6208.379162083756;6216.2540495141775;5725.885482290414;5965.73154208053;5977.462028417463;6007.3992600739575;6020.518358531394;5867.383924687846;5956.76172953077;5969.4627483524955;5979.940200598112;6002.129691194839;5948.405159484017;6073.787417440369;6134.294556126102;6255.343188965275;6270.905936410866;3790.4693342932565;3814.4469051986753;3814.847406103836;3821.350370437088;3833.9233215336094;5395.192610475535;5418.347404041648;5432.098765432067;5438.422857371318;5478.233047373451;4939;5232.319798426181;5270.7101109439345;5278.466336158589;5312.415634280149;5215.217717342429;5223.52988231061;5224.869378265422;5244.711540865289;5276.657017293864;5165.173572228426;5182.32629758129;5204.90696953644;5218.895622087461;5269.604779641466;4926.127776054757;5201.373306480204;5202.606800871641;5210.713410762444;5235.476452354734;5049.798008079782;5100;5126.102931986813;5139.820106296262;5157.871053223699 22:27:40 INFO - PROCESS | 30161 | |5;yui.html;718.0557566799658;767.0948281028384;794.3645083932852;800.4476910931457;806.5120602035772;14627;15625.765613515778;15756.054636721805;15777.842221577781;15865.444709435173;13416.53042143525;13466.5286714965;13491.46034158635;13537.93231033846;13889.79165312521;656.3207080671482;778.420077042604;783.1463704562012;797.5772840394571;802.3060053054124;789.7472808701218;800.951942883426;801.6873419366452;802.0535767794;803.7267329108103;841.8610929196675;842.6503001254472;843.2832092721169;844.025150950653;844.0841686769862;829.5603330234985;832.8958880139983;834.2491757418342;834.7787836223406;837.1252041616495;606.3209205689611;612.7487730030666;613.1844646619985;613.6440864298696;615.3047056825758;14415.567532974028;14470.565883023526;14503.41986320546;14516.709665806731;14547.490837820731;14778;15000.174990375524;15034.849651503455;15182.164980926045;15277.61805954849;11939.462724177432;12057.758844823054;12098;12229.877701222962;12270;3910.8240129194255;3931.3709806430834;3932.9803350983348;3940.3892396678275;4066.8779936601654;74351.25648743498;74454;76034;76142.23857761407;76431.617841911;11690.88309116915;11691.649250522498;11761.647150585324;11850.348230847214;12045.518179272905;22003.33989980271;22101.557968840534;22171.224007159995;22319.776802232096;22803.543929121326;8671.826563468696;8762.342824288204;8791.780205494913;8858.822823543493;8950.373473856898;4675.7895894684825;4703.764811759467;4835.709857408567;4838.879028024327;4890.413150421972;3632.3461776880417;3777.0746167189213;3779.244151169777;3785.981070094632;3805.448210009533;605.4762630324738;625.4152367536368;627.9403456671608;642.9614223146533;643.3919945651314;692.4529621598981;709.386380780621;712.2983860897012;762.969481220756;767.6468824340755;9422.152006319464;9573.090556397132;9636.277279204087;9666.178374838077;9856;667.2226855713137;672.7174586673609;693.7918624412656;713.293839099288;772.4476998945669;4613.146567884952;4769.165396055676;4788.209945358977;4803.1114243864995;4864.294677271776;33642.49536256958;33686.663133368354;33704.83147584246;33798.83100584481;106700;86389;91826.54086729523;93459;93554.53222733842;94584.52707736417;84572.57713711393;84662.5766871174;84706.57646711727;85057.57471212605;85359.57320213359;8917.643294268157;9154.26765858743;9156.633734650539;9211.170994610517;9267.72196834096;48659;49318.50681493283;49334.50665493299;51156.44217789021;51301.48698512967;47573.76213118912;71079;73428.26571734215;75226.62386688031;76851.23148768664;11417.942910285396;11469.713257168638;11543.942280288546;11552.884471155181;11667.416629168678;12946.482140714266;13098.345082746015;13171.07802453819;13193.20840749558;13463.12489688167;12185.51257949672;13257.337133143497;13420.46318147263;13709.588712338646;13762.105463144864;13657.863421366057;13933.58199254024;14125.434982600991;14592.854071459149;14738.926305368404;13546.864531354955;13800.93099534496;13831.723365532433;13891.652708682364;14183;10882.129429645767;11129;11229.21395502307;11249.550017999189;11464.31214127155;10902.56389744433;11275.830862537232;11362.886371136514;11484.770304593694;11718.414079296172 22:27:40 INFO - PROCESS | 30161 | __end_tp_report 22:27:40 INFO - PROCESS | 30161 | __start_cc_report 22:27:40 INFO - PROCESS | 30161 | _x_x_mozilla_cycle_collect,337 22:27:40 INFO - PROCESS | 30161 | __end_cc_report 22:27:40 INFO - PROCESS | 30161 | __startTimestamp1465277260775__endTimestamp 22:27:40 INFO - PROCESS | 30161 | 22:27:40 INFO - PROCESS | 30161 | ------- Summary: start ------- 22:27:40 INFO - PROCESS | 30161 | Number of tests: 6 22:27:40 INFO - PROCESS | 30161 | 22:27:40 INFO - PROCESS | 30161 | [#0] dojo.html Cycles:165 Average:5466.76 Median:5684.43 stddev:614.05 (10.8%) stddev-sans-first:589.54 22:27:40 INFO - PROCESS | 30161 | Values: 3196.4 3203.2 3221.5 3224.0 3237.4 5394.9 5422.5 5437.3 5529.5 5545.4 5420.9 5454.3 5484.0 5509.8 5529.5 4877.4 4986.0 5026.0 5073.0 5110.4 4933.3 5080.2 5081.9 5111.5 5119.2 4868.2 4947.6 4954.6 4975.9 5006.0 5032.9 5047.7 5055.7 5060.2 5062.9 5017.7 5020.3 5022.9 5042.7 5072.2 5346.5 5357.1 5423.6 5455.6 5466.2 5958.2 5966.9 5969.6 5980.1 5993.6 6024.2 6035.7 6036.1 6040.4 6043.3 5362.8 5427.7 5432.8 5440.9 5477.9 5904.1 5937.1 5939.1 5940.5 5945.8 5657.0 5709.0 5709.1 5714.5 5728.3 5838.0 5838.9 5896.1 5920.3 5943.4 5705.2 5784.2 5876.1 5879.3 5885.5 5845.2 5856.3 5860.4 5952.0 5953.7 4392.6 4452.2 4468.9 4501.1 4504.9 4007.7 4014.9 4020.3 4022.0 4049.5 5680.5 5684.4 5692.1 5726.4 5756.3 5805.5 5918.6 5974.6 6002.7 6024.2 5670.2 5694.1 5715.7 5717.3 5724.8 5004.2 5145.9 5146.0 5149.2 5206.6 6056.6 6088.9 6095.4 6102.4 6133.9 5869.0 5877.3 5879.4 5881.2 5885.6 5919.6 5927.4 6064.3 6064.4 6071.5 5649.8 5726.8 5731.3 5753.7 5792.5 5776.7 5800.7 5816.4 5843.1 5852.1 5659.5 5664.5 5726.9 5790.2 5820.1 5615.2 5671.3 5671.8 5679.5 5900.6 5632.1 5665.9 5668.2 5684.2 5689.7 5588.3 5715.6 5775.2 5797.1 5815.9 5618.4 5659.7 5687.6 5693.5 5735.2 22:27:40 INFO - PROCESS | 30161 | 22:27:40 INFO - PROCESS | 30161 | [#1] ext.html Cycles:180 Average:62955.28 Median:22681.96 stddev:131957.42 (581.8%) stddev-sans-first:132244.43 22:27:40 INFO - PROCESS | 30161 | Values: 556.2 612.6 627.1 631.8 640.0 37188.0 41233.8 41435.8 41610.6 41875.4 33188.5 33480.8 33483.8 33522.0 33565.0 8703.5 8860.2 8866.4 8875.9 8897.7 8754.8 8838.2 8876.9 8879.6 8882.6 8702.2 8813.3 8843.4 8869.9 8885.1 8824.0 8838.6 8847.9 8874.9 8891.1 8790.2 8851.6 8854.0 8896.5 8911.0 39629.2 39892.0 40094.8 40108.6 40114.8 3562.6 3636.4 3763.9 3840.3 4275.7 31578.0 31608.7 31785.8 31896.8 32578.6 4087.9 4130.1 4211.5 4215.5 4338.2 343361.0 344374.0 344977.0 346222.3 347509.0 33928.3 35312.8 35545.0 63159.0 63347.0 34869.5 34994.3 35066.3 35095.0 35321.6 15004.7 15011.0 15134.5 15174.2 15217.9 13029.8 13062.0 13065.3 13073.5 13124.1 8380.1 8426.5 8429.7 8480.1 8502.7 3177.0 3190.4 3192.1 3197.6 3262.3 1040.9 1053.0 1080.9 1101.2 1126.0 33317.0 33328.5 33335.8 33339.3 33490.7 1102.9 1113.9 1116.0 1133.2 1152.1 17319.6 17406.6 17512.8 17597.2 17636.6 577708.0 584057.0 585538.0 586150.0 587138.0 59543.7 60404.0 60513.7 60654.4 60805.7 1376.3 1386.5 1395.8 1405.4 1408.7 31140.5 32259.8 33070.0 33256.8 33286.0 53333.0 54507.2 56221.2 56342.0 62340.7 521239.0 522771.0 523780.0 524441.0 525901.0 44686.6 44692.1 44837.3 44981.0 46496.8 48129.3 49584.8 50509.5 50634.0 51043.5 40951.4 42762.8 43529.3 43836.6 44590.8 41690.8 41940.6 42187.0 42499.8 43714.3 41760.2 42655.4 42670.6 42715.1 45160.3 34946.8 36814.8 37435.6 37535.0 37565.2 28667.4 28860.0 28911.7 28935.0 28991.9 22:27:40 INFO - PROCESS | 30161 | 22:27:40 INFO - PROCESS | 30161 | [#2] jquery.html Cycles:180 Average:28315.83 Median:2378.91 stddev:79726.51 (3351.4%) stddev-sans-first:79922.58 22:27:40 INFO - PROCESS | 30161 | Values: 383.5 384.0 384.4 394.9 405.0 17140.3 18715.3 19500.2 19509.4 19580.6 17563.6 18331.5 18436.0 18464.7 18582.9 5155.1 5323.6 5350.5 5357.4 5499.9 5640.4 5749.6 5750.4 5771.5 5787.5 5258.8 5331.0 5674.7 5709.2 5730.6 5211.4 5223.8 5234.6 5260.9 5285.9 4978.4 5100.6 5100.7 5106.3 5306.8 13123.9 13415.3 13432.7 13448.6 22103.7 2075.4 2180.5 2290.3 2334.9 2422.9 9476.5 9636.5 9895.1 10207.1 14049.6 1896.4 1936.6 1959.0 1997.0 2007.1 71338.6 72662.6 73982.6 82080.6 84211.6 10586.7 10993.7 11057.9 11186.5 11186.6 15415.6 15521.3 15545.0 15546.0 15559.2 8092.3 8277.0 8465.9 8493.8 8591.0 8192.0 8205.3 8209.2 8244.8 8336.1 4698.9 4724.4 4726.1 4815.7 4851.4 1935.3 1952.9 1964.4 1978.4 2024.8 926.3 939.1 1021.2 1055.8 1082.6 16774.3 17708.3 18987.7 19038.2 19077.3 992.6 1006.6 1014.9 1040.8 1073.6 10432.4 10790.0 10838.4 10848.3 10859.1 476979.0 482563.0 482691.0 485318.0 488249.0 75399.6 91370.5 96345.5 98073.0 100559.0 999.4 1022.7 1056.5 1058.6 1072.3 16448.3 16985.8 17212.1 17273.1 17658.0 39705.6 40535.8 40601.8 41569.0 41759.6 57285.0 58907.4 59453.0 59526.0 59763.7 8049.2 8182.0 8337.8 8519.6 8718.3 9064.8 12797.4 12944.7 12998.9 13200.2 10986.1 11034.9 11170.3 11353.3 11475.9 11104.0 11141.7 11197.3 11294.6 11457.4 11383.5 11489.0 11618.9 11699.6 11921.5 7874.3 7959.7 7961.5 8086.0 8469.8 8488.2 10553.3 11656.5 11734.5 11756.9 22:27:40 INFO - PROCESS | 30161 | 22:27:40 INFO - PROCESS | 30161 | [#3] mootools.html Cycles:180 Average:5759.01 Median:4796.87 stddev:3274.23 (68.3%) stddev-sans-first:3267.55 22:27:40 INFO - PROCESS | 30161 | Values: 1468.8 1514.5 1612.3 1732.8 1766.6 7824.2 8397.3 8419.2 8948.2 9022.6 8513.9 8843.2 8864.6 8884.2 8899.2 2294.8 2310.4 2310.6 2315.1 2324.6 2224.5 2232.7 2271.0 2291.4 2291.9 2237.2 2243.5 2255.4 2257.1 2258.2 2227.5 2236.0 2268.4 2269.1 2269.1 2246.0 2278.8 2280.8 2284.3 2286.4 8689.9 8790.9 8791.8 8802.5 8808.4 5116.1 5174.4 5223.9 5224.4 5229.3 3178.0 3231.2 3239.4 3487.7 3503.6 1200.5 1200.5 1976.0 2001.1 2093.4 12086.1 12189.5 12277.3 12333.6 12349.0 4398.2 4408.5 4485.3 4503.6 4535.1 9638.5 9676.1 9685.5 9712.2 9773.0 5117.2 5162.1 5173.7 5268.6 5290.8 4323.3 4704.4 4765.2 4828.5 4857.6 3768.9 3807.9 3867.4 3889.7 3951.2 1122.5 1124.6 1127.5 1144.7 1156.7 840.9 899.0 914.8 957.4 970.9 5897.8 5920.8 6102.5 6113.1 6200.2 413.6 426.3 429.4 433.9 435.0 4074.1 4118.9 4122.7 4141.7 4194.9 10509.9 10853.5 10881.7 10894.3 10896.9 10576.4 10663.8 10710.8 10745.3 10787.1 8144.8 8190.3 8256.0 8298.9 8322.1 5704.9 5862.4 5933.2 5946.5 5971.6 9663.4 9955.1 10005.3 10062.4 10114.1 10179.9 10186.9 10236.4 10370.7 10418.8 7582.8 7771.5 7787.5 7939.3 8071.7 8642.7 8678.1 8689.1 8707.0 8732.9 7372.6 7524.1 7703.6 7917.0 7972.0 7221.6 7498.3 7505.4 7531.9 7580.5 7084.6 7122.4 7151.1 7152.2 7163.7 5177.5 5259.5 5263.2 5291.8 5382.2 5158.6 5194.4 5222.1 5328.6 5345.6 22:27:40 INFO - PROCESS | 30161 | 22:27:40 INFO - PROCESS | 30161 | [#4] prototype.html Cycles:180 Average:4723.62 Median:5212.97 stddev:1398.79 (26.8%) stddev-sans-first:1363.06 22:27:40 INFO - PROCESS | 30161 | Values: 317.9 318.8 325.4 327.7 328.0 5523.0 5625.5 5645.0 5645.9 5657.0 5500.8 5526.3 5528.7 5538.2 5554.5 4210.4 4230.6 4288.3 4297.5 4305.7 4255.3 4269.3 4282.5 4289.5 4305.1 4259.4 4263.6 4275.5 4286.2 4303.4 4002.8 4073.6 4177.3 4269.7 4275.6 3684.2 3692.7 3700.8 3711.8 3723.6 5261.1 5316.6 5328.2 5345.1 5350.1 4814.9 4857.1 4866.9 4867.7 4876.6 5694.5 5694.7 5738.9 5749.3 5776.4 4103.3 4120.7 4145.2 4147.0 4164.1 5616.9 6001.9 6029.3 6085.8 6105.9 4121.3 4126.3 4149.9 4150.9 4163.7 4194.7 4209.5 4223.0 4239.2 4242.0 4735.4 4765.8 4834.1 4865.2 4893.0 5707.7 5739.2 5784.2 5799.9 5804.9 1418.6 1441.7 1448.6 1453.2 1457.6 877.9 910.9 916.3 921.5 924.1 5033.4 5043.5 5052.4 5066.5 5090.4 5306.9 5419.1 5446.9 5457.0 5464.3 5121.4 5126.1 5166.5 5261.4 5550.3 3091.3 3160.5 3205.2 3205.9 3214.6 6141.4 6155.2 6159.3 6162.1 6197.4 5974.5 6098.8 6163.5 6192.6 6193.1 6167.8 6176.9 6183.6 6208.4 6216.3 5725.9 5965.7 5977.5 6007.4 6020.5 5867.4 5956.8 5969.5 5979.9 6002.1 5948.4 6073.8 6134.3 6255.3 6270.9 3790.5 3814.4 3814.8 3821.4 3833.9 5395.2 5418.3 5432.1 5438.4 5478.2 4939.0 5232.3 5270.7 5278.5 5312.4 5215.2 5223.5 5224.9 5244.7 5276.7 5165.2 5182.3 5204.9 5218.9 5269.6 4926.1 5201.4 5202.6 5210.7 5235.5 5049.8 5100.0 5126.1 5139.8 5157.9 22:27:40 INFO - PROCESS | 30161 | 22:27:40 INFO - PROCESS | 30161 | [#5] yui.html Cycles:180 Average:18454.57 Median:4795.66 stddev:25210.86 (525.7%) stddev-sans-first:25246.40 22:27:40 INFO - PROCESS | 30161 | Values: 718.1 767.1 794.4 800.4 806.5 14627.0 15625.8 15756.1 15777.8 15865.4 13416.5 13466.5 13491.5 13537.9 13889.8 656.3 778.4 783.1 797.6 802.3 789.7 801.0 801.7 802.1 803.7 841.9 842.7 843.3 844.0 844.1 829.6 832.9 834.2 834.8 837.1 606.3 612.7 613.2 613.6 615.3 14415.6 14470.6 14503.4 14516.7 14547.5 14778.0 15000.2 15034.8 15182.2 15277.6 11939.5 12057.8 12098.0 12229.9 12270.0 3910.8 3931.4 3933.0 3940.4 4066.9 74351.3 74454.0 76034.0 76142.2 76431.6 11690.9 11691.6 11761.6 11850.3 12045.5 22003.3 22101.6 22171.2 22319.8 22803.5 8671.8 8762.3 8791.8 8858.8 8950.4 4675.8 4703.8 4835.7 4838.9 4890.4 3632.3 3777.1 3779.2 3786.0 3805.4 605.5 625.4 627.9 643.0 643.4 692.5 709.4 712.3 763.0 767.6 9422.2 9573.1 9636.3 9666.2 9856.0 667.2 672.7 693.8 713.3 772.4 4613.1 4769.2 4788.2 4803.1 4864.3 33642.5 33686.7 33704.8 33798.8 106700.0 86389.0 91826.5 93459.0 93554.5 94584.5 84572.6 84662.6 84706.6 85057.6 85359.6 8917.6 9154.3 9156.6 9211.2 9267.7 48659.0 49318.5 49334.5 51156.4 51301.5 47573.8 71079.0 73428.3 75226.6 76851.2 11417.9 11469.7 11543.9 11552.9 11667.4 12946.5 13098.3 13171.1 13193.2 13463.1 12185.5 13257.3 13420.5 13709.6 13762.1 13657.9 13933.6 14125.4 14592.9 14738.9 13546.9 13800.9 13831.7 13891.7 14183.0 10882.1 11129.0 11229.2 11249.6 11464.3 10902.6 11275.8 11362.9 11484.8 11718.4 22:27:40 INFO - PROCESS | 30161 | -------- Summary: end -------- 22:27:40 INFO - PROCESS | 30161 | 22:27:40 ERROR - PROCESS | 30161 | 1465277260791 addons.xpi WARN Exception running bootstrap method shutdown on flyweb@mozilla.org: TypeError: gDiscoveryManagerInstance is undefined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@mozilla.org.xpi!/bootstrap.js:202:5) JS Stack trace: uninit@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@bootstrap.js:202:5 < shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@bootstrap.js:41:3 < this.XPIProvider.callBootstrapMethod@XPIProvider.jsm:4754:9 < this.XPIProvider.startup/<.observe@XPIProvider.jsm:2778:15 < goQuitApplication@quit.js:101:5 < plStopAll@pageloader.js:804:3 < collectMemory/gMemCallback@memory.js:49:38 < collectAndReport@memory.js:63:3 < collectMemory@memory.js:57:5 < plStop@pageloader.js:755:5 < plNextPage<@pageloader.js:501:5 < TaskImpl_run@Task.jsm:319:40 < TaskImpl@Task.jsm:280:3 < createAsyncFunction/asyncFunction@Task.jsm:254:14 < setTimeout handler*_loadHandlerCapturing@pageloader.js:626:5 < plRecordTimeMessage@pageloader.js:728:3 < ContentListener.receiveMessage@pageloader.js:329:44 22:27:41 INFO - TEST-INFO | 30161: exit 0 22:27:41 INFO - TEST-OK | dromaeo_css | took 1111181ms 22:27:41 INFO - TEST-START | kraken 22:27:41 INFO - operating with platform_type : linux_ 22:27:41 INFO - Initialising browser for kraken test... 22:27:41 INFO - TEST-INFO | started process 30397 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXuiGYn/profile http://localhost:42104/getInfo.html) 22:27:46 INFO - PROCESS | 30397 | __metrics Screen width/height:1600/1200 22:27:46 INFO - PROCESS | 30397 | colorDepth:24 22:27:46 INFO - PROCESS | 30397 | Browser inner width/height: 1024/697 22:27:46 INFO - PROCESS | 30397 | __metrics 22:27:47 ERROR - PROCESS | 30397 | 1465277267124 addons.xpi WARN Exception running bootstrap method shutdown on flyweb@mozilla.org: TypeError: gDiscoveryManagerInstance is undefined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@mozilla.org.xpi!/bootstrap.js:202:5) JS Stack trace: uninit@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@bootstrap.js:202:5 < shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@bootstrap.js:41:3 < this.XPIProvider.callBootstrapMethod@XPIProvider.jsm:4754:9 < this.XPIProvider.startup/<.observe@XPIProvider.jsm:2778:15 < permitUnload@remote-browser.xml:356:13 < CanCloseWindow@browser.js:6146:36 < canClose@browser.js:5002:12 < forceQuit@jar:file:///tmp/tmpXuiGYn/profile/extensions/talos-powers@TalosPowersService.js:228:7 < forceQuit/whenDone@jar:file:///tmp/tmpXuiGYn/profile/extensions/talos-powers@TalosPowersService.js:213:9 < promise callback*forceQuit@jar:file:///tmp/tmpXuiGYn/profile/extensions/talos-powers@TalosPowersService.js:215:7 < receiveMessage@jar:file:///tmp/tmpXuiGYn/profile/extensions/talos-powers@TalosPowersService.js:53:9 22:27:47 ERROR - PROCESS | 30397 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 22:27:48 INFO - TEST-INFO | 30397: exit 0 22:27:48 INFO - Browser initialized. 22:27:48 INFO - Running cycle 1/1 for kraken test... 22:27:48 INFO - TEST-INFO | started process 30519 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXuiGYn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:27:59 INFO - PROCESS | 30519 | RSS: Main: 167763968 22:27:59 INFO - PROCESS | 30519 | 22:28:02 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42104/tests/kraken/driver.html?testName=audio-beat-detection) 22:28:03 INFO - PROCESS | 30519 | RSS: Main: 172503040 22:28:03 INFO - PROCESS | 30519 | 22:28:06 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42104/tests/kraken/driver.html?testName=audio-dft) 22:28:06 INFO - PROCESS | 30519 | RSS: Main: 171950080 22:28:06 INFO - PROCESS | 30519 | 22:28:09 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42104/tests/kraken/driver.html?testName=audio-fft) 22:28:10 INFO - PROCESS | 30519 | RSS: Main: 166621184 22:28:10 INFO - PROCESS | 30519 | 22:28:12 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42104/tests/kraken/driver.html?testName=audio-oscillator) 22:28:13 INFO - PROCESS | 30519 | RSS: Main: 167645184 22:28:13 INFO - PROCESS | 30519 | 22:28:15 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42104/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:28:15 INFO - PROCESS | 30519 | RSS: Main: 168144896 22:28:15 INFO - PROCESS | 30519 | 22:28:18 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42104/tests/kraken/driver.html?testName=imaging-darkroom) 22:28:19 INFO - PROCESS | 30519 | RSS: Main: 168390656 22:28:19 INFO - PROCESS | 30519 | 22:28:23 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42104/tests/kraken/driver.html?testName=imaging-desaturate) 22:28:23 INFO - PROCESS | 30519 | RSS: Main: 165879808 22:28:23 INFO - PROCESS | 30519 | 22:28:26 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42104/tests/kraken/driver.html?testName=json-parse-financial) 22:28:27 INFO - PROCESS | 30519 | RSS: Main: 167157760 22:28:27 INFO - PROCESS | 30519 | 22:28:28 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42104/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:28:29 INFO - PROCESS | 30519 | RSS: Main: 167661568 22:28:29 INFO - PROCESS | 30519 | 22:28:31 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42104/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:28:31 INFO - PROCESS | 30519 | RSS: Main: 167641088 22:28:31 INFO - PROCESS | 30519 | 22:28:33 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42104/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:28:34 INFO - PROCESS | 30519 | RSS: Main: 167251968 22:28:34 INFO - PROCESS | 30519 | 22:28:37 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42104/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:28:37 INFO - PROCESS | 30519 | RSS: Main: 168497152 22:28:37 INFO - PROCESS | 30519 | 22:28:40 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42104/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:28:41 INFO - PROCESS | 30519 | RSS: Main: 169463808 22:28:41 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | Cycle 1(1): loaded http://localhost:42104/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42104/tests/kraken/driver.html?testName=ai-astar) 22:28:42 INFO - PROCESS | 30519 | RSS: Main: 169369600 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | __start_tp_report 22:28:42 INFO - PROCESS | 30519 | _x_x_mozilla_page_load 22:28:42 INFO - PROCESS | 30519 | _x_x_mozilla_page_load_details 22:28:42 INFO - PROCESS | 30519 | |i|pagename|runs| 22:28:42 INFO - PROCESS | 30519 | |0;ai-astar;113;105;111;112;113;111;112;111;111;112 22:28:42 INFO - PROCESS | 30519 | |1;audio-beat-detection;159;133;154;178;126;154;148;137;179;127 22:28:42 INFO - PROCESS | 30519 | |2;audio-dft;163;162;165;161;163;165;176;163;162;163 22:28:42 INFO - PROCESS | 30519 | |3;audio-fft;75;78;76;161;76;75;74;75;165;77 22:28:42 INFO - PROCESS | 30519 | |4;audio-oscillator;86;85;152;86;86;85;141;85;85;143 22:28:42 INFO - PROCESS | 30519 | |5;imaging-gaussian-blur;118;117;118;117;118;118;117;117;117;117 22:28:42 INFO - PROCESS | 30519 | |6;imaging-darkroom;147;148;147;149;147;147;147;146;147;147 22:28:42 INFO - PROCESS | 30519 | |7;imaging-desaturate;96;96;96;96;95;95;96;96;96;95 22:28:42 INFO - PROCESS | 30519 | |8;json-parse-financial;55;54;61;54;53;53;63;55;53;54 22:28:42 INFO - PROCESS | 30519 | |9;json-stringify-tinderbox;62;63;63;61;61;63;61;62;62;62 22:28:42 INFO - PROCESS | 30519 | |10;stanford-crypto-aes;81;79;80;81;81;87;81;80;119;86 22:28:42 INFO - PROCESS | 30519 | |11;stanford-crypto-ccm;131;131;136;124;125;131;126;133;135;126 22:28:42 INFO - PROCESS | 30519 | |12;stanford-crypto-pbkdf2;168;167;169;164;158;158;160;167;165;157 22:28:42 INFO - PROCESS | 30519 | |13;stanford-crypto-sha256-iterative;64;63;64;64;63;65;63;64;63;64 22:28:42 INFO - PROCESS | 30519 | __end_tp_report 22:28:42 INFO - PROCESS | 30519 | __start_cc_report 22:28:42 INFO - PROCESS | 30519 | _x_x_mozilla_cycle_collect,881 22:28:42 INFO - PROCESS | 30519 | __end_cc_report 22:28:42 INFO - PROCESS | 30519 | __startTimestamp1465277322984__endTimestamp 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | ------- Summary: start ------- 22:28:42 INFO - PROCESS | 30519 | Number of tests: 14 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:2.28 (2.0%) stddev-sans-first:2.32 22:28:42 INFO - PROCESS | 30519 | Values: 113.0 105.0 111.0 112.0 113.0 111.0 112.0 111.0 111.0 112.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#1] audio-beat-detection Cycles:10 Average:149.50 Median:154.00 stddev:19.16 (12.4%) stddev-sans-first:20.01 22:28:42 INFO - PROCESS | 30519 | Values: 159.0 133.0 154.0 178.0 126.0 154.0 148.0 137.0 179.0 127.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#2] audio-dft Cycles:10 Average:164.30 Median:163.00 stddev:4.30 (2.6%) stddev-sans-first:4.53 22:28:42 INFO - PROCESS | 30519 | Values: 163.0 162.0 165.0 161.0 163.0 165.0 176.0 163.0 162.0 163.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#3] audio-fft Cycles:10 Average:93.20 Median:75.50 stddev:36.82 (48.8%) stddev-sans-first:38.46 22:28:42 INFO - PROCESS | 30519 | Values: 75.0 78.0 76.0 161.0 76.0 75.0 74.0 75.0 165.0 77.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#4] audio-oscillator Cycles:10 Average:103.40 Median:85.00 stddev:29.07 (34.2%) stddev-sans-first:30.15 22:28:42 INFO - PROCESS | 30519 | Values: 86.0 85.0 152.0 86.0 86.0 85.0 141.0 85.0 85.0 143.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 22:28:42 INFO - PROCESS | 30519 | Values: 118.0 117.0 118.0 117.0 118.0 118.0 117.0 117.0 117.0 117.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 22:28:42 INFO - PROCESS | 30519 | Values: 147.0 148.0 147.0 149.0 147.0 147.0 147.0 146.0 147.0 147.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 22:28:42 INFO - PROCESS | 30519 | Values: 96.0 96.0 96.0 96.0 95.0 95.0 96.0 96.0 96.0 95.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:54.50 stddev:3.54 (6.5%) stddev-sans-first:3.75 22:28:42 INFO - PROCESS | 30519 | Values: 55.0 54.0 61.0 54.0 53.0 53.0 63.0 55.0 53.0 54.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 22:28:42 INFO - PROCESS | 30519 | Values: 62.0 63.0 63.0 61.0 61.0 63.0 61.0 62.0 62.0 62.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:81.00 stddev:12.06 (14.9%) stddev-sans-first:12.68 22:28:42 INFO - PROCESS | 30519 | Values: 81.0 79.0 80.0 81.0 81.0 87.0 81.0 80.0 119.0 86.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#11] stanford-crypto-ccm Cycles:10 Average:129.80 Median:131.00 stddev:4.29 (3.3%) stddev-sans-first:4.53 22:28:42 INFO - PROCESS | 30519 | Values: 131.0 131.0 136.0 124.0 125.0 131.0 126.0 133.0 135.0 126.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.30 Median:166.00 stddev:4.62 (2.8%) stddev-sans-first:4.58 22:28:42 INFO - PROCESS | 30519 | Values: 168.0 167.0 169.0 164.0 158.0 158.0 160.0 167.0 165.0 157.0 22:28:42 INFO - PROCESS | 30519 | 22:28:42 INFO - PROCESS | 30519 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 22:28:42 INFO - PROCESS | 30519 | Values: 64.0 63.0 64.0 64.0 63.0 65.0 63.0 64.0 63.0 64.0 22:28:43 INFO - PROCESS | 30519 | -------- Summary: end -------- 22:28:43 INFO - PROCESS | 30519 | 22:28:43 ERROR - PROCESS | 30519 | 1465277323000 addons.xpi WARN Exception running bootstrap method shutdown on flyweb@mozilla.org: TypeError: gDiscoveryManagerInstance is undefined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@mozilla.org.xpi!/bootstrap.js:202:5) JS Stack trace: uninit@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@bootstrap.js:202:5 < shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/flyweb@bootstrap.js:41:3 < this.XPIProvider.callBootstrapMethod@XPIProvider.jsm:4754:9 < this.XPIProvider.startup/<.observe@XPIProvider.jsm:2778:15 < goQuitApplication@quit.js:101:5 < plStopAll@pageloader.js:804:3 < collectMemory/gMemCallback@memory.js:49:38 < collectAndReport@memory.js:63:3 < collectMemory@memory.js:57:5 < plStop@pageloader.js:755:5 < plNextPage<@pageloader.js:501:5 < TaskImpl_run@Task.jsm:319:40 < TaskImpl@Task.jsm:280:3 < createAsyncFunction/asyncFunction@Task.jsm:254:14 < setTimeout handler*_loadHandlerCapturing@pageloader.js:626:5 < plRecordTimeMessage@pageloader.js:728:3 < ContentListener.receiveMessage@pageloader.js:329:44 22:28:43 INFO - TEST-INFO | 30519: exit 0 22:28:43 INFO - TEST-OK | kraken | took 62529ms 22:28:43 INFO - SUITE-END | took 1173s 22:28:44 INFO - Completed test suite (00:19:33) 22:28:44 INFO - javascript benchmark 22:28:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7143.4515716937085, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3196.4246435641576, 3203.19920019995, 3221.5328777327286, 3223.983880080599, 3237.352529494102, 5394.921015796846, 5422.539084177844, 5437.3475182978045, 5529.529989950859, 5545.390007099226, 5420.91868621971, 5454.290942177511, 5483.972580137094, 5509.834704958857, 5529.529989950849, 4877.390326209215, 4985.975070124644, 5025.974870125644, 5073.0361231366105, 5110.386753589574, 4933.284673722308, 5080.237964305346, 5081.923771143436, 5111.51440613143, 5119.155339368999, 4868.172410690191, 4947.604191664658, 4954.628402869763, 4975.855553222761, 5006.0, 5032.9245061323745, 5047.747612619354, 5055.671381360236, 5060.2156665716875, 5062.873428164288, 5017.698938063728, 5020.322256495363, 5022.89954200914, 5042.747862606855, 5072.188449848043, 5346.492083252085, 5357.062514060063, 5423.620346575742, 5455.645383050089, 5466.207399927028, 5958.225430694026, 5966.850828729273, 5969.611975221596, 5980.1328807323125, 5993.61041532299, 6024.246969128859, 6035.7283922223605, 6036.0945858121195, 6040.426159514839, 6043.305019922721, 5362.758675859595, 5427.701476418795, 5432.809851655173, 5440.91838622421, 5477.890442191134, 5904.114382842522, 5937.10943358491, 5939.10913362999, 5940.524758019348, 5945.791897283661, 5657.0, 5708.971455142698, 5709.05800542914, 5714.485696287354, 5728.312602487728, 5838.0, 5838.941610583925, 5896.056630939029, 5920.348761636216, 5943.405659434108, 5705.229793977843, 5784.248047753765, 5876.1479585459865, 5879.2944846618675, 5885.529157667376, 5845.210896528999, 5856.297244330707, 5860.384659610765, 5952.0, 5953.672548009815, 4392.626626736763, 4452.220861349251, 4468.910621787546, 4501.0547784964865, 4504.864854054384, 4007.6593489553134, 4014.9397759033636, 4020.276350256982, 4021.959780402217, 4049.4735684360844, 5680.5455563554815, 5684.431556844283, 5692.060809966392, 5726.427357264323, 5756.280464941882, 5805.477507024388, 5918.585699001029, 5974.581779275496, 6002.729877155539, 6024.216851809237, 5670.234518340054, 5694.088945768657, 5715.714214289269, 5717.256756621696, 5724.77100915967, 5004.199328107486, 5145.897082058338, 5146.0, 5149.176131818891, 5206.6095042871175, 6056.576039677268, 6088.878222435526, 6095.408112664863, 6102.428659372087, 6133.907991380132, 5868.972929737364, 5877.324107727666, 5879.382664820303, 5881.235439392852, 5885.588008839341, 5919.556033297434, 5927.407259274038, 6064.272287325549, 6064.423879731418, 6071.483923866521, 5649.774009039593, 5726.7709291629535, 5731.254936858182, 5753.712314384347, 5792.507636850914, 5776.7400466979925, 5800.738966746422, 5816.360200377871, 5843.065109582446, 5852.122181672783, 5659.462351076641, 5664.461876121762, 5726.942730572641, 5790.189373487631, 5820.126980952891, 5615.157726341082, 5671.291088613923, 5671.801486947937, 5679.488846003879, 5900.5574581907385, 5632.127020311858, 5665.858353541194, 5668.178114173339, 5684.204211410323, 5689.6586204827845, 5588.273524441796, 5715.599908006566, 5775.220345253337, 5797.072468405035, 5815.854603634773, 5618.381977982337, 5659.717014149193, 5687.60186786921, 5693.5445164387575, 5735.197072409949], "value": 5425.811155547696, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.2462862820878, 612.5558969746933, 627.0531497438866, 631.8199313195739, 639.9616023038615, 37188.0, 41233.793831030875, 41435.75692729223, 41610.583894161085, 41875.37186942194, 33188.50217246737, 33480.83259583705, 33483.832580837065, 33522.0, 33565.0, 8703.521306328164, 8860.24687901529, 8866.42368246062, 8875.866861997076, 8897.68858089967, 8754.824903501925, 8838.160374764388, 8876.8668469973, 8879.600417981206, 8882.600282987249, 8702.173293537104, 8813.294936405073, 8843.425177363482, 8869.866951995726, 8885.111488851095, 8824.0, 8838.646454141826, 8847.911520884838, 8874.866876996817, 8891.06643802401, 8790.164934331293, 8851.60167792451, 8853.955730221373, 8896.466212027299, 8911.0197878234, 39629.22595418322, 39892.0, 40094.799526002476, 40108.59891401078, 40114.79942600268, 3562.6259242779392, 3636.3999940009867, 3763.9435408468894, 3840.2895464339185, 4275.679324050709, 31578.0, 31608.696086361833, 31785.841070794497, 31896.840515797274, 32578.6070021557, 4087.89780255493, 4130.132672138854, 4211.515675697304, 4215.536291007987, 4338.240807858612, 343361.0, 344373.9999999975, 344977.0, 346222.26888865646, 347509.0, 33928.32143357119, 35312.82343588266, 35545.0, 63159.0, 63347.0, 34869.476957845654, 34994.300113998084, 35066.29867402637, 35095.0, 35321.64678353235, 15004.699906002037, 15011.0, 15134.545963621109, 15174.165420901956, 15217.923910380377, 13029.804552931713, 13062.0, 13065.281409522377, 13073.542426015043, 13124.081314307907, 8380.078191398941, 8426.53654049021, 8429.662813487514, 8480.109588493242, 8502.702405415781, 3176.952345714816, 3190.3938251732097, 3192.0742984534227, 3197.568328275653, 3262.2986057997646, 1040.9479526023667, 1052.9947350263199, 1080.8865069167782, 1101.2346419238713, 1125.9507695993213, 33317.0, 33328.50007249893, 33335.83332083324, 33339.333213335594, 33490.66509334924, 1102.9172812039128, 1113.8663360396642, 1116.0402054233352, 1133.2463911498926, 1152.1301417429954, 17319.56701082483, 17406.564835879206, 17512.824871751374, 17597.208125634377, 17636.559086022953, 577708.0, 584057.0, 585538.0, 586150.0, 587138.0, 59543.70228148832, 60404.0, 60513.69743151256, 60654.39345606576, 60805.69597151986, 1376.3256004557895, 1386.473140206715, 1395.8464568897414, 1405.444849284527, 1408.7393832141086, 31140.53716427233, 32259.838700806347, 33070.0, 33256.833715831264, 33286.00141995744, 53333.0, 54507.18239226494, 56221.15668264897, 56342.0, 62340.688296558226, 521239.0, 522771.0, 523780.0, 524441.0, 525901.0, 44686.55313446824, 44692.10615787731, 44837.32744008777, 44981.0, 46496.7675161622, 48129.27806082981, 49584.75207623939, 50509.49490505048, 50634.0, 51043.48956510536, 40951.38572921349, 42762.78618606887, 43529.347059793494, 43836.561634384525, 44590.77704611456, 41690.79154604208, 41940.580594194886, 42187.0, 42499.787501062296, 43714.34428483639, 41760.16479670451, 42655.36016959686, 42670.57329426666, 42715.145697086504, 45160.29053753459, 34946.82526587351, 36814.8159259202, 37435.62564374322, 37535.0, 37565.248695026494, 28667.42665146727, 28860.0, 28911.7108828909, 28935.0, 28991.855040724662], "value": 19257.717944668206, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.5493295377932, 383.9731218814682, 384.3945785388014, 394.91509325495014, 404.9898752531186, 17140.314387424503, 18715.3449629263, 19500.219991200353, 19509.414717558462, 19580.608387832253, 17563.56091097723, 18331.45005649829, 18436.0, 18464.723029154575, 18582.907085464554, 5155.149400348947, 5323.574114070884, 5350.464953504638, 5357.410684824686, 5499.917501237484, 5640.407757185498, 5749.56878234132, 5750.424957504258, 5771.538276937835, 5787.536997040227, 5258.842234732964, 5330.973345133269, 5674.716264186774, 5709.172170035343, 5730.570207234453, 5211.426743058299, 5223.764930578133, 5234.607404444663, 5260.947390526103, 5285.947140528584, 4978.377702787152, 5100.566451851597, 5100.744962751847, 5106.336176297094, 5306.8407947761625, 13123.86876131236, 13415.329233538381, 13432.731345373135, 13448.596542103654, 22103.668444973337, 2075.4396312995405, 2180.476685595446, 2290.3243543154645, 2334.941626459352, 2422.87885605719, 9476.526173691287, 9636.469994150319, 9895.109440150349, 10207.081362677394, 14049.64875878101, 1896.3552392186584, 1936.6417212815534, 1958.9706154407695, 1997.0392154914646, 2007.0667139779925, 71338.64330678365, 72662.63668681675, 73982.63008684976, 82080.58959705223, 84211.5789421049, 10586.682399528027, 10993.670189894316, 11057.889421105847, 11186.496607652674, 11186.608468703556, 15415.614609634624, 15521.301541430663, 15545.0, 15546.0, 15559.22203889801, 8092.329531254672, 8277.0, 8465.915340846635, 8493.830123397622, 8590.957045214735, 8191.95904020488, 8205.261526462647, 8209.179082091863, 8244.83510329802, 8336.124706905688, 4698.882527936829, 4724.362211101457, 4726.078414709167, 4815.662903596714, 4851.393575803025, 1935.2549268531716, 1952.8535359847783, 1964.3517639179036, 1978.3965890403565, 2024.7671517775645, 926.2821313482106, 939.1313035442189, 1021.1568119544294, 1055.773008803111, 1082.6048492300254, 16774.329026839034, 17708.29166833338, 18987.71518427225, 19038.238470461303, 19077.332293369665, 992.5831150916632, 1006.5872992073215, 1014.8579198912158, 1040.7606250562412, 1073.5598404654054, 10432.374057556572, 10790.0, 10838.403887786248, 10848.294860834021, 10859.076978456762, 476979.0, 482563.0, 482691.0, 485318.0, 488249.0, 75399.62300188464, 91370.54314728516, 96345.51827240818, 98073.0, 100559.0, 999.3504222255447, 1022.6574097677212, 1056.4664844253757, 1058.6453538064682, 1072.2869291920786, 16448.259828307993, 16985.83014169892, 17212.13939303055, 17273.136343182538, 17658.0, 39705.60294397019, 40535.797321013204, 40601.79699101485, 41569.0, 41759.58240417557, 57285.0, 58907.410925890195, 59453.0, 59526.0, 59763.701181493816, 8049.195080491904, 8181.959090204511, 8337.83324333522, 8519.574021299035, 8718.346124040596, 9064.818703626022, 12797.424115914993, 12944.741105177656, 12998.870011299767, 13200.20798752078, 10986.121110311316, 11034.896510349223, 11170.329780213213, 11353.262037967506, 11475.942620286845, 11104.0, 11141.742470988687, 11197.328160310082, 11294.60468883585, 11457.369844658291, 11383.487743051417, 11489.0, 11618.94190529042, 11699.590514331958, 11921.463534141158, 7874.251946065115, 7959.681612735426, 7961.547819437645, 8086.0, 8469.830603388022, 8488.193621605938, 10553.314034587727, 11656.53373865036, 11734.471948762155, 11756.94121529387], "value": 8957.873078238097, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1468.7576549869273, 1514.492644963937, 1612.2664187794555, 1732.844044036038, 1766.567191038195, 7824.178461261571, 8397.286230670392, 8419.200175983271, 8948.194662480375, 9022.593983270752, 8513.872291915626, 8843.159899809523, 8864.601092950817, 8884.244839188661, 8899.243564297027, 2294.8393612447135, 2310.410845234463, 2310.572544079342, 2315.073970411832, 2324.6164382876805, 2224.4661281292456, 2232.6539386395134, 2270.9886450567724, 2291.415688999303, 2291.919782807602, 2237.1722462688826, 2243.4727838957915, 2255.4248666589997, 2257.0971611355426, 2258.1870526610405, 2227.543353612514, 2236.0496788864666, 2268.421552504109, 2269.126386341254, 2269.1263863412623, 2245.9663105053437, 2278.8176945844293, 2280.771922807706, 2284.257616274701, 2286.416963674269, 8689.913100869036, 8790.912090879074, 8791.78020549485, 8802.515861627608, 8808.427452215585, 5116.130257856174, 5174.3790745110455, 5223.869403264911, 5224.399194092652, 5229.294045303874, 3177.9682203177904, 3231.2245061185145, 3239.4492936200904, 3487.7384196185135, 3503.597086335053, 1200.4717924113363, 1200.4897918384652, 1976.0, 2001.0795034284247, 2093.3824521765964, 12086.093542984312, 12189.451474683661, 12277.324747138904, 12333.62999110028, 12348.950339221177, 4398.186335527937, 4408.53710360414, 4485.349624304457, 4503.639708823286, 4535.092981403733, 9638.518074096408, 9676.080772326617, 9685.467299298607, 9712.22302215821, 9772.951135244279, 5117.232415137684, 5162.148245539444, 5173.71544565045, 5268.604854635918, 5290.788368465295, 4323.2650449423045, 4704.388429504143, 4765.213739732974, 4828.517148285214, 4857.587105096036, 3768.924621507609, 3807.9428808567895, 3867.41988701697, 3889.669378102836, 3951.2492626401468, 1122.5341483284346, 1124.6063877642923, 1127.4644543841773, 1144.7195437117794, 1156.7050402147568, 840.9369297302727, 898.9865152022594, 914.7621618379136, 957.3681370295571, 970.9029097090234, 5897.793577224862, 5920.792772253037, 6102.4507794298725, 6113.113598528189, 6200.224971878515, 413.5760845133702, 426.2923546912111, 429.428859616709, 433.8741764888147, 434.98260069596864, 4074.083331250445, 4118.876433706993, 4122.690798190148, 4141.668666506672, 4194.853180138742, 10509.947450262853, 10853.457327133612, 10881.673549793519, 10894.291871028357, 10896.891031089746, 10576.36541807494, 10663.786724265627, 10710.839337409789, 10745.301555398873, 10787.083097936764, 8144.75565733029, 8190.344772418312, 8256.009278886451, 8298.917010829935, 8322.126176751475, 5704.885902282014, 5862.3844496328975, 5933.169356290209, 5946.464818166386, 5971.58198926071, 9663.371880827724, 9955.104040636377, 10005.349652272578, 10062.446565438971, 10114.089731924161, 10179.898201018192, 10186.949065254626, 10236.436995964996, 10370.688879333633, 10418.843717344094, 7582.810429739301, 7771.494852834548, 7787.493812902143, 7939.28546430824, 8071.677132914852, 8642.740717778477, 8678.088800675838, 8689.13108689126, 8706.956465217634, 8732.91267087321, 7372.557646541225, 7524.097108347033, 7703.614819259126, 7916.960415197887, 7972.043354797462, 7221.602811845399, 7498.32515073646, 7505.437092217996, 7531.88702169457, 7580.469367144247, 7084.574925504487, 7122.430205583644, 7151.141862976476, 7152.177499587614, 7163.677634506564, 5177.508136727005, 5259.500347466985, 5263.23683065961, 5291.7618707159045, 5382.219578161066, 5158.638895277295, 5194.35070616173, 5222.112240919128, 5328.626996110235, 5345.572354211576], "value": 4544.106055716044, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.90303957293025, 318.83579956322495, 325.35254842862713, 327.71325090545787, 327.9639239683633, 5523.0, 5625.493705566499, 5644.971775141129, 5645.915311270324, 5657.0, 5500.779968801253, 5526.309211348582, 5528.668279903209, 5538.224648549196, 5554.472325129106, 4210.368444733299, 4230.640395566381, 4288.313869780835, 4297.507445681513, 4305.67707421943, 4255.276602977486, 4269.316909294514, 4282.50751163617, 4289.485261768592, 4305.138972205556, 4259.446271984653, 4263.637590804786, 4275.5296917339065, 4286.164197981395, 4303.440552728141, 4002.8198731057173, 4073.551909289976, 4177.289860723684, 4269.701120921537, 4275.572442755715, 3684.2263124743836, 3692.723045771551, 3700.833462494194, 3711.795851228181, 3723.6276372362818, 5261.053010458154, 5316.627836051478, 5328.227407025959, 5345.064613692627, 5350.117230656937, 4814.855554333376, 4857.149998750205, 4866.902661946741, 4867.732274724889, 4876.609871210295, 5694.487496125369, 5694.715264236772, 5738.856528586777, 5749.3388260350175, 5776.36459989405, 4103.281925662997, 4120.7321524100835, 4145.150244199962, 4147.0, 4164.083901541656, 5616.859578510529, 6001.909971350433, 6029.276486821565, 6085.778690777878, 6105.877882442371, 4121.258173528734, 4126.319157339006, 4149.875503734893, 4150.875473735793, 4163.708540402204, 4194.6853985951175, 4209.473815773028, 4222.978885105616, 4239.173361194599, 4242.0455397535925, 4735.431748190171, 4765.761711914391, 4834.129856625771, 4865.245886887538, 4893.0, 5707.6575405475805, 5739.16782066598, 5784.161296611967, 5799.88400231993, 5804.912926306109, 1418.6453386653336, 1441.654003039284, 1448.630599197219, 1453.1716921354725, 1457.6283047822737, 877.8595424732013, 910.9160099481728, 916.2578311567652, 921.4885738415244, 924.1497822003772, 5033.370828646419, 5043.52086551777, 5052.368453943257, 5066.46802085783, 5090.440051594322, 5306.893862122736, 5419.078756611386, 5446.8757184698, 5456.963176996358, 5464.3442786865035, 5121.436641969381, 5126.102931986888, 5166.501714502752, 5261.421243663194, 5550.250716153268, 3091.27355071558, 3160.4627213373783, 3205.230744621321, 3205.8717651293687, 3214.5821043264227, 6141.416565426277, 6155.230596175478, 6159.29168145669, 6162.073015317778, 6197.411245931629, 5974.492168165754, 6098.817035488943, 6163.476104531165, 6192.59748116371, 6193.142057382767, 6167.753289868445, 6176.8764624707255, 6183.5671502994355, 6208.379162083756, 6216.2540495141775, 5725.885482290414, 5965.73154208053, 5977.462028417463, 6007.3992600739575, 6020.518358531394, 5867.383924687846, 5956.76172953077, 5969.4627483524955, 5979.940200598112, 6002.129691194839, 5948.405159484017, 6073.787417440369, 6134.294556126102, 6255.343188965275, 6270.905936410866, 3790.4693342932565, 3814.4469051986753, 3814.847406103836, 3821.350370437088, 3833.9233215336094, 5395.192610475535, 5418.347404041648, 5432.098765432067, 5438.422857371318, 5478.233047373451, 4939.0, 5232.319798426181, 5270.7101109439345, 5278.466336158589, 5312.415634280149, 5215.217717342429, 5223.52988231061, 5224.869378265422, 5244.711540865289, 5276.657017293864, 5165.173572228426, 5182.32629758129, 5204.90696953644, 5218.895622087461, 5269.604779641466, 4926.127776054757, 5201.373306480204, 5202.606800871641, 5210.713410762444, 5235.476452354734, 5049.798008079782, 5100.0, 5126.102931986813, 5139.820106296262, 5157.871053223699], "value": 4273.068550764396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.0557566799658, 767.0948281028384, 794.3645083932852, 800.4476910931457, 806.5120602035772, 14627.0, 15625.765613515778, 15756.054636721805, 15777.842221577781, 15865.444709435173, 13416.53042143525, 13466.5286714965, 13491.46034158635, 13537.93231033846, 13889.79165312521, 656.3207080671482, 778.420077042604, 783.1463704562012, 797.5772840394571, 802.3060053054124, 789.7472808701218, 800.951942883426, 801.6873419366452, 802.0535767794, 803.7267329108103, 841.8610929196675, 842.6503001254472, 843.2832092721169, 844.025150950653, 844.0841686769862, 829.5603330234985, 832.8958880139983, 834.2491757418342, 834.7787836223406, 837.1252041616495, 606.3209205689611, 612.7487730030666, 613.1844646619985, 613.6440864298696, 615.3047056825758, 14415.567532974028, 14470.565883023526, 14503.41986320546, 14516.709665806731, 14547.490837820731, 14778.0, 15000.174990375524, 15034.849651503455, 15182.164980926045, 15277.61805954849, 11939.462724177432, 12057.758844823054, 12098.0, 12229.877701222962, 12270.0, 3910.8240129194255, 3931.3709806430834, 3932.9803350983348, 3940.3892396678275, 4066.8779936601654, 74351.25648743498, 74454.0, 76034.0, 76142.23857761407, 76431.617841911, 11690.88309116915, 11691.649250522498, 11761.647150585324, 11850.348230847214, 12045.518179272905, 22003.33989980271, 22101.557968840534, 22171.224007159995, 22319.776802232096, 22803.543929121326, 8671.826563468696, 8762.342824288204, 8791.780205494913, 8858.822823543493, 8950.373473856898, 4675.7895894684825, 4703.764811759467, 4835.709857408567, 4838.879028024327, 4890.413150421972, 3632.3461776880417, 3777.0746167189213, 3779.244151169777, 3785.981070094632, 3805.448210009533, 605.4762630324738, 625.4152367536368, 627.9403456671608, 642.9614223146533, 643.3919945651314, 692.4529621598981, 709.386380780621, 712.2983860897012, 762.969481220756, 767.6468824340755, 9422.152006319464, 9573.090556397132, 9636.277279204087, 9666.178374838077, 9856.0, 667.2226855713137, 672.7174586673609, 693.7918624412656, 713.293839099288, 772.4476998945669, 4613.146567884952, 4769.165396055676, 4788.209945358977, 4803.1114243864995, 4864.294677271776, 33642.49536256958, 33686.663133368354, 33704.83147584246, 33798.83100584481, 106700.0, 86389.0, 91826.54086729523, 93459.0, 93554.53222733842, 94584.52707736417, 84572.57713711393, 84662.5766871174, 84706.57646711727, 85057.57471212605, 85359.57320213359, 8917.643294268157, 9154.26765858743, 9156.633734650539, 9211.170994610517, 9267.72196834096, 48659.0, 49318.50681493283, 49334.50665493299, 51156.44217789021, 51301.48698512967, 47573.76213118912, 71079.0, 73428.26571734215, 75226.62386688031, 76851.23148768664, 11417.942910285396, 11469.713257168638, 11543.942280288546, 11552.884471155181, 11667.416629168678, 12946.482140714266, 13098.345082746015, 13171.07802453819, 13193.20840749558, 13463.12489688167, 12185.51257949672, 13257.337133143497, 13420.46318147263, 13709.588712338646, 13762.105463144864, 13657.863421366057, 13933.58199254024, 14125.434982600991, 14592.854071459149, 14738.926305368404, 13546.864531354955, 13800.93099534496, 13831.723365532433, 13891.652708682364, 14183.0, 10882.129429645767, 11129.0, 11229.21395502307, 11249.550017999189, 11464.31214127155, 10902.56389744433, 11275.830862537232, 11362.886371136514, 11484.770304593694, 11718.414079296172], "value": 7310.431403192705, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 111.0, 112.0, 113.0, 111.0, 112.0, 111.0, 111.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 133.0, 154.0, 178.0, 126.0, 154.0, 148.0, 137.0, 179.0, 127.0], "value": 149.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 165.0, 161.0, 163.0, 165.0, 176.0, 163.0, 162.0, 163.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 76.0, 161.0, 76.0, 75.0, 74.0, 75.0, 165.0, 77.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 152.0, 86.0, 86.0, 85.0, 141.0, 85.0, 85.0, 143.0], "value": 103.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 149.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 61.0, 54.0, 53.0, 53.0, 63.0, 55.0, 53.0, 54.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 61.0, 61.0, 63.0, 61.0, 62.0, 62.0, 62.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 80.0, 81.0, 81.0, 87.0, 81.0, 80.0, 119.0, 86.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 131.0, 136.0, 124.0, 125.0, 131.0, 126.0, 133.0, 135.0, 126.0], "value": 129.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 169.0, 164.0, 158.0, 158.0, 160.0, 167.0, 165.0, 157.0], "value": 163.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 64.0, 63.0, 65.0, 63.0, 64.0, 63.0, 64.0], "value": 63.7, "unit": "score"}]}]} 22:28:44 INFO - Return code: 0 22:28:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:28:44 ERROR - # TBPL SUCCESS # 22:28:44 INFO - Running post-action listener: _resource_record_post_action 22:28:44 INFO - ##### 22:28:44 INFO - ##### Finished run-tests step (success) 22:28:44 INFO - ##### 22:28:44 INFO - Running post-run listener: _resource_record_post_run 22:28:44 INFO - Total resource usage - Wall time: 1192s; CPU: 14.0%; Read bytes: 8192; Write bytes: 540745728; Read time: 284; Write time: 714268 22:28:44 INFO - TinderboxPrint: CPU usage
13.5% 22:28:44 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 284 22:28:44 INFO - TinderboxPrint: I/O write bytes / time
540,745,728 / 714,268 22:28:44 INFO - TinderboxPrint: CPU idle
8,223.8 (86.5%) 22:28:44 INFO - TinderboxPrint: CPU user
1,227.1 (12.9%) 22:28:44 INFO - TinderboxPrint: Swap in / out
0 / 0 22:28:44 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247574528; Read time: 0; Write time: 421652 22:28:44 INFO - run-tests - Wall time: 1175s; CPU: 14.0%; Read bytes: 8192; Write bytes: 292700160; Read time: 284; Write time: 292616 22:28:44 INFO - Running post-run listener: _upload_blobber_files 22:28:44 INFO - Blob upload gear active. 22:28:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:28:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:28:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:28:45 INFO - (blobuploader) - INFO - Open directory for files ... 22:28:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:28:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:28:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:46 INFO - (blobuploader) - INFO - Done attempting. 22:28:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:28:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:28:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:50 INFO - (blobuploader) - INFO - Done attempting. 22:28:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:28:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:28:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:51 INFO - (blobuploader) - INFO - Done attempting. 22:28:51 INFO - (blobuploader) - INFO - Iteration through files over. 22:28:51 INFO - Return code: 0 22:28:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:28:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:28:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e18adc65c4fb019e5886decd32d24b49c8f83cba64dfd5fa02131659285c16bb3483dbc27154d736370c60fe2a96fc369ed9d3a0de7a666bcab17d386786193", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15e823b7550f051edd3e7d6305cd8fe72b23cfb3de62fbdb1fa56b94ad65e3cc79726fec9e8851072b82e940115cfed3ba47dff4a84f0896c368bc4d51cd9a37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/696142628a691250c511ded078c148bb4ae7af4a7617bef521fcd05cb8a8b18ba08c78b4f29dd7a97170ea37e9899dd02d0fd32b589ef8ca217d859269fad868"} 22:28:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:28:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:28:51 INFO - Contents: 22:28:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e18adc65c4fb019e5886decd32d24b49c8f83cba64dfd5fa02131659285c16bb3483dbc27154d736370c60fe2a96fc369ed9d3a0de7a666bcab17d386786193", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15e823b7550f051edd3e7d6305cd8fe72b23cfb3de62fbdb1fa56b94ad65e3cc79726fec9e8851072b82e940115cfed3ba47dff4a84f0896c368bc4d51cd9a37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/696142628a691250c511ded078c148bb4ae7af4a7617bef521fcd05cb8a8b18ba08c78b4f29dd7a97170ea37e9899dd02d0fd32b589ef8ca217d859269fad868"} 22:28:51 INFO - Running post-run listener: copy_logs_to_upload_dir 22:28:51 INFO - Copying logs to upload dir... 22:28:51 INFO - mkdir: /builds/slave/test/build/upload/logs 22:28:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.482325 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-06-06 22:28:51.313418) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 22:28:51.317042) ========= 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=d2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762 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/Mozilla-Inbound/sha512/2e18adc65c4fb019e5886decd32d24b49c8f83cba64dfd5fa02131659285c16bb3483dbc27154d736370c60fe2a96fc369ed9d3a0de7a666bcab17d386786193", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15e823b7550f051edd3e7d6305cd8fe72b23cfb3de62fbdb1fa56b94ad65e3cc79726fec9e8851072b82e940115cfed3ba47dff4a84f0896c368bc4d51cd9a37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/696142628a691250c511ded078c148bb4ae7af4a7617bef521fcd05cb8a8b18ba08c78b4f29dd7a97170ea37e9899dd02d0fd32b589ef8ca217d859269fad868"} build_url:https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011155 build_url: 'https://queue.taskcluster.net/v1/task/FIrsSzApQ8y7fMeGKOWhyg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e18adc65c4fb019e5886decd32d24b49c8f83cba64dfd5fa02131659285c16bb3483dbc27154d736370c60fe2a96fc369ed9d3a0de7a666bcab17d386786193", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15e823b7550f051edd3e7d6305cd8fe72b23cfb3de62fbdb1fa56b94ad65e3cc79726fec9e8851072b82e940115cfed3ba47dff4a84f0896c368bc4d51cd9a37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/696142628a691250c511ded078c148bb4ae7af4a7617bef521fcd05cb8a8b18ba08c78b4f29dd7a97170ea37e9899dd02d0fd32b589ef8ca217d859269fad868"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 22:28:51.343482) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:28:51.343830) ========= 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=d2a8c569965ab8367867b893000001a1-1465275687.901754-1969425762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004542 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:28:51.428086) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 22:28:51.428860) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 22:28:51.429335) ========= ========= Total master_lag: 0.17 =========