builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1466770662.4 results: success (0) buildid: 20160624023357 builduid: 0e6b3271dec145a599e3530f79b6e9d2 revision: afcdd85399636b518887cc17aedbda8cc1c1f197 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:42.398731) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:42.399435) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:42.399849) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:42.415741) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:42.416066) ========= 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=52faae308a556c440401f8e5000001e8-1466770662.273993-947807449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-24 05:17:43-- 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% 218M=0s 2016-06-24 05:17:43 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114221 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:42.546316) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:42.546838) ========= 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=52faae308a556c440401f8e5000001e8-1466770662.273993-947807449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015012 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:42.632427) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:42.633239) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev afcdd85399636b518887cc17aedbda8cc1c1f197 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev afcdd85399636b518887cc17aedbda8cc1c1f197 --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=52faae308a556c440401f8e5000001e8-1466770662.273993-947807449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-24 05:17:43,524 truncating revision to first 12 chars 2016-06-24 05:17:43,524 Setting DEBUG logging. 2016-06-24 05:17:43,524 attempt 1/10 2016-06-24 05:17:43,524 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/afcdd8539963?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-24 05:17:43,846 unpacking tar archive at: mozilla-aurora-afcdd8539963/testing/mozharness/ program finished with exit code 0 elapsedTime=0.666254 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:43.315438) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:43.316035) ========= script_repo_revision: afcdd85399636b518887cc17aedbda8cc1c1f197 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:43.316538) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:43.316920) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-24 05:17:43.327785) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-06-24 05:17:43.328074) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1466770662.273993-947807449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:17:44 INFO - MultiFileLogger online at 20160624 05:17:44 in /builds/slave/test 05:17:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:17:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:17:44 INFO - {'append_to_log': False, 05:17:44 INFO - 'base_work_dir': '/builds/slave/test', 05:17:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:17:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:17:44 INFO - 'branch': 'Mozilla-Aurora', 05:17:44 INFO - 'buildbot_json_path': 'buildprops.json', 05:17:44 INFO - 'config_files': ('talos/linux_config.py',), 05:17:44 INFO - 'default_actions': ('clobber', 05:17:44 INFO - 'read-buildbot-config', 05:17:44 INFO - 'download-and-extract', 05:17:44 INFO - 'populate-webroot', 05:17:44 INFO - 'create-virtualenv', 05:17:44 INFO - 'install', 05:17:44 INFO - 'run-tests'), 05:17:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:17:44 INFO - 'download_minidump_stackwalk': True, 05:17:44 INFO - 'download_symbols': 'ondemand', 05:17:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:17:44 INFO - 'tooltool.py': '/tools/tooltool.py', 05:17:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:17:44 INFO - '/tools/misc-python/virtualenv.py')}, 05:17:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:17:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:17:44 INFO - 'installer_path': 'installer.exe', 05:17:44 INFO - 'log_level': 'info', 05:17:44 INFO - 'log_name': 'talos', 05:17:44 INFO - 'log_to_console': True, 05:17:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:17:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:17:44 INFO - 'opt_config_files': (), 05:17:44 INFO - 'pip_index': False, 05:17:44 INFO - 'sps_profile': False, 05:17:44 INFO - 'sps_profile_interval': 0, 05:17:44 INFO - 'suite': 'dromaeojs', 05:17:44 INFO - 'system_bits': '32', 05:17:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:17:44 INFO - 'title': 'talos-linux64-ix-056', 05:17:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:17:44 INFO - 'use_talos_json': True, 05:17:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:17:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:17:44 INFO - 'work_dir': 'build'} 05:17:44 INFO - ##### 05:17:44 INFO - ##### Running clobber step. 05:17:44 INFO - ##### 05:17:44 INFO - Running pre-action listener: _resource_record_pre_action 05:17:44 INFO - Running main action method: clobber 05:17:44 INFO - rmtree: /builds/slave/test/build 05:17:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:17:44 INFO - Running post-action listener: _resource_record_post_action 05:17:44 INFO - ##### 05:17:44 INFO - ##### Finished clobber step (success) 05:17:44 INFO - ##### 05:17:44 INFO - ##### 05:17:44 INFO - ##### Running read-buildbot-config step. 05:17:44 INFO - ##### 05:17:44 INFO - Running pre-action listener: _resource_record_pre_action 05:17:44 INFO - Running main action method: read_buildbot_config 05:17:44 INFO - Using buildbot properties: 05:17:44 INFO - { 05:17:44 INFO - "project": "", 05:17:44 INFO - "product": "firefox", 05:17:44 INFO - "script_repo_revision": "production", 05:17:44 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 05:17:44 INFO - "repository": "", 05:17:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 05:17:44 INFO - "buildid": "20160624023357", 05:17:44 INFO - "pgo_build": "True", 05:17:44 INFO - "basedir": "/builds/slave/test", 05:17:44 INFO - "buildnumber": 313, 05:17:44 INFO - "slavename": "talos-linux64-ix-056", 05:17:44 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:17:44 INFO - "platform": "ubuntu64_hw", 05:17:44 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:17:44 INFO - "branch": "mozilla-aurora", 05:17:44 INFO - "repo_path": "releases/mozilla-aurora", 05:17:44 INFO - "revision": "afcdd85399636b518887cc17aedbda8cc1c1f197", 05:17:44 INFO - "stage_platform": "linux64-pgo", 05:17:44 INFO - "builduid": "0e6b3271dec145a599e3530f79b6e9d2", 05:17:44 INFO - "slavebuilddir": "test" 05:17:44 INFO - } 05:17:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 05:17:44 INFO - Running post-action listener: _resource_record_post_action 05:17:44 INFO - ##### 05:17:44 INFO - ##### Finished read-buildbot-config step (success) 05:17:44 INFO - ##### 05:17:44 INFO - ##### 05:17:44 INFO - ##### Running download-and-extract step. 05:17:44 INFO - ##### 05:17:44 INFO - Running pre-action listener: _resource_record_pre_action 05:17:44 INFO - Running main action method: download_and_extract 05:17:44 INFO - mkdir: /builds/slave/test/build/tests 05:17: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')]} 05:17:44 INFO - https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:17:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 05:17:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 05:17:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 05:17:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:17:45 INFO - Downloaded 1361 bytes. 05:17:45 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 05:17:45 INFO - Using the following test package requirements: 05:17:45 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 05:17:45 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:17:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:17:45 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:17:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:17:45 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:17:45 INFO - u'jsshell-linux-x86_64.zip'], 05:17:45 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:17:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:17:45 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 05:17:45 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:17:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:17:45 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:17:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:17:45 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:17:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:17:45 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:17:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:17:45 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:45 INFO - https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:17:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 05:17:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 05:17:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 05:17:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:17:46 INFO - Downloaded 24516165 bytes. 05:17:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:17:47 INFO - Return code: 0 05:17:47 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:17:47 INFO - https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:17:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 05:17:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 05:17:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 05:17:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:17:48 INFO - Downloaded 24516165 bytes. 05:17:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:17:50 INFO - Return code: 0 05:17:50 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')]} 05:17:50 INFO - https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:17:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:17:51 INFO - Downloaded 10833369 bytes. 05:17:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:17:51 INFO - Return code: 0 05:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:51 INFO - https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:17:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 05:17:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 05:17:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 05:17:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:17:52 INFO - Downloaded 63614198 bytes. 05:17:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 05:17:52 INFO - mkdir: /builds/slave/test/properties 05:17:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:17:52 INFO - Writing to file /builds/slave/test/properties/build_url 05:17:52 INFO - Contents: 05:17:52 INFO - build_url:https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 05:17:53 INFO - Running post-action listener: _resource_record_post_action 05:17:53 INFO - Running post-action listener: set_extra_try_arguments 05:17:53 INFO - ##### 05:17:53 INFO - ##### Finished download-and-extract step (success) 05:17:53 INFO - ##### 05:17:53 INFO - ##### 05:17:53 INFO - ##### Running populate-webroot step. 05:17:53 INFO - ##### 05:17:53 INFO - Running pre-action listener: _resource_record_pre_action 05:17:53 INFO - Running main action method: populate_webroot 05:17:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:17:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:17:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:17:53 INFO - '--tppagecycles', 05:17:53 INFO - '7'], 05:17:53 INFO - 'tests': ['tsvgm']}}, 05:17:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:17:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:17:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:17:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:17:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:17:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:17:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:53 INFO - 'talos_options': ['--disable-e10s'], 05:17:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:53 INFO - 'talos_options': ['--disable-e10s'], 05:17:53 INFO - 'tests': ['damp', 'tps']}, 05:17:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:53 INFO - 'tests': ['damp', 'tps']}, 05:17:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:17:53 INFO - 'tests': ['dromaeo_dom']}, 05:17:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:17:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:17:53 INFO - 'tests': ['a11yr', 05:17:53 INFO - 'ts_paint', 05:17:53 INFO - 'tpaint', 05:17:53 INFO - 'sessionrestore', 05:17:53 INFO - 'sessionrestore_no_auto_restore', 05:17:53 INFO - 'tabpaint']}, 05:17:53 INFO - 'other-e10s': {'tests': ['a11yr', 05:17:53 INFO - 'ts_paint', 05:17:53 INFO - 'tpaint', 05:17:53 INFO - 'sessionrestore', 05:17:53 INFO - 'sessionrestore_no_auto_restore', 05:17:53 INFO - 'tabpaint']}, 05:17:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:17:53 INFO - 'ts_paint', 05:17:53 INFO - 'tpaint', 05:17:53 INFO - 'sessionrestore', 05:17:53 INFO - 'sessionrestore_no_auto_restore', 05:17:53 INFO - 'tabpaint']}, 05:17:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:17:53 INFO - 'ts_paint', 05:17:53 INFO - 'tpaint', 05:17:53 INFO - 'sessionrestore', 05:17:53 INFO - 'sessionrestore_no_auto_restore', 05:17:53 INFO - 'tabpaint']}, 05:17:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:17:53 INFO - 'tests': ['a11yr', 05:17:53 INFO - 'ts_paint', 05:17:53 INFO - 'tpaint', 05:17:53 INFO - 'sessionrestore', 05:17:53 INFO - 'sessionrestore_no_auto_restore', 05:17:53 INFO - 'tabpaint']}, 05:17:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:17:53 INFO - 'tests': ['a11yr', 05:17:53 INFO - 'ts_paint', 05:17:53 INFO - 'tpaint', 05:17:53 INFO - 'sessionrestore', 05:17:53 INFO - 'sessionrestore_no_auto_restore', 05:17:53 INFO - 'tabpaint']}, 05:17:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:17:53 INFO - 'tests': ['tsvgx', 05:17:53 INFO - 'tsvgr_opacity', 05:17:53 INFO - 'tart', 05:17:53 INFO - 'tscrollx', 05:17:53 INFO - 'cart']}, 05:17:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:17:53 INFO - 'tsvgr_opacity', 05:17:53 INFO - 'tart', 05:17:53 INFO - 'tscrollx', 05:17:53 INFO - 'cart']}, 05:17:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:53 INFO - 'talos_options': ['--disable-e10s'], 05:17:53 INFO - 'tests': ['tp5o']}, 05:17:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:53 INFO - 'tests': ['tp5o']}, 05:17:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:53 INFO - 'talos_options': ['--disable-e10s', 05:17:53 INFO - '--xperf_path', 05:17:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:53 INFO - 'tests': ['tp5n']}, 05:17:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:53 INFO - 'talos_options': ['--xperf_path', 05:17:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:53 INFO - 'tests': ['tp5n']}}, 05:17:53 INFO - 'talos.zip': {'path': '', 05:17:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:17:53 INFO - Running post-action listener: _resource_record_post_action 05:17:53 INFO - ##### 05:17:53 INFO - ##### Finished populate-webroot step (success) 05:17:53 INFO - ##### 05:17:53 INFO - ##### 05:17:53 INFO - ##### Running create-virtualenv step. 05:17:53 INFO - ##### 05:17:53 INFO - Running pre-action listener: _resource_record_pre_action 05:17:53 INFO - Running main action method: create_virtualenv 05:17:53 INFO - Creating virtualenv /builds/slave/test/build/venv 05:17:53 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 05:17:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:17:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:17:53 INFO - Using real prefix '/usr' 05:17:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:17:54 INFO - Installing distribute.............................................................................................................................................................................................done. 05:17:56 INFO - Installing pip.................done. 05:17:56 INFO - Return code: 0 05:17:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb4980f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e53350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, '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': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', '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 05:17:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:17:56 INFO - Using env: {'DISPLAY': ':0', 05:17:56 INFO - 'HOME': '/home/cltbld', 05:17:56 INFO - 'LANG': 'en_US.UTF-8', 05:17:56 INFO - 'LANGUAGE': 'en_US:en', 05:17:56 INFO - 'LOGNAME': 'cltbld', 05:17:56 INFO - 'MAIL': '/var/mail/cltbld', 05:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:56 INFO - 'MOZ_NO_REMOTE': '1', 05:17:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:56 INFO - 'NO_EM_RESTART': '1', 05:17:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:56 INFO - 'PWD': '/builds/slave/test', 05:17:56 INFO - 'SHELL': '/bin/bash', 05:17:56 INFO - 'SHLVL': '1', 05:17:56 INFO - 'TERM': 'linux', 05:17:56 INFO - 'TMOUT': '86400', 05:17:56 INFO - 'USER': 'cltbld', 05:17:56 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:56 INFO - '_': '/tools/buildbot/bin/python'} 05:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:56 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 05:17:56 INFO - Cleaning up... 05:17:56 INFO - Return code: 0 05:17:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fceb4980f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e53350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, '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': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', '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 05:17:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:17:56 INFO - Using env: {'DISPLAY': ':0', 05:17:56 INFO - 'HOME': '/home/cltbld', 05:17:56 INFO - 'LANG': 'en_US.UTF-8', 05:17:56 INFO - 'LANGUAGE': 'en_US:en', 05:17:56 INFO - 'LOGNAME': 'cltbld', 05:17:56 INFO - 'MAIL': '/var/mail/cltbld', 05:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:56 INFO - 'MOZ_NO_REMOTE': '1', 05:17:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:56 INFO - 'NO_EM_RESTART': '1', 05:17:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:56 INFO - 'PWD': '/builds/slave/test', 05:17:56 INFO - 'SHELL': '/bin/bash', 05:17:56 INFO - 'SHLVL': '1', 05:17:56 INFO - 'TERM': 'linux', 05:17:56 INFO - 'TMOUT': '86400', 05:17:56 INFO - 'USER': 'cltbld', 05:17:56 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:56 INFO - '_': '/tools/buildbot/bin/python'} 05:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:56 INFO - Downloading/unpacking psutil>=3.1.1 05:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:17:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:58 INFO - Installing collected packages: psutil 05:17:58 INFO - Running setup.py install for psutil 05:17:58 INFO - building 'psutil._psutil_linux' extension 05:17:58 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 05:17:58 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 05:17:58 INFO - building 'psutil._psutil_posix' extension 05:17:58 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 05:17:59 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 05:17:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:59 INFO - Successfully installed psutil 05:17:59 INFO - Cleaning up... 05:17:59 INFO - Return code: 0 05:17:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb4980f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e53350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, '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': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', '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 05:17:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:17:59 INFO - Using env: {'DISPLAY': ':0', 05:17:59 INFO - 'HOME': '/home/cltbld', 05:17:59 INFO - 'LANG': 'en_US.UTF-8', 05:17:59 INFO - 'LANGUAGE': 'en_US:en', 05:17:59 INFO - 'LOGNAME': 'cltbld', 05:17:59 INFO - 'MAIL': '/var/mail/cltbld', 05:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:59 INFO - 'MOZ_NO_REMOTE': '1', 05:17:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:17:59 INFO - 'NO_EM_RESTART': '1', 05:17:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:59 INFO - 'PWD': '/builds/slave/test', 05:17:59 INFO - 'SHELL': '/bin/bash', 05:17:59 INFO - 'SHLVL': '1', 05:17:59 INFO - 'TERM': 'linux', 05:17:59 INFO - 'TMOUT': '86400', 05:17:59 INFO - 'USER': 'cltbld', 05:17:59 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:59 INFO - '_': '/tools/buildbot/bin/python'} 05:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:18:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:18:01 INFO - Installing collected packages: mozsystemmonitor 05:18:01 INFO - Running setup.py install for mozsystemmonitor 05:18:01 INFO - Successfully installed mozsystemmonitor 05:18:01 INFO - Cleaning up... 05:18:01 INFO - Return code: 0 05:18:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fceb4980f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e53350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, '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': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', '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 05:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:18:01 INFO - Using env: {'DISPLAY': ':0', 05:18:01 INFO - 'HOME': '/home/cltbld', 05:18:01 INFO - 'LANG': 'en_US.UTF-8', 05:18:01 INFO - 'LANGUAGE': 'en_US:en', 05:18:01 INFO - 'LOGNAME': 'cltbld', 05:18:01 INFO - 'MAIL': '/var/mail/cltbld', 05:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:01 INFO - 'MOZ_NO_REMOTE': '1', 05:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:18:01 INFO - 'NO_EM_RESTART': '1', 05:18:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:01 INFO - 'PWD': '/builds/slave/test', 05:18:01 INFO - 'SHELL': '/bin/bash', 05:18:01 INFO - 'SHLVL': '1', 05:18:01 INFO - 'TERM': 'linux', 05:18:01 INFO - 'TMOUT': '86400', 05:18:01 INFO - 'USER': 'cltbld', 05:18:01 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:01 INFO - '_': '/tools/buildbot/bin/python'} 05:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:01 INFO - Downloading/unpacking jsonschema==2.5.1 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:18:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:03 INFO - Downloading functools32-3.2.3-2.tar.gz 05:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:18:03 INFO - warning: no files found matching '*.txt' 05:18:03 INFO - no previously-included directories found matching 'build' 05:18:03 INFO - no previously-included directories found matching 'dist' 05:18:03 INFO - no previously-included directories found matching '.git*' 05:18:04 INFO - Installing collected packages: jsonschema, functools32 05:18:04 INFO - Running setup.py install for functools32 05:18:04 INFO - warning: no files found matching '*.txt' 05:18:04 INFO - no previously-included directories found matching 'build' 05:18:04 INFO - no previously-included directories found matching 'dist' 05:18:04 INFO - no previously-included directories found matching '.git*' 05:18:04 INFO - Successfully installed jsonschema functools32 05:18:04 INFO - Cleaning up... 05:18:04 INFO - Return code: 0 05:18:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:04 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 0x7fceb4980f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e53350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, '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': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', '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 05:18:04 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 05:18:04 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 05:18:04 INFO - Using env: {'DISPLAY': ':0', 05:18:04 INFO - 'HOME': '/home/cltbld', 05:18:04 INFO - 'LANG': 'en_US.UTF-8', 05:18:04 INFO - 'LANGUAGE': 'en_US:en', 05:18:04 INFO - 'LOGNAME': 'cltbld', 05:18:04 INFO - 'MAIL': '/var/mail/cltbld', 05:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:04 INFO - 'MOZ_NO_REMOTE': '1', 05:18:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:18:04 INFO - 'NO_EM_RESTART': '1', 05:18:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:04 INFO - 'PWD': '/builds/slave/test', 05:18:04 INFO - 'SHELL': '/bin/bash', 05:18:04 INFO - 'SHLVL': '1', 05:18:04 INFO - 'TERM': 'linux', 05:18:04 INFO - 'TMOUT': '86400', 05:18:04 INFO - 'USER': 'cltbld', 05:18:04 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:04 INFO - '_': '/tools/buildbot/bin/python'} 05:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:18:04 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 05:18:04 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 05:18:04 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 05:18:04 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 05:18:04 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 05:18:04 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 05:18:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18:06 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 05:18:06 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 05:18:06 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 05:18:06 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 05:18:06 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 05:18:06 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 05:18:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:18:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:18:06 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 05:18:06 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 05:18:06 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 05:18:06 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 05:18:06 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 05:18:06 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 05:18:07 INFO - Downloading docopt-0.6.1.tar.gz 05:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:18:07 INFO - Installing collected packages: blobuploader, requests, docopt 05:18:07 INFO - Running setup.py install for blobuploader 05:18:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:18:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:18:07 INFO - Running setup.py install for requests 05:18:07 INFO - Running setup.py install for docopt 05:18:07 INFO - Successfully installed blobuploader requests docopt 05:18:07 INFO - Cleaning up... 05:18:07 INFO - Return code: 0 05:18:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb4980f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e53350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, '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': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', '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 05:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:07 INFO - Using env: {'DISPLAY': ':0', 05:18:07 INFO - 'HOME': '/home/cltbld', 05:18:07 INFO - 'LANG': 'en_US.UTF-8', 05:18:07 INFO - 'LANGUAGE': 'en_US:en', 05:18:07 INFO - 'LOGNAME': 'cltbld', 05:18:07 INFO - 'MAIL': '/var/mail/cltbld', 05:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:07 INFO - 'MOZ_NO_REMOTE': '1', 05:18:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:18:07 INFO - 'NO_EM_RESTART': '1', 05:18:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:07 INFO - 'PWD': '/builds/slave/test', 05:18:07 INFO - 'SHELL': '/bin/bash', 05:18:07 INFO - 'SHLVL': '1', 05:18:07 INFO - 'TERM': 'linux', 05:18:07 INFO - 'TMOUT': '86400', 05:18:07 INFO - 'USER': 'cltbld', 05:18:07 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:07 INFO - '_': '/tools/buildbot/bin/python'} 05:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:07 INFO - Running setup.py (path:/tmp/pip-6Wa43d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:07 INFO - Running setup.py (path:/tmp/pip-NMhuY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:08 INFO - Running setup.py (path:/tmp/pip-0MTG6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:08 INFO - Running setup.py (path:/tmp/pip-lSyh7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:08 INFO - Running setup.py (path:/tmp/pip-n_JV98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:08 INFO - Running setup.py (path:/tmp/pip-qetUgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:08 INFO - Running setup.py (path:/tmp/pip-gSv62K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:08 INFO - Running setup.py (path:/tmp/pip-r8zgSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:08 INFO - Running setup.py (path:/tmp/pip-xx8cM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:08 INFO - Running setup.py (path:/tmp/pip-UuZjJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:08 INFO - Running setup.py (path:/tmp/pip-5_sobF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:08 INFO - Running setup.py (path:/tmp/pip-Bc_NDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:08 INFO - Running setup.py (path:/tmp/pip-YgtHmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:08 INFO - Running setup.py (path:/tmp/pip-430tn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:08 INFO - Running setup.py (path:/tmp/pip-Fw6Ck8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:08 INFO - Running setup.py (path:/tmp/pip-IQoDb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:09 INFO - Running setup.py (path:/tmp/pip-vA09eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:18:09 INFO - Running setup.py install for manifestparser 05:18:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:18:09 INFO - Running setup.py install for mozcrash 05:18:09 INFO - Running setup.py install for mozdebug 05:18:09 INFO - Running setup.py install for mozdevice 05:18:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:18:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:18:09 INFO - Running setup.py install for mozfile 05:18:09 INFO - Running setup.py install for mozhttpd 05:18:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:18:09 INFO - Running setup.py install for mozinfo 05:18:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:18:09 INFO - Running setup.py install for mozInstall 05:18:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:18:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:18:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:18:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:18:09 INFO - Running setup.py install for mozleak 05:18:10 INFO - Running setup.py install for mozlog 05:18:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:18:10 INFO - Running setup.py install for moznetwork 05:18:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:18:10 INFO - Running setup.py install for mozprocess 05:18:10 INFO - Running setup.py install for mozprofile 05:18:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:18:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:18:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:18:10 INFO - Running setup.py install for mozrunner 05:18:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:18:10 INFO - Running setup.py install for mozscreenshot 05:18:10 INFO - Running setup.py install for moztest 05:18:10 INFO - Running setup.py install for mozversion 05:18:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:18:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:18:10 INFO - Cleaning up... 05:18:10 INFO - Return code: 0 05:18:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:10 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 0x7fceb4980f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e53350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, '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': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', '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 05:18:10 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 05:18:10 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 05:18:10 INFO - Using env: {'DISPLAY': ':0', 05:18:10 INFO - 'HOME': '/home/cltbld', 05:18:10 INFO - 'LANG': 'en_US.UTF-8', 05:18:10 INFO - 'LANGUAGE': 'en_US:en', 05:18:10 INFO - 'LOGNAME': 'cltbld', 05:18:10 INFO - 'MAIL': '/var/mail/cltbld', 05:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:10 INFO - 'MOZ_NO_REMOTE': '1', 05:18:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:18:10 INFO - 'NO_EM_RESTART': '1', 05:18:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:10 INFO - 'PWD': '/builds/slave/test', 05:18:10 INFO - 'SHELL': '/bin/bash', 05:18:10 INFO - 'SHLVL': '1', 05:18:10 INFO - 'TERM': 'linux', 05:18:10 INFO - 'TMOUT': '86400', 05:18:10 INFO - 'USER': 'cltbld', 05:18:10 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:10 INFO - '_': '/tools/buildbot/bin/python'} 05:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:11 INFO - Running setup.py (path:/tmp/pip-dmWHeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:11 INFO - Running setup.py (path:/tmp/pip-6tkhj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:11 INFO - Running setup.py (path:/tmp/pip-I77xTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:11 INFO - Running setup.py (path:/tmp/pip-7oIPqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:11 INFO - Running setup.py (path:/tmp/pip-R3KNzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:11 INFO - Running setup.py (path:/tmp/pip-22UNHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:11 INFO - Running setup.py (path:/tmp/pip-BLluz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:11 INFO - Running setup.py (path:/tmp/pip-xrvY1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:11 INFO - Running setup.py (path:/tmp/pip-RnSkFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:11 INFO - Running setup.py (path:/tmp/pip-veW2Ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:11 INFO - Running setup.py (path:/tmp/pip-lCkrLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:11 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)) 05:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:11 INFO - Running setup.py (path:/tmp/pip-aTU7Qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:12 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)) 05:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:12 INFO - Running setup.py (path:/tmp/pip-B7pfuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:12 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)) 05:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:12 INFO - Running setup.py (path:/tmp/pip-nVMEYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:12 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)) 05:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:12 INFO - Running setup.py (path:/tmp/pip-Yta1QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:12 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)) 05:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:12 INFO - Running setup.py (path:/tmp/pip-mPxU4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:12 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)) 05:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:12 INFO - Running setup.py (path:/tmp/pip-NVn8v8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:12 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)) 05:18:12 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)) 05:18:12 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)) 05:18:12 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)) 05:18:12 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)) 05:18:12 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)) 05:18:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:12 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 05:18:12 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 05:18:12 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 05:18:12 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 05:18:12 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 05:18:12 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 05:18:14 INFO - Downloading blessings-1.6.tar.gz 05:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:18:14 INFO - Installing collected packages: blessings 05:18:14 INFO - Running setup.py install for blessings 05:18:14 INFO - Successfully installed blessings 05:18:14 INFO - Cleaning up... 05:18:14 INFO - Return code: 0 05:18:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:18:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:14 INFO - Reading from file tmpfile_stdout 05:18:14 INFO - Current package versions: 05:18:14 INFO - argparse == 1.2.1 05:18:14 INFO - blessings == 1.6 05:18:14 INFO - blobuploader == 1.2.4 05:18:14 INFO - docopt == 0.6.1 05:18:14 INFO - functools32 == 3.2.3-2 05:18:14 INFO - jsonschema == 2.5.1 05:18:14 INFO - manifestparser == 1.1 05:18:14 INFO - mozInstall == 1.12 05:18:14 INFO - mozcrash == 0.17 05:18:14 INFO - mozdebug == 0.1 05:18:14 INFO - mozdevice == 0.48 05:18:14 INFO - mozfile == 1.2 05:18:14 INFO - mozhttpd == 0.7 05:18:14 INFO - mozinfo == 0.9 05:18:14 INFO - mozleak == 0.1 05:18:14 INFO - mozlog == 3.2 05:18:14 INFO - moznetwork == 0.27 05:18:14 INFO - mozprocess == 0.22 05:18:14 INFO - mozprofile == 0.28 05:18:14 INFO - mozrunner == 6.11 05:18:14 INFO - mozscreenshot == 0.1 05:18:14 INFO - mozsystemmonitor == 0.3 05:18:14 INFO - moztest == 0.7 05:18:14 INFO - mozversion == 1.4 05:18:14 INFO - psutil == 3.1.1 05:18:14 INFO - requests == 1.2.3 05:18:14 INFO - wsgiref == 0.1.2 05:18:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:14 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')]} 05:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:14 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')]} 05:18:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:14 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 0x7fceb4980f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e53350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, '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': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', '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 05:18:14 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 05:18:14 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 05:18:14 INFO - Using env: {'DISPLAY': ':0', 05:18:14 INFO - 'HOME': '/home/cltbld', 05:18:14 INFO - 'LANG': 'en_US.UTF-8', 05:18:14 INFO - 'LANGUAGE': 'en_US:en', 05:18:14 INFO - 'LOGNAME': 'cltbld', 05:18:14 INFO - 'MAIL': '/var/mail/cltbld', 05:18:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:14 INFO - 'MOZ_NO_REMOTE': '1', 05:18:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:18:14 INFO - 'NO_EM_RESTART': '1', 05:18:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:18:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:14 INFO - 'PWD': '/builds/slave/test', 05:18:14 INFO - 'SHELL': '/bin/bash', 05:18:14 INFO - 'SHLVL': '1', 05:18:14 INFO - 'TERM': 'linux', 05:18:14 INFO - 'TMOUT': '86400', 05:18:14 INFO - 'USER': 'cltbld', 05:18:14 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:18:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:14 INFO - '_': '/tools/buildbot/bin/python'} 05:18:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:14 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)) 05:18:14 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)) 05:18:14 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)) 05:18:14 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)) 05:18:14 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)) 05:18:14 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)) 05:18:14 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)) 05:18:14 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)) 05:18:14 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)) 05:18:14 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)) 05:18:14 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)) 05:18:14 INFO - Cleaning up... 05:18:15 INFO - Return code: 0 05:18:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:18:15 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')]} 05:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:15 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')]} 05:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:15 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 0x7fceb4980f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e53350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, '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': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', '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 05:18:15 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 05:18:15 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 05:18:15 INFO - Using env: {'DISPLAY': ':0', 05:18:15 INFO - 'HOME': '/home/cltbld', 05:18:15 INFO - 'LANG': 'en_US.UTF-8', 05:18:15 INFO - 'LANGUAGE': 'en_US:en', 05:18:15 INFO - 'LOGNAME': 'cltbld', 05:18:15 INFO - 'MAIL': '/var/mail/cltbld', 05:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:15 INFO - 'MOZ_NO_REMOTE': '1', 05:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:18:15 INFO - 'NO_EM_RESTART': '1', 05:18:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:15 INFO - 'PWD': '/builds/slave/test', 05:18:15 INFO - 'SHELL': '/bin/bash', 05:18:15 INFO - 'SHLVL': '1', 05:18:15 INFO - 'TERM': 'linux', 05:18:15 INFO - 'TMOUT': '86400', 05:18:15 INFO - 'USER': 'cltbld', 05:18:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:15 INFO - '_': '/tools/buildbot/bin/python'} 05:18:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:18:15 INFO - Cleaning up... 05:18:15 INFO - Return code: 0 05:18:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:18:15 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')]} 05:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:15 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')]} 05:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:15 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 0x7fceb4980f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e53350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, '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': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', '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 05:18:15 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 05:18:15 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 05:18:15 INFO - Using env: {'DISPLAY': ':0', 05:18:15 INFO - 'HOME': '/home/cltbld', 05:18:15 INFO - 'LANG': 'en_US.UTF-8', 05:18:15 INFO - 'LANGUAGE': 'en_US:en', 05:18:15 INFO - 'LOGNAME': 'cltbld', 05:18:15 INFO - 'MAIL': '/var/mail/cltbld', 05:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:15 INFO - 'MOZ_NO_REMOTE': '1', 05:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:18:15 INFO - 'NO_EM_RESTART': '1', 05:18:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:15 INFO - 'PWD': '/builds/slave/test', 05:18:15 INFO - 'SHELL': '/bin/bash', 05:18:15 INFO - 'SHLVL': '1', 05:18:15 INFO - 'TERM': 'linux', 05:18:15 INFO - 'TMOUT': '86400', 05:18:15 INFO - 'USER': 'cltbld', 05:18:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:15 INFO - '_': '/tools/buildbot/bin/python'} 05:18:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:15 INFO - Downloading/unpacking flake8 05:18:15 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 05:18:15 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 05:18:15 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 05:18:15 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 05:18:15 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 05:18:15 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 05:18:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:18:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:18:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:18:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:18 INFO - no previously-included directories found matching 'docs/_build' 05:18:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:18:18 INFO - Running setup.py install for pep8 05:18:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:18 INFO - no previously-included directories found matching 'docs/_build' 05:18:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:18:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:18:18 INFO - Cleaning up... 05:18:18 INFO - Return code: 0 05:18:18 INFO - Running post-action listener: _resource_record_post_action 05:18:18 INFO - Running post-action listener: _start_resource_monitoring 05:18:18 INFO - Starting resource monitoring. 05:18:18 INFO - ##### 05:18:18 INFO - ##### Finished create-virtualenv step (success) 05:18:18 INFO - ##### 05:18:18 INFO - ##### 05:18:18 INFO - ##### Running install step. 05:18:18 INFO - ##### 05:18:18 INFO - Running pre-action listener: _resource_record_pre_action 05:18:18 INFO - Running main action method: install 05:18:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:18 INFO - Reading from file tmpfile_stdout 05:18:18 INFO - Detecting whether we're running mozinstall >=1.0... 05:18:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:18:18 INFO - Reading from file tmpfile_stdout 05:18:18 INFO - Output received: 05:18:18 INFO - Usage: mozinstall [options] installer 05:18:18 INFO - Options: 05:18:18 INFO - -h, --help show this help message and exit 05:18:18 INFO - -d DEST, --destination=DEST 05:18:18 INFO - Directory to install application into. [default: 05:18:18 INFO - "/builds/slave/test"] 05:18:18 INFO - --app=APP Application being installed. [default: firefox] 05:18:18 INFO - mkdir: /builds/slave/test/build/application 05:18:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:18:34 INFO - Reading from file tmpfile_stdout 05:18:34 INFO - Output received: 05:18:34 INFO - /builds/slave/test/build/application/firefox/firefox 05:18:34 INFO - Running post-action listener: _resource_record_post_action 05:18:34 INFO - ##### 05:18:34 INFO - ##### Finished install step (success) 05:18:34 INFO - ##### 05:18:34 INFO - ##### 05:18:34 INFO - ##### Running run-tests step. 05:18:34 INFO - ##### 05:18:34 INFO - Running pre-action listener: _resource_record_pre_action 05:18:34 INFO - Running main action method: run_tests 05:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:18:34 INFO - Python 2.7.3 05:18:34 INFO - Return code: 0 05:18:34 INFO - grabbing minidump binary from tooltool 05:18:34 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')]} 05:18:34 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 0x1ee21c0>, '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 0x1ee30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee3550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:18:34 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 05:18:34 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 05:18:34 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 05:18:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:18:34 INFO - Return code: 0 05:18:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:18:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:18:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:18:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:18:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:18:35 INFO - Return code: 0 05:18:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:18:35 INFO - Using env: {'DISPLAY': ':0', 05:18:35 INFO - 'HOME': '/home/cltbld', 05:18:35 INFO - 'LANG': 'en_US.UTF-8', 05:18:35 INFO - 'LANGUAGE': 'en_US:en', 05:18:35 INFO - 'LOGNAME': 'cltbld', 05:18:35 INFO - 'MAIL': '/var/mail/cltbld', 05:18:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:18:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:35 INFO - 'MOZ_NO_REMOTE': '1', 05:18:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:18:35 INFO - 'NO_EM_RESTART': '1', 05:18:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:35 INFO - 'PWD': '/builds/slave/test', 05:18:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:18:35 INFO - 'SHELL': '/bin/bash', 05:18:35 INFO - 'SHLVL': '1', 05:18:35 INFO - 'TERM': 'linux', 05:18:35 INFO - 'TMOUT': '86400', 05:18:35 INFO - 'USER': 'cltbld', 05:18:35 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1466770662.273993-947807449', 05:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:35 INFO - '_': '/tools/buildbot/bin/python'} 05:18:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:18:35 INFO - mozversion application_buildid: 20160624023357 05:18:35 INFO - mozversion application_changeset: afcdd85399636b518887cc17aedbda8cc1c1f197 05:18:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:18:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:18:35 INFO - mozversion application_name: Firefox 05:18:35 INFO - mozversion application_remotingname: firefox-dev 05:18:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:18:35 INFO - mozversion application_vendor: Mozilla 05:18:35 INFO - mozversion application_version: 49.0a2 05:18:35 INFO - mozversion platform_buildid: 20160624023357 05:18:35 INFO - mozversion platform_changeset: afcdd85399636b518887cc17aedbda8cc1c1f197 05:18:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:18:35 INFO - mozversion platform_version: 49.0a2 05:18:35 INFO - using testdate: 1466770715 05:18:35 INFO - actual date: 1466770715 05:18:35 INFO - starting webserver on 'localhost:45386' 05:18:35 INFO - SUITE-START | Running 2 tests 05:18:35 INFO - TEST-START | dromaeo_css 05:18:35 INFO - operating with platform_type : linux_ 05:18:35 INFO - Initialising browser for dromaeo_css test... 05:18:35 INFO - TEST-INFO | started process 710 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjslCDr/profile http://localhost:45386/getInfo.html) 05:18:39 INFO - PROCESS | 710 | __metrics Screen width/height:1600/1200 05:18:39 INFO - PROCESS | 710 | colorDepth:24 05:18:39 INFO - PROCESS | 710 | Browser inner width/height: 1024/697 05:18:39 INFO - PROCESS | 710 | __metrics 05:18:41 ERROR - PROCESS | 710 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:18:41 INFO - PROCESS | 710 | console.error: PushService: 05:18:41 INFO - PROCESS | 710 | stateChangeProcessEnqueue: Error transitioning state 05:18:41 INFO - PROCESS | 710 | UnknownError 05:18:41 INFO - TEST-INFO | 710: exit 0 05:18:41 INFO - Browser initialized. 05:18:41 INFO - Running cycle 1/1 for dromaeo_css test... 05:18:41 INFO - TEST-INFO | started process 803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjslCDr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:18:53 INFO - PROCESS | 803 | RSS: Main: 145264640 05:18:53 INFO - PROCESS | 803 | 05:19:44 INFO - PROCESS | 803 | 1466770784940 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] 05:21:52 INFO - PROCESS | 803 | Cycle 1(1): loaded http://localhost:45386/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45386/tests/dromaeo/cssquery-ext.html) 05:21:53 INFO - PROCESS | 803 | RSS: Main: 255598592 05:21:53 INFO - PROCESS | 803 | 05:24:56 INFO - PROCESS | 803 | Cycle 1(1): loaded http://localhost:45386/tests/dromaeo/cssquery-ext.html (next: http://localhost:45386/tests/dromaeo/cssquery-jquery.html) 05:24:57 INFO - PROCESS | 803 | RSS: Main: 233435136 05:24:57 INFO - PROCESS | 803 | 05:27:58 INFO - PROCESS | 803 | Cycle 1(1): loaded http://localhost:45386/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45386/tests/dromaeo/cssquery-mootools.html) 05:27:59 INFO - PROCESS | 803 | RSS: Main: 229728256 05:27:59 INFO - PROCESS | 803 | 05:31:01 INFO - PROCESS | 803 | Cycle 1(1): loaded http://localhost:45386/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45386/tests/dromaeo/cssquery-prototype.html) 05:31:02 INFO - PROCESS | 803 | RSS: Main: 223412224 05:31:02 INFO - PROCESS | 803 | 05:34:04 INFO - PROCESS | 803 | Cycle 1(1): loaded http://localhost:45386/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45386/tests/dromaeo/cssquery-yui.html) 05:34:05 INFO - PROCESS | 803 | RSS: Main: 218968064 05:34:05 INFO - PROCESS | 803 | 05:37:07 INFO - PROCESS | 803 | Cycle 1(1): loaded http://localhost:45386/tests/dromaeo/cssquery-yui.html (next: http://localhost:45386/tests/dromaeo/cssquery-dojo.html) 05:37:07 INFO - PROCESS | 803 | RSS: Main: 257093632 05:37:07 INFO - PROCESS | 803 | 05:37:07 INFO - PROCESS | 803 | __start_tp_report 05:37:07 INFO - PROCESS | 803 | _x_x_mozilla_page_load 05:37:07 INFO - PROCESS | 803 | _x_x_mozilla_page_load_details 05:37:07 INFO - PROCESS | 803 | |i|pagename|runs| 05:37:07 INFO - PROCESS | 803 | |0;dojo.html;3295.7528185386095;3326.085326535201;3338.1821453743823;3339.4823802310652;3376.290978894432;5557.138643510258;5562.415946325638;5583.162525621159;5589.944100558993;5596.300462442195;5465.945340546593;5509.889802203954;5533.059379905416;5537;5539.25220095287;4932.605391568666;4989.9750501247445;5033.773480193382;5044.470330615288;5059.316992206061;5034.09386310464;5116.130257856174;5126;5129.666571672848;5129.820456284031;5008.373953255843;5055.342805435288;5075.441701412841;5094.2103973884105;5104.081265372231;5130.050940576006;5148.768305426265;5158.92261616076;5162.974185129088;5180.507851754096;4930.8520744377365;4954.925676114861;5013.323201367842;5064.3163172971545;5081.923771143436;5413.377461591928;5441.809536666197;5458.2904222450825;5476.151196564539;5513.641613295123;6007.969960150172;6017.097435384683;6021.578489505737;6022.8494287642725;6024.367441418632;5923.644581325134;6019.367966363513;6025.096235564656;6058.454739073461;6061.575689701723;5606.383297837235;5627.380988091307;5647.8588035299035;5667.149927510865;5669.801556945569;5928.0515117581845;6135.171751813448;6143.8326717923455;6151.907721384183;6157.261128664589;5753.1370294456165;5818.389069147764;5830.183774271605;5857.502112320415;5860.912086318709;5855.502282305966;5873.676947767914;5914.615549989237;5926.170336152942;5942.8811423771285;5678.858028549319;5797.333306669787;5822.126680997884;5823.5341172706085;5826.41735826414;5939.346671866091;5967.045272756425;5982.700864956735;5990.311114221833;6002.669853158118;4545.272756358967;4553.4763502197675;4557.7721113944835;4565.132624801344;4582.587567118975;3985.4021896715726;4016.7389119707127;4056.513218413809;4068.4304197412384;4104.15864747726;5671.546276297886;5698.088305871041;5702.144678298288;5720.513756330759;5724.313082430135;6043.7884674036195;6049.78825741097;6057.091436284592;6059.787907423219;6080.391960803884;5494.423085576037;5599.412061733541;5616.719164041863;5639.464250896158;5646.802361917313;4969.751512424365;4976.2038073908725;4978.950210497921;4986.0775756484445;5014;6154.169187159766;6200.224971878515;6205.720742566505;6205.875882482325;6214.347493513116;5786.0453025252045;5907.084401917709;5960.731767070405;5969.522438204846;5972.25346831646;6168.228971378578;6191.690415479298;6192.566520343533;6192.876142477216;6193.938060619336;5654.208410822406;5880.735366908414;5891.381404952418;5892.469677729025;5895.823125306248;5879.235699359056;5967.104934259895;5983.042713165873;6011.729472173675;6015.48868346178;5847.035239185487;5871.6770577618645;5879.588428810113;5880.500157486662;5888.733922206746;5612.074007788669;5717.685527296038;5821.446962538722;5879.147523609138;5886.117082437669;5723.542116630576;5762.740676669476;5781.884362312814;5783.913241301299;5805.41945805433;5837.67892765902;5850.414958504116;5853;5854.707264636836;5897.233359663216;5557.083081291542;5596.636218645775;5650.491455769001;5678.0915053591225;5704.885902282014 05:37:07 INFO - PROCESS | 803 | |1;ext.html;599.598269159663;637.2957881540897;649.5745286837121;657.753342496564;663.986720265595;34577;37813;47014.529854701446;47904;47907;48873;49108.263376049385;49576.752116239455;49660.25509617358;49728.50271497284;8373.874391884126;8997.325200609948;9164.862527062067;9185.816283674323;10782.299150555227;10843.891561084367;10974.067204287605;10993.395363255018;11005.889941100566;11014.06380457658;10988.945055274713;11048.779024419508;11053.392063436508;11055.391953442557;11055.944720276388;8525.829483410296;8717.215450609412;10874.401907895062;10952.835707464394;11155.498002589822;10839.916008399176;10945.4527273636;10950.507227174716;10956.506957186946;10970.067544258747;44821.551784482064;45357;45385.09229815385;45408;45501.72491375418;3737.5141231639986;3751.9249615007548;3786.678132358753;3971.960280397217;4641.582257596799;34781.13047173816;35065;35336.293274134376;37290;37630.43554346687;4630.120277147331;4643.8374656886845;4671.299305104261;4700.2009658358165;4742.9525704742855;389263.99999999715;390774;393759;395350;395468;70919.29080709127;72115.63942180255;72132.63933680298;72603;72711.27288727045;39834.402483962185;40691.38962915559;40717;42531.14937701228;42676.78661606672;19010.52473688169;19250.22999080021;19253.90373048126;19420.902895485713;19421.611567768847;12569.874301257054;14313.78529322061;14730.189839558712;14772.778408323884;15119.395224191128;22694.886525567268;22754.20360287382;22800.42998925007;22801.201957931393;22809.771902280765;7622.923770762332;7815.374770018385;7975.7607271781935;8029.919700803034;8089.838203235902;1023.3246057602096;1081.432248069754;1121.388843080523;1162.4071723421116;1211.5638370186903;33523;34148.48777268343;34809;35326;35582.64417355845;1218.9939050304693;1229.2562999385423;1268.923864568129;1287.5815360008035;1305.9934700326628;17954.5511362217;18558.44324670262;18601.906990464962;18656.34702785396;18659.626807463774;520742;525499;526311;528617;528929;66822.33177668258;67485.6625716878;67492;67532.3246767526;67688.32311676918;1308.4700696218047;1383.508854356709;1409.9647508812361;1430.8410187747957;1434.9713005740036;34016.82991585026;34887.825560872036;35657;35761.821190893876;36070.27859442744;56589.717051416126;57183.42816571947;57202;57214.7139264301;57314;433777;440161;441092;443805;444727;27939.44121117607;30211.244718882204;30568.694313056585;31364.686353136178;31491.84254078715;53298.73350633222;53668.463315366345;54146;54745;55239.44760552343;45304.32043519416;45760.54239457563;46340.536594633624;46834.531654683014;47313.817154571414;44807.551924480336;45896.31155532737;46233.537664624266;46620.90205940809;47005.294920576904;44499.77750111229;45048.54951450443;45490;46045.309320360895;46047;39540.40689389604;39568.40647390349;40394.39408408818;40538.1892362157;40573.79713101416;30111.69888301089;30575.694243058173;30580.388392232475;30636.234094147825;30699.846500767355 05:37:07 INFO - PROCESS | 803 | |2;jquery.html;409.6886366361566;412.23736088236745;419.62443612966393;426.9722468039577;435.52962800175794;19522.511937201558;19580.70628940565;19643.607127857435;20328.288509902155;20418.59162816743;19739.309124180658;20058;20362.89818550909;20930.581388372262;20962.26632067878;5670.064439367509;5924.140999555062;5955.374685657986;5963.463288304052;5964.165016897616;5578.581606379517;5731.1116776899435;5814.24414826072;5913.556483263751;5918.112283157539;5693.487586117249;5778.942210577903;5779.017567013597;5804.448577385142;5831.183634291181;5734.942650573504;5775.768969241246;5800.071988481823;5822.243108395903;5825.621334613236;5072.54347108762;5184.092783762864;5186.740662966837;5193.610479214074;5222.033923724123;23936.401589960216;23961.161359352514;24611.13861014856;24704.876475617686;24791.380215494577;2555.7060937992183;2828.222238884303;2873.339131999631;2879.3521457672064;2946.911592652224;14442.927785361111;14961.177135257556;14989.550313490612;15540.533783986499;15621.297041633154;2016.153215649416;2026.98986505068;2124.1184908263053;2130.88280144592;2183.5742030304095;79248;79672;79775.20224797736;80380.59809700915;81145;10712.410817404962;11081.72295692598;11185.384803835866;11189.936955989167;11242.269252498725;15827.446039388566;15850.44523441674;15883.841161588467;15957.281922313523;15967.68064638724;8619.439736417113;8902.109789021046;8915.108489151162;8967.237784788236;9015.774605634911;8313.916860831436;8410.074891761902;8458.619362128718;8490.660373584988;8493.405461617627;6938.271481494471;6987.930120698829;7042.330978557029;7085.433165346863;7104.964475177694;1990.8009199080266;2035.3385149826365;2039.3576023552534;2045.3148195354424;2068.782777808339;974.3861367338532;1078.5955266774959;1094.4965315954591;1110.5224753356135;1183.650823007215;20043;20140.39578812638;20170.39488815338;20254.392368228975;20420.795792042187;1040.4641609571077;1048.753542917429;1053.2890299048113;1078.1859695929522;1100.4277775556666;10546.841797373045;11679.883201167879;11764.941175294069;11804.350760708243;11882.76234475323;512234;517672;529550;530652;537614;83453.58273208595;84874;85237;85964;86144.56927715447;1079.6275285026654;1095.1293721491434;1098.5331234225423;1107.8449017137607;1112.6828853776635;17773.46679599614;17868.642627147125;17973.460796175612;18302.176402061672;18329.175187116343;38458;39174;39392.80303598464;39890.87301587302;40431;53504.46495534995;53543.19685204803;53743.19385209147;53769.462305376444;53825.192622111485;8992.46045237288;9035;9114.951780545322;9248.491332976751;9333.766655833659;13447.260400678057;13510;13692.65768355799;13694.726105478034;13790.172589644653;11649.242799218024;11882;11981.221220620631;12152.270863748203;12337.383130843602;11826.881731182923;11921.344326061802;12006.09954253417;12169.2698438094;12457.003439724982;12167.391630418266;12239.632811015685;12332.568360107713;12472.5010999559;12483.188592741442;8630.5253211074;8847.51338676379;8883.244924181517;8997.235235005099;9073.500957447404;12042.939785301018;12049;12230.510779568718;12287.385630718249;12431.502739890304 05:37:07 INFO - PROCESS | 803 | |3;mootools.html;1344.206917918428;1471.418789578117;1534.7314220011497;1683.604352977051;1874.5313671582096;9238.260939124872;9616.855747163789;9663.420194788308;9900.95049524753;9902.455364954925;9644.373115747472;9832.705018849429;9837.40975541468;9847.409155450678;9848.606055757778;2532.8227024108364;2564.7948164146915;2566.1659960512816;2566.9743302567017;2572.6912770467475;2539.9111031113916;2569.884355204011;2570.3060173753074;2574.9742502575014;2577.755113264237;2442.7068751749816;2471.1968610201666;2527.3555243412998;2528.05198050731;2531.632913227581;2355.988220058906;2404.711434627847;2411.168146989286;2455.2756936703713;2457.1522824625476;2407.1935901473025;2418.697662792151;2432.987835060831;2464.223769512616;2464.8521088734733;9397.389169704016;9568.904310956941;9638.325317227796;9730.610775568968;9743.756406089833;5740.655560666373;5856.853578660568;5863.296404431453;5867.471927526543;5883.117532370136;3624.1664417183933;3636.1091532574424;3706.36991711407;3708.2398108387715;3710.3135919854913;1216.2763155922212;1231.9199252048588;1232.4146030635466;1241.6150993192139;1244.1974926172659;13945.721085578332;13954;14002.299885005708;14039.368228429643;14043.929780351033;4532.478764942008;4646.25659894422;4684.672072954861;4740.407449068866;4747.216709242936;10746.24776265654;10762.78474430507;10771.461426928623;10783.676489705322;10796.892031079746;5492.752826122834;5494;5494.725263736797;5495.587830912697;5499.367572729107;4603.861884143481;4823.155947709137;4963.420446226316;5169.431362550116;5293.470652934753;4136.110736191733;4137.13120244752;4169.082801783603;4212.431321771522;4239.894002649897;1238.9690257743628;1247.8814512621286;1249.7125661097998;1262.0471543984418;1262.9075849390395;1014.5130337438071;1028.1671845804922;1042.598599539183;1051.574112484445;1060.4432672846663;6395.584287021329;6435.22777266731;6454.128692626529;6482.319356467503;6633.436157926631;455.13524303823;460.41297345884266;464.6631192385561;475.94050743657044;478.1966296621709;4314.439122914064;4341.9131617367475;4370.060437006059;4381.386605875116;4399.780010999437;12300.938495307466;12686.175398599062;12765.808512872314;12768.93615531935;12805.871941280468;11797.35114568707;12439.626811195678;12579;12600.306983116017;12601.621951341474;9065.909340906506;9244.583993720298;9284.164425201629;9305.11601397866;9306.86039709405;6486.870262594627;6671.733130674718;6713.899291510534;6733.93266067333;6759.695813688298;11272.549098035986;11530.308181509135;11652.592159274724;11732.589359372381;11749.353785541878;11372.20394572372;11770.705732356759;11782;11925.224860384047;11929.403529823649;8608.741737747878;8643.740687779376;8724.083971182934;8725.52009639476;8734.126587341216;9364.484953327632;9466.053394660754;9483.099105584959;9594.856077158709;9602.759931001781;8291.173975951751;8403.915960840557;8432.70485533028;8452.070272270168;8582.871256931276;8025.638846252049;8123.75628731139;8261;8316.126806685454;8481.321494280566;7046.471514636525;7608.087029556267;7893.723719669784;7899.68401263943;7905.93269908578;5414.3232095988005;5443.374011988672;5461.371942226695;5506.284183056177;5550.500454959073;5288.536008914168;5295.973520132528;5327.493888080626;5542.5565954722715;5553.16702494629 05:37:07 INFO - PROCESS | 803 | |4;prototype.html;333.40775470715204;338.2119661189428;349.6730556929271;349.9177693242088;350.08627482271254;5323.361196656407;5422.213779002042;5428.348598168221;5516.200150978106;5569.637973531722;5424.945750542493;5425.504268036698;5465.480779325958;5491.368492623349;5497.278846970745;4265;4268.1890440816305;4270.530241673413;4277.443932288798;4285.35719642053;4076.9196163016823;4166.33338665812;4310.97844510777;4315.244832154376;4332.870013899588;4252.107057517925;4257.191133684605;4264.957350426487;4270.167317373113;4276.123394704094;4286.935695964563;4305.913881722379;4318.309070548728;4327.913441731163;4328.9783551082355;3574.070741607175;3753.343164946151;3758.530183727034;3766.2090960898245;3847;5289.418164001957;5311.097113490716;5339.305890234284;5343.706096164709;5352.892942141136;4666.23007203811;4800.807967681323;4869.391326084239;4873.293372460973;4903.387076615423;5711.944109901225;5738.598298119134;5765;5767.279090113736;5773.653580785124;4083.9183216335805;4091.3044782387065;4091.3249313863175;4092.856750013735;4116.773577453238;5699.515541179005;5876.55925805562;5894.115882617599;5927.4665280125;5936.97031514844;4121.072758629284;4181.749095054306;4184.393262976912;4191.140816132685;4196.181744559782;4192.895177620584;4197.580241975778;4209.87370378883;4228.577142285808;4229.703920725581;4736.750043729201;4802.567768900815;4813.518648135228;4846.127697014571;4858.58702010333;5453.863653408696;5624.690642014729;5728.398518155534;5738.770449181986;5795.507381872588;1435.6626192844674;1443.2927865345905;1446.681730019394;1449.3695242569515;1457.8979471436899;910.9180173784258;916.8028873792167;932.8973812880578;941.2187884056353;944.527736131934;4767.141914455362;4937.506249375034;4939.827106051271;4952.925706114411;4957.876053098701;5186.4035635902355;5213.661112027782;5269.341332333458;5275.419703832576;5293.206019097166;4616.815327386934;4947.777350019258;4969.304297398367;4969.726665033458;5054.469280725544;2759.806813523034;2772.944541109207;2775.777937764974;2805.424887897976;2834.8157369770897;5272.55183309423;5331.787308267026;5341.465853414627;5344.198370244417;5345.492178243061;4966.975165124223;5201.249406927052;5296.894062118736;5320.031588312349;5345.685674881598;5430.945690543202;5450.264214331015;5453.836384908459;5490.972545137329;5494;5196.896062078813;5231.921521177262;5251.606129540376;5255.185446255837;5278.4721527846905;4972.577330926912;5178.792848286026;5202.401723801811;5207.895842083213;5225.503577160163;5380.977614253279;5401.351837779492;5424.5660347172925;5456.236126942311;5510.622344413897;3835.5972622874197;3837.7335479292324;3845.596502186134;3860.343741563997;3869.245497128033;4911.336969509213;5198.4801519849225;5201.245819356248;5620.747066382104;5734;5688.573356998324;5709.743061562157;5713.771449141987;5734.684592347294;5748.166515855094;5712.171735098471;5717.285339332583;5717.42825717425;5768.711564421678;5775.566832487496;5511.338639363302;5520.696361699985;5646.802361917313;5653.745581448927;5660.971695141663;5642.589352661835;5662.603617746883;5672.404397538199;5684.118961560965;5694.943050569441;5403.487023633389;5631.436856314336;5638.519051617275;5652.943470565242;5667.1782591524825 05:37:07 INFO - PROCESS | 803 | |5;yui.html;736.911570611527;770.5915864591765;870.2689740617882;884.4251236696143;911.4166933162777;15069.397224111022;15986.92006539967;15994.120323382209;16012;16035.759463608027;14272.357743901523;15497.225138743073;15501.767473487907;15512.84487155128;15523.146226957513;719.8524302517998;844.5819319436887;870.1081391573656;883.9999999999984;887.9600417981206;886.521278509604;886.6586364249778;887.2591386192537;888.1518150166607;889.3907673243816;921.6866265469772;924.865894445305;925.2228128372166;925.6898938855492;926.2775035472342;761.8400135971452;781.6170076662423;781.7146741439391;911.239115338693;918.3846822628855;682.3381320119477;682.8975653651992;682.9965850170767;683.0948992584796;684.5310961991051;15486.148261845594;15528.767068493982;15530.223488825513;15557.611059723598;15580.143092129929;15323.540293791204;15488.690226195413;15709.842901571066;15807.446739364066;15910.76133857993;13234.933825330909;13483.66290842727;13546.661333466644;13595.252261125634;13654.658633534142;4653.208954477747;4709.364235828154;4709.599684026862;4718.339432479455;4752.7861246244;79863.20136798674;80267.5986620069;80378.59810700968;80489.59755201245;81371;12039.944074315497;12247.448864801105;12374.690632734073;12531.31077790712;12619.116661833767;20785.1685932561;21394.465138371354;21439.03524341377;23573.29280121599;23585.764142358355;8603.354748393895;8637.093105223987;8955;9125.45247285165;9230.16928476427;4893.339399181065;4903.387076615423;4917;4949.282354058713;4969;3378.3412234614507;3436.0722604897037;3465.2549701814232;3476.808775517371;3486.9825650871585;618.0235228339213;622.7322251431838;629.6002038705363;635.9650219237893;636.1634450697411;715.7172916697875;764.6635480388611;770.4144849914105;777.6422845491024;778.190681691035;10111.797764044677;10207.438590877424;10207.9489602553;10268.691939241835;10290.228232882564;623.2023010546508;723.7901008707416;764;771.0939645916026;775.6083177995076;5105.006141586051;5143.074246635567;5192.1173400522;5212.348456442945;5217.3999990000875;69765.65117174381;72322;72341.27658723346;72349.6382518084;94020;66204;85785.57107214424;89260.5536972311;100411;100596.49701751591;81805.59097204595;89306.55346723225;89713;90535;92360;9285.35002549815;9333.67332143399;9482.762930926781;9518.666846660333;9565.521723913636;48364;48559.78600535015;53558.464415355345;54046.189307161214;56991.430085698616;56799;57267;64589;71437.64281178561;78341;14006.229657368942;14232.217228052557;14321.498747544203;14443.205623690797;14485.7827132591;15723.21383930776;15859.841401585836;16082;16324.020558766511;16831.24259408305;9553.904460955302;13745.312734363442;14101.576952691436;14984.250787460802;15690.450834220748;14720.263986800832;14967.550973470814;14974.44081490189;15072.095674259579;15241.237938103272;14338.283085845875;14490.565283041526;14852.405903763729;14885.106893586419;15083.622909426913;11101.611443599435;11107.888921110685;11177.608783692533;11458.255213411101;11589.24669896454;12029.939850301043;12227.388630568614;12525.436355363849;12646.494140234656;12702.30137342455 05:37:07 INFO - PROCESS | 803 | __end_tp_report 05:37:07 INFO - PROCESS | 803 | __start_cc_report 05:37:07 INFO - PROCESS | 803 | _x_x_mozilla_cycle_collect,926 05:37:07 INFO - PROCESS | 803 | __end_cc_report 05:37:07 INFO - PROCESS | 803 | __startTimestamp1466771827925__endTimestamp 05:37:07 INFO - PROCESS | 803 | 05:37:07 INFO - PROCESS | 803 | ------- Summary: start ------- 05:37:07 INFO - PROCESS | 803 | Number of tests: 6 05:37:07 INFO - PROCESS | 803 | 05:37:07 INFO - PROCESS | 803 | [#0] dojo.html Cycles:165 Average:5530.70 Median:5720.51 stddev:622.37 (10.9%) stddev-sans-first:599.08 05:37:07 INFO - PROCESS | 803 | Values: 3295.8 3326.1 3338.2 3339.5 3376.3 5557.1 5562.4 5583.2 5589.9 5596.3 5465.9 5509.9 5533.1 5537.0 5539.3 4932.6 4990.0 5033.8 5044.5 5059.3 5034.1 5116.1 5126.0 5129.7 5129.8 5008.4 5055.3 5075.4 5094.2 5104.1 5130.1 5148.8 5158.9 5163.0 5180.5 4930.9 4954.9 5013.3 5064.3 5081.9 5413.4 5441.8 5458.3 5476.2 5513.6 6008.0 6017.1 6021.6 6022.8 6024.4 5923.6 6019.4 6025.1 6058.5 6061.6 5606.4 5627.4 5647.9 5667.1 5669.8 5928.1 6135.2 6143.8 6151.9 6157.3 5753.1 5818.4 5830.2 5857.5 5860.9 5855.5 5873.7 5914.6 5926.2 5942.9 5678.9 5797.3 5822.1 5823.5 5826.4 5939.3 5967.0 5982.7 5990.3 6002.7 4545.3 4553.5 4557.8 4565.1 4582.6 3985.4 4016.7 4056.5 4068.4 4104.2 5671.5 5698.1 5702.1 5720.5 5724.3 6043.8 6049.8 6057.1 6059.8 6080.4 5494.4 5599.4 5616.7 5639.5 5646.8 4969.8 4976.2 4979.0 4986.1 5014.0 6154.2 6200.2 6205.7 6205.9 6214.3 5786.0 5907.1 5960.7 5969.5 5972.3 6168.2 6191.7 6192.6 6192.9 6193.9 5654.2 5880.7 5891.4 5892.5 5895.8 5879.2 5967.1 5983.0 6011.7 6015.5 5847.0 5871.7 5879.6 5880.5 5888.7 5612.1 5717.7 5821.4 5879.1 5886.1 5723.5 5762.7 5781.9 5783.9 5805.4 5837.7 5850.4 5853.0 5854.7 5897.2 5557.1 5596.6 5650.5 5678.1 5704.9 05:37:07 INFO - PROCESS | 803 | 05:37:07 INFO - PROCESS | 803 | [#1] ext.html Cycles:180 Average:63399.30 Median:21770.18 stddev:120555.68 (553.8%) stddev-sans-first:120801.66 05:37:07 INFO - PROCESS | 803 | Values: 599.6 637.3 649.6 657.8 664.0 34577.0 37813.0 47014.5 47904.0 47907.0 48873.0 49108.3 49576.8 49660.3 49728.5 8373.9 8997.3 9164.9 9185.8 10782.3 10843.9 10974.1 10993.4 11005.9 11014.1 10988.9 11048.8 11053.4 11055.4 11055.9 8525.8 8717.2 10874.4 10952.8 11155.5 10839.9 10945.5 10950.5 10956.5 10970.1 44821.6 45357.0 45385.1 45408.0 45501.7 3737.5 3751.9 3786.7 3972.0 4641.6 34781.1 35065.0 35336.3 37290.0 37630.4 4630.1 4643.8 4671.3 4700.2 4743.0 389264.0 390774.0 393759.0 395350.0 395468.0 70919.3 72115.6 72132.6 72603.0 72711.3 39834.4 40691.4 40717.0 42531.1 42676.8 19010.5 19250.2 19253.9 19420.9 19421.6 12569.9 14313.8 14730.2 14772.8 15119.4 22694.9 22754.2 22800.4 22801.2 22809.8 7622.9 7815.4 7975.8 8029.9 8089.8 1023.3 1081.4 1121.4 1162.4 1211.6 33523.0 34148.5 34809.0 35326.0 35582.6 1219.0 1229.3 1268.9 1287.6 1306.0 17954.6 18558.4 18601.9 18656.3 18659.6 520742.0 525499.0 526311.0 528617.0 528929.0 66822.3 67485.7 67492.0 67532.3 67688.3 1308.5 1383.5 1410.0 1430.8 1435.0 34016.8 34887.8 35657.0 35761.8 36070.3 56589.7 57183.4 57202.0 57214.7 57314.0 433777.0 440161.0 441092.0 443805.0 444727.0 27939.4 30211.2 30568.7 31364.7 31491.8 53298.7 53668.5 54146.0 54745.0 55239.4 45304.3 45760.5 46340.5 46834.5 47313.8 44807.6 45896.3 46233.5 46620.9 47005.3 44499.8 45048.5 45490.0 46045.3 46047.0 39540.4 39568.4 40394.4 40538.2 40573.8 30111.7 30575.7 30580.4 30636.2 30699.8 05:37:07 INFO - PROCESS | 803 | 05:37:07 INFO - PROCESS | 803 | [#2] jquery.html Cycles:180 Average:30115.04 Median:24286.15 stddev:86187.17 (354.9%) stddev-sans-first:86400.09 05:37:07 INFO - PROCESS | 803 | Values: 409.7 412.2 419.6 427.0 435.5 19522.5 19580.7 19643.6 20328.3 20418.6 19739.3 20058.0 20362.9 20930.6 20962.3 5670.1 5924.1 5955.4 5963.5 5964.2 5578.6 5731.1 5814.2 5913.6 5918.1 5693.5 5778.9 5779.0 5804.4 5831.2 5734.9 5775.8 5800.1 5822.2 5825.6 5072.5 5184.1 5186.7 5193.6 5222.0 23936.4 23961.2 24611.1 24704.9 24791.4 2555.7 2828.2 2873.3 2879.4 2946.9 14442.9 14961.2 14989.6 15540.5 15621.3 2016.2 2027.0 2124.1 2130.9 2183.6 79248.0 79672.0 79775.2 80380.6 81145.0 10712.4 11081.7 11185.4 11189.9 11242.3 15827.4 15850.4 15883.8 15957.3 15967.7 8619.4 8902.1 8915.1 8967.2 9015.8 8313.9 8410.1 8458.6 8490.7 8493.4 6938.3 6987.9 7042.3 7085.4 7105.0 1990.8 2035.3 2039.4 2045.3 2068.8 974.4 1078.6 1094.5 1110.5 1183.7 20043.0 20140.4 20170.4 20254.4 20420.8 1040.5 1048.8 1053.3 1078.2 1100.4 10546.8 11679.9 11764.9 11804.4 11882.8 512234.0 517672.0 529550.0 530652.0 537614.0 83453.6 84874.0 85237.0 85964.0 86144.6 1079.6 1095.1 1098.5 1107.8 1112.7 17773.5 17868.6 17973.5 18302.2 18329.2 38458.0 39174.0 39392.8 39890.9 40431.0 53504.5 53543.2 53743.2 53769.5 53825.2 8992.5 9035.0 9115.0 9248.5 9333.8 13447.3 13510.0 13692.7 13694.7 13790.2 11649.2 11882.0 11981.2 12152.3 12337.4 11826.9 11921.3 12006.1 12169.3 12457.0 12167.4 12239.6 12332.6 12472.5 12483.2 8630.5 8847.5 8883.2 8997.2 9073.5 12042.9 12049.0 12230.5 12287.4 12431.5 05:37:07 INFO - PROCESS | 803 | 05:37:07 INFO - PROCESS | 803 | [#3] mootools.html Cycles:180 Average:6370.04 Median:2493.81 stddev:3789.44 (152.0%) stddev-sans-first:3781.25 05:37:07 INFO - PROCESS | 803 | Values: 1344.2 1471.4 1534.7 1683.6 1874.5 9238.3 9616.9 9663.4 9901.0 9902.5 9644.4 9832.7 9837.4 9847.4 9848.6 2532.8 2564.8 2566.2 2567.0 2572.7 2539.9 2569.9 2570.3 2575.0 2577.8 2442.7 2471.2 2527.4 2528.1 2531.6 2356.0 2404.7 2411.2 2455.3 2457.2 2407.2 2418.7 2433.0 2464.2 2464.9 9397.4 9568.9 9638.3 9730.6 9743.8 5740.7 5856.9 5863.3 5867.5 5883.1 3624.2 3636.1 3706.4 3708.2 3710.3 1216.3 1231.9 1232.4 1241.6 1244.2 13945.7 13954.0 14002.3 14039.4 14043.9 4532.5 4646.3 4684.7 4740.4 4747.2 10746.2 10762.8 10771.5 10783.7 10796.9 5492.8 5494.0 5494.7 5495.6 5499.4 4603.9 4823.2 4963.4 5169.4 5293.5 4136.1 4137.1 4169.1 4212.4 4239.9 1239.0 1247.9 1249.7 1262.0 1262.9 1014.5 1028.2 1042.6 1051.6 1060.4 6395.6 6435.2 6454.1 6482.3 6633.4 455.1 460.4 464.7 475.9 478.2 4314.4 4341.9 4370.1 4381.4 4399.8 12300.9 12686.2 12765.8 12768.9 12805.9 11797.4 12439.6 12579.0 12600.3 12601.6 9065.9 9244.6 9284.2 9305.1 9306.9 6486.9 6671.7 6713.9 6733.9 6759.7 11272.5 11530.3 11652.6 11732.6 11749.4 11372.2 11770.7 11782.0 11925.2 11929.4 8608.7 8643.7 8724.1 8725.5 8734.1 9364.5 9466.1 9483.1 9594.9 9602.8 8291.2 8403.9 8432.7 8452.1 8582.9 8025.6 8123.8 8261.0 8316.1 8481.3 7046.5 7608.1 7893.7 7899.7 7905.9 5414.3 5443.4 5461.4 5506.3 5550.5 5288.5 5296.0 5327.5 5542.6 5553.2 05:37:07 INFO - PROCESS | 803 | 05:37:07 INFO - PROCESS | 803 | [#4] prototype.html Cycles:180 Average:4622.64 Median:5210.78 stddev:1331.08 (25.5%) stddev-sans-first:1295.30 05:37:07 INFO - PROCESS | 803 | Values: 333.4 338.2 349.7 349.9 350.1 5323.4 5422.2 5428.3 5516.2 5569.6 5424.9 5425.5 5465.5 5491.4 5497.3 4265.0 4268.2 4270.5 4277.4 4285.4 4076.9 4166.3 4311.0 4315.2 4332.9 4252.1 4257.2 4265.0 4270.2 4276.1 4286.9 4305.9 4318.3 4327.9 4329.0 3574.1 3753.3 3758.5 3766.2 3847.0 5289.4 5311.1 5339.3 5343.7 5352.9 4666.2 4800.8 4869.4 4873.3 4903.4 5711.9 5738.6 5765.0 5767.3 5773.7 4083.9 4091.3 4091.3 4092.9 4116.8 5699.5 5876.6 5894.1 5927.5 5937.0 4121.1 4181.7 4184.4 4191.1 4196.2 4192.9 4197.6 4209.9 4228.6 4229.7 4736.8 4802.6 4813.5 4846.1 4858.6 5453.9 5624.7 5728.4 5738.8 5795.5 1435.7 1443.3 1446.7 1449.4 1457.9 910.9 916.8 932.9 941.2 944.5 4767.1 4937.5 4939.8 4952.9 4957.9 5186.4 5213.7 5269.3 5275.4 5293.2 4616.8 4947.8 4969.3 4969.7 5054.5 2759.8 2772.9 2775.8 2805.4 2834.8 5272.6 5331.8 5341.5 5344.2 5345.5 4967.0 5201.2 5296.9 5320.0 5345.7 5430.9 5450.3 5453.8 5491.0 5494.0 5196.9 5231.9 5251.6 5255.2 5278.5 4972.6 5178.8 5202.4 5207.9 5225.5 5381.0 5401.4 5424.6 5456.2 5510.6 3835.6 3837.7 3845.6 3860.3 3869.2 4911.3 5198.5 5201.2 5620.7 5734.0 5688.6 5709.7 5713.8 5734.7 5748.2 5712.2 5717.3 5717.4 5768.7 5775.6 5511.3 5520.7 5646.8 5653.7 5661.0 5642.6 5662.6 5672.4 5684.1 5694.9 5403.5 5631.4 5638.5 5652.9 5667.2 05:37:07 INFO - PROCESS | 803 | 05:37:07 INFO - PROCESS | 803 | [#5] yui.html Cycles:180 Average:19821.34 Median:4933.14 stddev:26296.42 (533.1%) stddev-sans-first:26331.15 05:37:07 INFO - PROCESS | 803 | Values: 736.9 770.6 870.3 884.4 911.4 15069.4 15986.9 15994.1 16012.0 16035.8 14272.4 15497.2 15501.8 15512.8 15523.1 719.9 844.6 870.1 884.0 888.0 886.5 886.7 887.3 888.2 889.4 921.7 924.9 925.2 925.7 926.3 761.8 781.6 781.7 911.2 918.4 682.3 682.9 683.0 683.1 684.5 15486.1 15528.8 15530.2 15557.6 15580.1 15323.5 15488.7 15709.8 15807.4 15910.8 13234.9 13483.7 13546.7 13595.3 13654.7 4653.2 4709.4 4709.6 4718.3 4752.8 79863.2 80267.6 80378.6 80489.6 81371.0 12039.9 12247.4 12374.7 12531.3 12619.1 20785.2 21394.5 21439.0 23573.3 23585.8 8603.4 8637.1 8955.0 9125.5 9230.2 4893.3 4903.4 4917.0 4949.3 4969.0 3378.3 3436.1 3465.3 3476.8 3487.0 618.0 622.7 629.6 636.0 636.2 715.7 764.7 770.4 777.6 778.2 10111.8 10207.4 10207.9 10268.7 10290.2 623.2 723.8 764.0 771.1 775.6 5105.0 5143.1 5192.1 5212.3 5217.4 69765.7 72322.0 72341.3 72349.6 94020.0 66204.0 85785.6 89260.6 100411.0 100596.5 81805.6 89306.6 89713.0 90535.0 92360.0 9285.4 9333.7 9482.8 9518.7 9565.5 48364.0 48559.8 53558.5 54046.2 56991.4 56799.0 57267.0 64589.0 71437.6 78341.0 14006.2 14232.2 14321.5 14443.2 14485.8 15723.2 15859.8 16082.0 16324.0 16831.2 9553.9 13745.3 14101.6 14984.3 15690.5 14720.3 14967.6 14974.4 15072.1 15241.2 14338.3 14490.6 14852.4 14885.1 15083.6 11101.6 11107.9 11177.6 11458.3 11589.2 12029.9 12227.4 12525.4 12646.5 12702.3 05:37:07 INFO - PROCESS | 803 | -------- Summary: end -------- 05:37:07 INFO - PROCESS | 803 | 05:37:08 INFO - TEST-INFO | 803: exit 0 05:37:08 INFO - TEST-OK | dromaeo_css | took 1112969ms 05:37:08 INFO - TEST-START | kraken 05:37:08 INFO - operating with platform_type : linux_ 05:37:08 INFO - Initialising browser for kraken test... 05:37:08 INFO - TEST-INFO | started process 996 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUfX2Wr/profile http://localhost:45386/getInfo.html) 05:37:12 INFO - PROCESS | 996 | __metrics Screen width/height:1600/1200 05:37:12 INFO - PROCESS | 996 | colorDepth:24 05:37:12 INFO - PROCESS | 996 | Browser inner width/height: 1024/697 05:37:12 INFO - PROCESS | 996 | __metrics 05:37:13 ERROR - PROCESS | 996 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:37:13 INFO - PROCESS | 996 | console.error: PushService: 05:37:13 INFO - PROCESS | 996 | stateChangeProcessEnqueue: Error transitioning state 05:37:13 INFO - PROCESS | 996 | UnknownError 05:37:14 INFO - TEST-INFO | 996: exit 0 05:37:14 INFO - Browser initialized. 05:37:14 INFO - Running cycle 1/1 for kraken test... 05:37:14 INFO - TEST-INFO | started process 1098 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUfX2Wr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:26 INFO - PROCESS | 1098 | RSS: Main: 148815872 05:37:26 INFO - PROCESS | 1098 | 05:37:28 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45386/tests/kraken/driver.html?testName=audio-beat-detection) 05:37:29 INFO - PROCESS | 1098 | RSS: Main: 198225920 05:37:29 INFO - PROCESS | 1098 | 05:37:32 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45386/tests/kraken/driver.html?testName=audio-dft) 05:37:32 INFO - PROCESS | 1098 | RSS: Main: 195465216 05:37:32 INFO - PROCESS | 1098 | 05:37:35 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45386/tests/kraken/driver.html?testName=audio-fft) 05:37:36 INFO - PROCESS | 1098 | RSS: Main: 282705920 05:37:36 INFO - PROCESS | 1098 | 05:37:38 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45386/tests/kraken/driver.html?testName=audio-oscillator) 05:37:39 INFO - PROCESS | 1098 | RSS: Main: 191406080 05:37:39 INFO - PROCESS | 1098 | 05:37:41 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45386/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:37:41 INFO - PROCESS | 1098 | RSS: Main: 192065536 05:37:41 INFO - PROCESS | 1098 | 05:37:44 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45386/tests/kraken/driver.html?testName=imaging-darkroom) 05:37:45 INFO - PROCESS | 1098 | RSS: Main: 226725888 05:37:45 INFO - PROCESS | 1098 | 05:37:48 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45386/tests/kraken/driver.html?testName=imaging-desaturate) 05:37:49 INFO - PROCESS | 1098 | RSS: Main: 226062336 05:37:49 INFO - PROCESS | 1098 | 05:37:52 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45386/tests/kraken/driver.html?testName=json-parse-financial) 05:37:52 INFO - PROCESS | 1098 | RSS: Main: 226050048 05:37:52 INFO - PROCESS | 1098 | 05:37:54 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45386/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:37:54 INFO - PROCESS | 1098 | RSS: Main: 204824576 05:37:54 INFO - PROCESS | 1098 | 05:37:56 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45386/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:37:57 INFO - PROCESS | 1098 | RSS: Main: 204754944 05:37:57 INFO - PROCESS | 1098 | 05:37:59 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45386/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:37:59 INFO - PROCESS | 1098 | RSS: Main: 210452480 05:37:59 INFO - PROCESS | 1098 | 05:38:02 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45386/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:38:02 INFO - PROCESS | 1098 | RSS: Main: 214122496 05:38:02 INFO - PROCESS | 1098 | 05:38:05 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45386/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:38:06 INFO - PROCESS | 1098 | RSS: Main: 213938176 05:38:06 INFO - PROCESS | 1098 | 05:38:07 INFO - PROCESS | 1098 | Cycle 1(1): loaded http://localhost:45386/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45386/tests/kraken/driver.html?testName=ai-astar) 05:38:08 INFO - PROCESS | 1098 | RSS: Main: 256430080 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | __start_tp_report 05:38:08 INFO - PROCESS | 1098 | _x_x_mozilla_page_load 05:38:08 INFO - PROCESS | 1098 | _x_x_mozilla_page_load_details 05:38:08 INFO - PROCESS | 1098 | |i|pagename|runs| 05:38:08 INFO - PROCESS | 1098 | |0;ai-astar;114;106;109;111;112;110;111;113;110;110 05:38:08 INFO - PROCESS | 1098 | |1;audio-beat-detection;153;123;133;149;128;155;153;127;197;128 05:38:08 INFO - PROCESS | 1098 | |2;audio-dft;162;162;161;163;161;161;160;161;163;160 05:38:08 INFO - PROCESS | 1098 | |3;audio-fft;77;74;78;111;76;76;76;75;100;75 05:38:08 INFO - PROCESS | 1098 | |4;audio-oscillator;85;86;114;85;86;84;127;85;85;133 05:38:08 INFO - PROCESS | 1098 | |5;imaging-gaussian-blur;117;117;116;118;118;117;117;117;117;117 05:38:08 INFO - PROCESS | 1098 | |6;imaging-darkroom;146;146;146;147;147;146;147;146;146;146 05:38:08 INFO - PROCESS | 1098 | |7;imaging-desaturate;94;96;95;95;95;94;95;94;94;94 05:38:08 INFO - PROCESS | 1098 | |8;json-parse-financial;52;50;50;52;52;50;53;52;51;51 05:38:08 INFO - PROCESS | 1098 | |9;json-stringify-tinderbox;60;60;59;60;60;60;61;59;60;60 05:38:08 INFO - PROCESS | 1098 | |10;stanford-crypto-aes;79;76;77;76;78;82;77;80;124;77 05:38:08 INFO - PROCESS | 1098 | |11;stanford-crypto-ccm;128;129;137;118;128;119;119;121;132;122 05:38:08 INFO - PROCESS | 1098 | |12;stanford-crypto-pbkdf2;160;155;156;163;158;159;156;164;156;154 05:38:08 INFO - PROCESS | 1098 | |13;stanford-crypto-sha256-iterative;62;62;62;61;64;63;62;61;61;61 05:38:08 INFO - PROCESS | 1098 | __end_tp_report 05:38:08 INFO - PROCESS | 1098 | __start_cc_report 05:38:08 INFO - PROCESS | 1098 | _x_x_mozilla_cycle_collect,1566 05:38:08 INFO - PROCESS | 1098 | __end_cc_report 05:38:08 INFO - PROCESS | 1098 | __startTimestamp1466771888052__endTimestamp 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | ------- Summary: start ------- 05:38:08 INFO - PROCESS | 1098 | Number of tests: 14 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:2.22 (2.0%) stddev-sans-first:1.99 05:38:08 INFO - PROCESS | 1098 | Values: 114.0 106.0 109.0 111.0 112.0 110.0 111.0 113.0 110.0 110.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#1] audio-beat-detection Cycles:10 Average:144.60 Median:151.00 stddev:22.30 (14.8%) stddev-sans-first:23.45 05:38:08 INFO - PROCESS | 1098 | Values: 153.0 123.0 133.0 149.0 128.0 155.0 153.0 127.0 197.0 128.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.07 (0.7%) stddev-sans-first:1.12 05:38:08 INFO - PROCESS | 1098 | Values: 162.0 162.0 161.0 163.0 161.0 161.0 160.0 161.0 163.0 160.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#3] audio-fft Cycles:10 Average:81.80 Median:76.00 stddev:12.80 (16.8%) stddev-sans-first:13.46 05:38:08 INFO - PROCESS | 1098 | Values: 77.0 74.0 78.0 111.0 76.0 76.0 76.0 75.0 100.0 75.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:85.00 stddev:19.64 (23.1%) stddev-sans-first:20.35 05:38:08 INFO - PROCESS | 1098 | Values: 85.0 86.0 114.0 85.0 86.0 84.0 127.0 85.0 85.0 133.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 05:38:08 INFO - PROCESS | 1098 | Values: 117.0 117.0 116.0 118.0 118.0 117.0 117.0 117.0 117.0 117.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 05:38:08 INFO - PROCESS | 1098 | Values: 146.0 146.0 146.0 147.0 147.0 146.0 147.0 146.0 146.0 146.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 05:38:08 INFO - PROCESS | 1098 | Values: 94.0 96.0 95.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:52.00 stddev:1.06 (2.0%) stddev-sans-first:1.09 05:38:08 INFO - PROCESS | 1098 | Values: 52.0 50.0 50.0 52.0 52.0 50.0 53.0 52.0 51.0 51.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 05:38:08 INFO - PROCESS | 1098 | Values: 60.0 60.0 59.0 60.0 60.0 60.0 61.0 59.0 60.0 60.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:77.50 stddev:14.67 (18.9%) stddev-sans-first:15.50 05:38:08 INFO - PROCESS | 1098 | Values: 79.0 76.0 77.0 76.0 78.0 82.0 77.0 80.0 124.0 77.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#11] stanford-crypto-ccm Cycles:10 Average:125.30 Median:128.00 stddev:6.43 (5.0%) stddev-sans-first:6.75 05:38:08 INFO - PROCESS | 1098 | Values: 128.0 129.0 137.0 118.0 128.0 119.0 119.0 121.0 132.0 122.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.10 Median:158.50 stddev:3.38 (2.1%) stddev-sans-first:3.52 05:38:08 INFO - PROCESS | 1098 | Values: 160.0 155.0 156.0 163.0 158.0 159.0 156.0 164.0 156.0 154.0 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - PROCESS | 1098 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:0.99 (1.6%) stddev-sans-first:1.05 05:38:08 INFO - PROCESS | 1098 | Values: 62.0 62.0 62.0 61.0 64.0 63.0 62.0 61.0 61.0 61.0 05:38:08 INFO - PROCESS | 1098 | -------- Summary: end -------- 05:38:08 INFO - PROCESS | 1098 | 05:38:08 INFO - TEST-INFO | 1098: exit 0 05:38:09 INFO - TEST-OK | kraken | took 60345ms 05:38:09 INFO - SUITE-END | took 1173s 05:38:09 INFO - Completed test suite (00:19:33) 05:38:09 INFO - javascript benchmark 05:38:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.7528185386095, 3326.085326535201, 3338.1821453743823, 3339.4823802310652, 3376.290978894432, 5557.138643510258, 5562.415946325638, 5583.162525621159, 5589.944100558993, 5596.300462442195, 5465.945340546593, 5509.889802203954, 5533.059379905416, 5537.0, 5539.25220095287, 4932.605391568666, 4989.9750501247445, 5033.773480193382, 5044.470330615288, 5059.316992206061, 5034.09386310464, 5116.130257856174, 5126.0, 5129.666571672848, 5129.820456284031, 5008.373953255843, 5055.342805435288, 5075.441701412841, 5094.2103973884105, 5104.081265372231, 5130.050940576006, 5148.768305426265, 5158.92261616076, 5162.974185129088, 5180.507851754096, 4930.8520744377365, 4954.925676114861, 5013.323201367842, 5064.3163172971545, 5081.923771143436, 5413.377461591928, 5441.809536666197, 5458.2904222450825, 5476.151196564539, 5513.641613295123, 6007.969960150172, 6017.097435384683, 6021.578489505737, 6022.8494287642725, 6024.367441418632, 5923.644581325134, 6019.367966363513, 6025.096235564656, 6058.454739073461, 6061.575689701723, 5606.383297837235, 5627.380988091307, 5647.8588035299035, 5667.149927510865, 5669.801556945569, 5928.0515117581845, 6135.171751813448, 6143.8326717923455, 6151.907721384183, 6157.261128664589, 5753.1370294456165, 5818.389069147764, 5830.183774271605, 5857.502112320415, 5860.912086318709, 5855.502282305966, 5873.676947767914, 5914.615549989237, 5926.170336152942, 5942.8811423771285, 5678.858028549319, 5797.333306669787, 5822.126680997884, 5823.5341172706085, 5826.41735826414, 5939.346671866091, 5967.045272756425, 5982.700864956735, 5990.311114221833, 6002.669853158118, 4545.272756358967, 4553.4763502197675, 4557.7721113944835, 4565.132624801344, 4582.587567118975, 3985.4021896715726, 4016.7389119707127, 4056.513218413809, 4068.4304197412384, 4104.15864747726, 5671.546276297886, 5698.088305871041, 5702.144678298288, 5720.513756330759, 5724.313082430135, 6043.7884674036195, 6049.78825741097, 6057.091436284592, 6059.787907423219, 6080.391960803884, 5494.423085576037, 5599.412061733541, 5616.719164041863, 5639.464250896158, 5646.802361917313, 4969.751512424365, 4976.2038073908725, 4978.950210497921, 4986.0775756484445, 5014.0, 6154.169187159766, 6200.224971878515, 6205.720742566505, 6205.875882482325, 6214.347493513116, 5786.0453025252045, 5907.084401917709, 5960.731767070405, 5969.522438204846, 5972.25346831646, 6168.228971378578, 6191.690415479298, 6192.566520343533, 6192.876142477216, 6193.938060619336, 5654.208410822406, 5880.735366908414, 5891.381404952418, 5892.469677729025, 5895.823125306248, 5879.235699359056, 5967.104934259895, 5983.042713165873, 6011.729472173675, 6015.48868346178, 5847.035239185487, 5871.6770577618645, 5879.588428810113, 5880.500157486662, 5888.733922206746, 5612.074007788669, 5717.685527296038, 5821.446962538722, 5879.147523609138, 5886.117082437669, 5723.542116630576, 5762.740676669476, 5781.884362312814, 5783.913241301299, 5805.41945805433, 5837.67892765902, 5850.414958504116, 5853.0, 5854.707264636836, 5897.233359663216, 5557.083081291542, 5596.636218645775, 5650.491455769001, 5678.0915053591225, 5704.885902282014], "value": 5489.762529842101, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.598269159663, 637.2957881540897, 649.5745286837121, 657.753342496564, 663.986720265595, 34577.0, 37813.0, 47014.529854701446, 47904.0, 47907.0, 48873.0, 49108.263376049385, 49576.752116239455, 49660.25509617358, 49728.50271497284, 8373.874391884126, 8997.325200609948, 9164.862527062067, 9185.816283674323, 10782.299150555227, 10843.891561084367, 10974.067204287605, 10993.395363255018, 11005.889941100566, 11014.06380457658, 10988.945055274713, 11048.779024419508, 11053.392063436508, 11055.391953442557, 11055.944720276388, 8525.829483410296, 8717.215450609412, 10874.401907895062, 10952.835707464394, 11155.498002589822, 10839.916008399176, 10945.4527273636, 10950.507227174716, 10956.506957186946, 10970.067544258747, 44821.551784482064, 45357.0, 45385.09229815385, 45408.0, 45501.72491375418, 3737.5141231639986, 3751.9249615007548, 3786.678132358753, 3971.960280397217, 4641.582257596799, 34781.13047173816, 35065.0, 35336.293274134376, 37290.0, 37630.43554346687, 4630.120277147331, 4643.8374656886845, 4671.299305104261, 4700.2009658358165, 4742.9525704742855, 389263.99999999715, 390774.0, 393759.0, 395350.0, 395468.0, 70919.29080709127, 72115.63942180255, 72132.63933680298, 72603.0, 72711.27288727045, 39834.402483962185, 40691.38962915559, 40717.0, 42531.14937701228, 42676.78661606672, 19010.52473688169, 19250.22999080021, 19253.90373048126, 19420.902895485713, 19421.611567768847, 12569.874301257054, 14313.78529322061, 14730.189839558712, 14772.778408323884, 15119.395224191128, 22694.886525567268, 22754.20360287382, 22800.42998925007, 22801.201957931393, 22809.771902280765, 7622.923770762332, 7815.374770018385, 7975.7607271781935, 8029.919700803034, 8089.838203235902, 1023.3246057602096, 1081.432248069754, 1121.388843080523, 1162.4071723421116, 1211.5638370186903, 33523.0, 34148.48777268343, 34809.0, 35326.0, 35582.64417355845, 1218.9939050304693, 1229.2562999385423, 1268.923864568129, 1287.5815360008035, 1305.9934700326628, 17954.5511362217, 18558.44324670262, 18601.906990464962, 18656.34702785396, 18659.626807463774, 520742.0, 525499.0, 526311.0, 528617.0, 528929.0, 66822.33177668258, 67485.6625716878, 67492.0, 67532.3246767526, 67688.32311676918, 1308.4700696218047, 1383.508854356709, 1409.9647508812361, 1430.8410187747957, 1434.9713005740036, 34016.82991585026, 34887.825560872036, 35657.0, 35761.821190893876, 36070.27859442744, 56589.717051416126, 57183.42816571947, 57202.0, 57214.7139264301, 57314.0, 433777.0, 440161.0, 441092.0, 443805.0, 444727.0, 27939.44121117607, 30211.244718882204, 30568.694313056585, 31364.686353136178, 31491.84254078715, 53298.73350633222, 53668.463315366345, 54146.0, 54745.0, 55239.44760552343, 45304.32043519416, 45760.54239457563, 46340.536594633624, 46834.531654683014, 47313.817154571414, 44807.551924480336, 45896.31155532737, 46233.537664624266, 46620.90205940809, 47005.294920576904, 44499.77750111229, 45048.54951450443, 45490.0, 46045.309320360895, 46047.0, 39540.40689389604, 39568.40647390349, 40394.39408408818, 40538.1892362157, 40573.79713101416, 30111.69888301089, 30575.694243058173, 30580.388392232475, 30636.234094147825, 30699.846500767355], "value": 22052.996710198, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.6886366361566, 412.23736088236745, 419.62443612966393, 426.9722468039577, 435.52962800175794, 19522.511937201558, 19580.70628940565, 19643.607127857435, 20328.288509902155, 20418.59162816743, 19739.309124180658, 20058.0, 20362.89818550909, 20930.581388372262, 20962.26632067878, 5670.064439367509, 5924.140999555062, 5955.374685657986, 5963.463288304052, 5964.165016897616, 5578.581606379517, 5731.1116776899435, 5814.24414826072, 5913.556483263751, 5918.112283157539, 5693.487586117249, 5778.942210577903, 5779.017567013597, 5804.448577385142, 5831.183634291181, 5734.942650573504, 5775.768969241246, 5800.071988481823, 5822.243108395903, 5825.621334613236, 5072.54347108762, 5184.092783762864, 5186.740662966837, 5193.610479214074, 5222.033923724123, 23936.401589960216, 23961.161359352514, 24611.13861014856, 24704.876475617686, 24791.380215494577, 2555.7060937992183, 2828.222238884303, 2873.339131999631, 2879.3521457672064, 2946.911592652224, 14442.927785361111, 14961.177135257556, 14989.550313490612, 15540.533783986499, 15621.297041633154, 2016.153215649416, 2026.98986505068, 2124.1184908263053, 2130.88280144592, 2183.5742030304095, 79248.0, 79672.0, 79775.20224797736, 80380.59809700915, 81145.0, 10712.410817404962, 11081.72295692598, 11185.384803835866, 11189.936955989167, 11242.269252498725, 15827.446039388566, 15850.44523441674, 15883.841161588467, 15957.281922313523, 15967.68064638724, 8619.439736417113, 8902.109789021046, 8915.108489151162, 8967.237784788236, 9015.774605634911, 8313.916860831436, 8410.074891761902, 8458.619362128718, 8490.660373584988, 8493.405461617627, 6938.271481494471, 6987.930120698829, 7042.330978557029, 7085.433165346863, 7104.964475177694, 1990.8009199080266, 2035.3385149826365, 2039.3576023552534, 2045.3148195354424, 2068.782777808339, 974.3861367338532, 1078.5955266774959, 1094.4965315954591, 1110.5224753356135, 1183.650823007215, 20043.0, 20140.39578812638, 20170.39488815338, 20254.392368228975, 20420.795792042187, 1040.4641609571077, 1048.753542917429, 1053.2890299048113, 1078.1859695929522, 1100.4277775556666, 10546.841797373045, 11679.883201167879, 11764.941175294069, 11804.350760708243, 11882.76234475323, 512234.0, 517672.0, 529550.0, 530652.0, 537614.0, 83453.58273208595, 84874.0, 85237.0, 85964.0, 86144.56927715447, 1079.6275285026654, 1095.1293721491434, 1098.5331234225423, 1107.8449017137607, 1112.6828853776635, 17773.46679599614, 17868.642627147125, 17973.460796175612, 18302.176402061672, 18329.175187116343, 38458.0, 39174.0, 39392.80303598464, 39890.87301587302, 40431.0, 53504.46495534995, 53543.19685204803, 53743.19385209147, 53769.462305376444, 53825.192622111485, 8992.46045237288, 9035.0, 9114.951780545322, 9248.491332976751, 9333.766655833659, 13447.260400678057, 13510.0, 13692.65768355799, 13694.726105478034, 13790.172589644653, 11649.242799218024, 11882.0, 11981.221220620631, 12152.270863748203, 12337.383130843602, 11826.881731182923, 11921.344326061802, 12006.09954253417, 12169.2698438094, 12457.003439724982, 12167.391630418266, 12239.632811015685, 12332.568360107713, 12472.5010999559, 12483.188592741442, 8630.5253211074, 8847.51338676379, 8883.244924181517, 8997.235235005099, 9073.500957447404, 12042.939785301018, 12049.0, 12230.510779568718, 12287.385630718249, 12431.502739890304], "value": 9755.484525118147, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.206917918428, 1471.418789578117, 1534.7314220011497, 1683.604352977051, 1874.5313671582096, 9238.260939124872, 9616.855747163789, 9663.420194788308, 9900.95049524753, 9902.455364954925, 9644.373115747472, 9832.705018849429, 9837.40975541468, 9847.409155450678, 9848.606055757778, 2532.8227024108364, 2564.7948164146915, 2566.1659960512816, 2566.9743302567017, 2572.6912770467475, 2539.9111031113916, 2569.884355204011, 2570.3060173753074, 2574.9742502575014, 2577.755113264237, 2442.7068751749816, 2471.1968610201666, 2527.3555243412998, 2528.05198050731, 2531.632913227581, 2355.988220058906, 2404.711434627847, 2411.168146989286, 2455.2756936703713, 2457.1522824625476, 2407.1935901473025, 2418.697662792151, 2432.987835060831, 2464.223769512616, 2464.8521088734733, 9397.389169704016, 9568.904310956941, 9638.325317227796, 9730.610775568968, 9743.756406089833, 5740.655560666373, 5856.853578660568, 5863.296404431453, 5867.471927526543, 5883.117532370136, 3624.1664417183933, 3636.1091532574424, 3706.36991711407, 3708.2398108387715, 3710.3135919854913, 1216.2763155922212, 1231.9199252048588, 1232.4146030635466, 1241.6150993192139, 1244.1974926172659, 13945.721085578332, 13954.0, 14002.299885005708, 14039.368228429643, 14043.929780351033, 4532.478764942008, 4646.25659894422, 4684.672072954861, 4740.407449068866, 4747.216709242936, 10746.24776265654, 10762.78474430507, 10771.461426928623, 10783.676489705322, 10796.892031079746, 5492.752826122834, 5494.0, 5494.725263736797, 5495.587830912697, 5499.367572729107, 4603.861884143481, 4823.155947709137, 4963.420446226316, 5169.431362550116, 5293.470652934753, 4136.110736191733, 4137.13120244752, 4169.082801783603, 4212.431321771522, 4239.894002649897, 1238.9690257743628, 1247.8814512621286, 1249.7125661097998, 1262.0471543984418, 1262.9075849390395, 1014.5130337438071, 1028.1671845804922, 1042.598599539183, 1051.574112484445, 1060.4432672846663, 6395.584287021329, 6435.22777266731, 6454.128692626529, 6482.319356467503, 6633.436157926631, 455.13524303823, 460.41297345884266, 464.6631192385561, 475.94050743657044, 478.1966296621709, 4314.439122914064, 4341.9131617367475, 4370.060437006059, 4381.386605875116, 4399.780010999437, 12300.938495307466, 12686.175398599062, 12765.808512872314, 12768.93615531935, 12805.871941280468, 11797.35114568707, 12439.626811195678, 12579.0, 12600.306983116017, 12601.621951341474, 9065.909340906506, 9244.583993720298, 9284.164425201629, 9305.11601397866, 9306.86039709405, 6486.870262594627, 6671.733130674718, 6713.899291510534, 6733.93266067333, 6759.695813688298, 11272.549098035986, 11530.308181509135, 11652.592159274724, 11732.589359372381, 11749.353785541878, 11372.20394572372, 11770.705732356759, 11782.0, 11925.224860384047, 11929.403529823649, 8608.741737747878, 8643.740687779376, 8724.083971182934, 8725.52009639476, 8734.126587341216, 9364.484953327632, 9466.053394660754, 9483.099105584959, 9594.856077158709, 9602.759931001781, 8291.173975951751, 8403.915960840557, 8432.70485533028, 8452.070272270168, 8582.871256931276, 8025.638846252049, 8123.75628731139, 8261.0, 8316.126806685454, 8481.321494280566, 7046.471514636525, 7608.087029556267, 7893.723719669784, 7899.68401263943, 7905.93269908578, 5414.3232095988005, 5443.374011988672, 5461.371942226695, 5506.284183056177, 5550.500454959073, 5288.536008914168, 5295.973520132528, 5327.493888080626, 5542.5565954722715, 5553.16702494629], "value": 4930.559021123105, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.40775470715204, 338.2119661189428, 349.6730556929271, 349.9177693242088, 350.08627482271254, 5323.361196656407, 5422.213779002042, 5428.348598168221, 5516.200150978106, 5569.637973531722, 5424.945750542493, 5425.504268036698, 5465.480779325958, 5491.368492623349, 5497.278846970745, 4265.0, 4268.1890440816305, 4270.530241673413, 4277.443932288798, 4285.35719642053, 4076.9196163016823, 4166.33338665812, 4310.97844510777, 4315.244832154376, 4332.870013899588, 4252.107057517925, 4257.191133684605, 4264.957350426487, 4270.167317373113, 4276.123394704094, 4286.935695964563, 4305.913881722379, 4318.309070548728, 4327.913441731163, 4328.9783551082355, 3574.070741607175, 3753.343164946151, 3758.530183727034, 3766.2090960898245, 3847.0, 5289.418164001957, 5311.097113490716, 5339.305890234284, 5343.706096164709, 5352.892942141136, 4666.23007203811, 4800.807967681323, 4869.391326084239, 4873.293372460973, 4903.387076615423, 5711.944109901225, 5738.598298119134, 5765.0, 5767.279090113736, 5773.653580785124, 4083.9183216335805, 4091.3044782387065, 4091.3249313863175, 4092.856750013735, 4116.773577453238, 5699.515541179005, 5876.55925805562, 5894.115882617599, 5927.4665280125, 5936.97031514844, 4121.072758629284, 4181.749095054306, 4184.393262976912, 4191.140816132685, 4196.181744559782, 4192.895177620584, 4197.580241975778, 4209.87370378883, 4228.577142285808, 4229.703920725581, 4736.750043729201, 4802.567768900815, 4813.518648135228, 4846.127697014571, 4858.58702010333, 5453.863653408696, 5624.690642014729, 5728.398518155534, 5738.770449181986, 5795.507381872588, 1435.6626192844674, 1443.2927865345905, 1446.681730019394, 1449.3695242569515, 1457.8979471436899, 910.9180173784258, 916.8028873792167, 932.8973812880578, 941.2187884056353, 944.527736131934, 4767.141914455362, 4937.506249375034, 4939.827106051271, 4952.925706114411, 4957.876053098701, 5186.4035635902355, 5213.661112027782, 5269.341332333458, 5275.419703832576, 5293.206019097166, 4616.815327386934, 4947.777350019258, 4969.304297398367, 4969.726665033458, 5054.469280725544, 2759.806813523034, 2772.944541109207, 2775.777937764974, 2805.424887897976, 2834.8157369770897, 5272.55183309423, 5331.787308267026, 5341.465853414627, 5344.198370244417, 5345.492178243061, 4966.975165124223, 5201.249406927052, 5296.894062118736, 5320.031588312349, 5345.685674881598, 5430.945690543202, 5450.264214331015, 5453.836384908459, 5490.972545137329, 5494.0, 5196.896062078813, 5231.921521177262, 5251.606129540376, 5255.185446255837, 5278.4721527846905, 4972.577330926912, 5178.792848286026, 5202.401723801811, 5207.895842083213, 5225.503577160163, 5380.977614253279, 5401.351837779492, 5424.5660347172925, 5456.236126942311, 5510.622344413897, 3835.5972622874197, 3837.7335479292324, 3845.596502186134, 3860.343741563997, 3869.245497128033, 4911.336969509213, 5198.4801519849225, 5201.245819356248, 5620.747066382104, 5734.0, 5688.573356998324, 5709.743061562157, 5713.771449141987, 5734.684592347294, 5748.166515855094, 5712.171735098471, 5717.285339332583, 5717.42825717425, 5768.711564421678, 5775.566832487496, 5511.338639363302, 5520.696361699985, 5646.802361917313, 5653.745581448927, 5660.971695141663, 5642.589352661835, 5662.603617746883, 5672.404397538199, 5684.118961560965, 5694.943050569441, 5403.487023633389, 5631.436856314336, 5638.519051617275, 5652.943470565242, 5667.1782591524825], "value": 4201.592842386201, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.911570611527, 770.5915864591765, 870.2689740617882, 884.4251236696143, 911.4166933162777, 15069.397224111022, 15986.92006539967, 15994.120323382209, 16012.0, 16035.759463608027, 14272.357743901523, 15497.225138743073, 15501.767473487907, 15512.84487155128, 15523.146226957513, 719.8524302517998, 844.5819319436887, 870.1081391573656, 883.9999999999984, 887.9600417981206, 886.521278509604, 886.6586364249778, 887.2591386192537, 888.1518150166607, 889.3907673243816, 921.6866265469772, 924.865894445305, 925.2228128372166, 925.6898938855492, 926.2775035472342, 761.8400135971452, 781.6170076662423, 781.7146741439391, 911.239115338693, 918.3846822628855, 682.3381320119477, 682.8975653651992, 682.9965850170767, 683.0948992584796, 684.5310961991051, 15486.148261845594, 15528.767068493982, 15530.223488825513, 15557.611059723598, 15580.143092129929, 15323.540293791204, 15488.690226195413, 15709.842901571066, 15807.446739364066, 15910.76133857993, 13234.933825330909, 13483.66290842727, 13546.661333466644, 13595.252261125634, 13654.658633534142, 4653.208954477747, 4709.364235828154, 4709.599684026862, 4718.339432479455, 4752.7861246244, 79863.20136798674, 80267.5986620069, 80378.59810700968, 80489.59755201245, 81371.0, 12039.944074315497, 12247.448864801105, 12374.690632734073, 12531.31077790712, 12619.116661833767, 20785.1685932561, 21394.465138371354, 21439.03524341377, 23573.29280121599, 23585.764142358355, 8603.354748393895, 8637.093105223987, 8955.0, 9125.45247285165, 9230.16928476427, 4893.339399181065, 4903.387076615423, 4917.0, 4949.282354058713, 4969.0, 3378.3412234614507, 3436.0722604897037, 3465.2549701814232, 3476.808775517371, 3486.9825650871585, 618.0235228339213, 622.7322251431838, 629.6002038705363, 635.9650219237893, 636.1634450697411, 715.7172916697875, 764.6635480388611, 770.4144849914105, 777.6422845491024, 778.190681691035, 10111.797764044677, 10207.438590877424, 10207.9489602553, 10268.691939241835, 10290.228232882564, 623.2023010546508, 723.7901008707416, 764.0, 771.0939645916026, 775.6083177995076, 5105.006141586051, 5143.074246635567, 5192.1173400522, 5212.348456442945, 5217.3999990000875, 69765.65117174381, 72322.0, 72341.27658723346, 72349.6382518084, 94020.0, 66204.0, 85785.57107214424, 89260.5536972311, 100411.0, 100596.49701751591, 81805.59097204595, 89306.55346723225, 89713.0, 90535.0, 92360.0, 9285.35002549815, 9333.67332143399, 9482.762930926781, 9518.666846660333, 9565.521723913636, 48364.0, 48559.78600535015, 53558.464415355345, 54046.189307161214, 56991.430085698616, 56799.0, 57267.0, 64589.0, 71437.64281178561, 78341.0, 14006.229657368942, 14232.217228052557, 14321.498747544203, 14443.205623690797, 14485.7827132591, 15723.21383930776, 15859.841401585836, 16082.0, 16324.020558766511, 16831.24259408305, 9553.904460955302, 13745.312734363442, 14101.576952691436, 14984.250787460802, 15690.450834220748, 14720.263986800832, 14967.550973470814, 14974.44081490189, 15072.095674259579, 15241.237938103272, 14338.283085845875, 14490.565283041526, 14852.405903763729, 14885.106893586419, 15083.622909426913, 11101.611443599435, 11107.888921110685, 11177.608783692533, 11458.255213411101, 11589.24669896454, 12029.939850301043, 12227.388630568614, 12525.436355363849, 12646.494140234656, 12702.30137342455], "value": 7821.1242114991155, "unit": "score"}], "name": "dromaeo_css", "value": 7591.298322996531, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 106.0, 109.0, 111.0, 112.0, 110.0, 111.0, 113.0, 110.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 133.0, 149.0, 128.0, 155.0, 153.0, 127.0, 197.0, 128.0], "value": 144.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 163.0, 161.0, 161.0, 160.0, 161.0, 163.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 78.0, 111.0, 76.0, 76.0, 76.0, 75.0, 100.0, 75.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 114.0, 85.0, 86.0, 84.0, 127.0, 85.0, 85.0, 133.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 50.0, 52.0, 52.0, 50.0, 53.0, 52.0, 51.0, 51.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 61.0, 59.0, 60.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 77.0, 76.0, 78.0, 82.0, 77.0, 80.0, 124.0, 77.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 129.0, 137.0, 118.0, 128.0, 119.0, 119.0, 121.0, 132.0, 122.0], "value": 125.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 155.0, 156.0, 163.0, 158.0, 159.0, 156.0, 164.0, 156.0, 154.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 64.0, 63.0, 62.0, 61.0, 61.0, 61.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1492.5, "alertThreshold": 2.0}]} 05:38:09 INFO - Return code: 0 05:38:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:38:09 ERROR - # TBPL SUCCESS # 05:38:09 INFO - Running post-action listener: _resource_record_post_action 05:38:09 INFO - ##### 05:38:09 INFO - ##### Finished run-tests step (success) 05:38:09 INFO - ##### 05:38:09 INFO - Running post-run listener: _resource_record_post_run 05:38:09 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 506056704; Read time: 40; Write time: 511420 05:38:09 INFO - TinderboxPrint: CPU usage
13.2% 05:38:09 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 40 05:38:09 INFO - TinderboxPrint: I/O write bytes / time
506,056,704 / 511,420 05:38:09 INFO - TinderboxPrint: CPU idle
8,243.0 (86.8%) 05:38:09 INFO - TinderboxPrint: CPU user
1,204.9 (12.7%) 05:38:09 INFO - TinderboxPrint: Swap in / out
0 / 0 05:38:09 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252821504; Read time: 0; Write time: 317624 05:38:09 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 266240; Write bytes: 253235200; Read time: 40; Write time: 193796 05:38:10 INFO - Running post-run listener: _upload_blobber_files 05:38:10 INFO - Blob upload gear active. 05:38:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:38:10 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 05:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:38:10 INFO - (blobuploader) - INFO - Open directory for files ... 05:38:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:38:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:38:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:14 INFO - (blobuploader) - INFO - Done attempting. 05:38:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:38:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:38:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:16 INFO - (blobuploader) - INFO - Done attempting. 05:38:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:38:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:38:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:16 INFO - (blobuploader) - INFO - Done attempting. 05:38:16 INFO - (blobuploader) - INFO - Iteration through files over. 05:38:16 INFO - Return code: 0 05:38:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:38:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:38:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38a30e0513b048d59d848d6f1aa169663c3183984cf8033d365621fc8510e8bdff6ad6853959311771727d0d056e53d342dcb1b735ad52cc487128999ef6aa7b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f4b0542430f8856b40fde345459c7c988c283aaaf7ff979760d7b1d91a55e8e9798e9b1fbbcd9eb144dd65b59e5836824d10762a385714bfc7fcc6cbe11d42d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b4eb5c21aaa8c8563396fc1277b28758fb7d21f08c28282165f6f5769165da56704034b6e4bc28ec5cac56a653dfe8c450520449788fb40b326ec07c7d41c96"} 05:38:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:38:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:38:16 INFO - Contents: 05:38:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38a30e0513b048d59d848d6f1aa169663c3183984cf8033d365621fc8510e8bdff6ad6853959311771727d0d056e53d342dcb1b735ad52cc487128999ef6aa7b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f4b0542430f8856b40fde345459c7c988c283aaaf7ff979760d7b1d91a55e8e9798e9b1fbbcd9eb144dd65b59e5836824d10762a385714bfc7fcc6cbe11d42d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b4eb5c21aaa8c8563396fc1277b28758fb7d21f08c28282165f6f5769165da56704034b6e4bc28ec5cac56a653dfe8c450520449788fb40b326ec07c7d41c96"} 05:38:16 INFO - Running post-run listener: copy_logs_to_upload_dir 05:38:16 INFO - Copying logs to upload dir... 05:38:16 INFO - mkdir: /builds/slave/test/build/upload/logs 05:38:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.758931 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-06-24 05:38:16.089951) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-24 05:38:16.094041) ========= 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=52faae308a556c440401f8e5000001e8-1466770662.273993-947807449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38a30e0513b048d59d848d6f1aa169663c3183984cf8033d365621fc8510e8bdff6ad6853959311771727d0d056e53d342dcb1b735ad52cc487128999ef6aa7b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f4b0542430f8856b40fde345459c7c988c283aaaf7ff979760d7b1d91a55e8e9798e9b1fbbcd9eb144dd65b59e5836824d10762a385714bfc7fcc6cbe11d42d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b4eb5c21aaa8c8563396fc1277b28758fb7d21f08c28282165f6f5769165da56704034b6e4bc28ec5cac56a653dfe8c450520449788fb40b326ec07c7d41c96"} build_url:https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/OzIqfqpEStik9b6brvmCuQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38a30e0513b048d59d848d6f1aa169663c3183984cf8033d365621fc8510e8bdff6ad6853959311771727d0d056e53d342dcb1b735ad52cc487128999ef6aa7b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f4b0542430f8856b40fde345459c7c988c283aaaf7ff979760d7b1d91a55e8e9798e9b1fbbcd9eb144dd65b59e5836824d10762a385714bfc7fcc6cbe11d42d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b4eb5c21aaa8c8563396fc1277b28758fb7d21f08c28282165f6f5769165da56704034b6e4bc28ec5cac56a653dfe8c450520449788fb40b326ec07c7d41c96"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-24 05:38:16.123203) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:38:16.124016) ========= 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=52faae308a556c440401f8e5000001e8-1466770662.273993-947807449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004549 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 05:38:16.208019) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-24 05:38:16.209031) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-24 05:38:16.210030) ========= ========= Total master_lag: 0.20 =========