builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1464066332.45 results: success (0) buildid: 20160523193033 builduid: 06a868e8689c40f29754689df9b8e090 revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:32.452208) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:32.452691) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:32.452972) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:32.484372) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:32.484647) ========= 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-1464066252.605930-175528650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-23 22:05:32-- 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% 7.61M=0.002s 2016-05-23 22:05:32 (7.61 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117474 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:32.615318) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:32.615618) ========= 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-1464066252.605930-175528650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015476 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:32.708279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:32.708649) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --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-1464066252.605930-175528650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-23 22:05:32,946 truncating revision to first 12 chars 2016-05-23 22:05:32,947 Setting DEBUG logging. 2016-05-23 22:05:32,947 attempt 1/10 2016-05-23 22:05:32,947 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/46fe2115d46a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 22:05:33,202 unpacking tar archive at: mozilla-central-46fe2115d46a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.529630 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:33.250918) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:33.251209) ========= script_repo_revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:33.251616) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:33.251931) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 22:05:33.259778) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-05-23 22:05:33.260089) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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-1464066252.605930-175528650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:05:33 INFO - MultiFileLogger online at 20160523 22:05:33 in /builds/slave/test 22:05:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:05:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:05:33 INFO - {'append_to_log': False, 22:05:33 INFO - 'base_work_dir': '/builds/slave/test', 22:05:33 INFO - 'blob_upload_branch': 'Firefox', 22:05:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:05:33 INFO - 'branch': 'Firefox', 22:05:33 INFO - 'buildbot_json_path': 'buildprops.json', 22:05:33 INFO - 'config_files': ('talos/linux_config.py',), 22:05:33 INFO - 'default_actions': ('clobber', 22:05:33 INFO - 'read-buildbot-config', 22:05:33 INFO - 'download-and-extract', 22:05:33 INFO - 'populate-webroot', 22:05:33 INFO - 'create-virtualenv', 22:05:33 INFO - 'install', 22:05:33 INFO - 'run-tests'), 22:05:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:05:33 INFO - 'download_minidump_stackwalk': True, 22:05:33 INFO - 'download_symbols': 'ondemand', 22:05:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:05:33 INFO - 'tooltool.py': '/tools/tooltool.py', 22:05:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:05:33 INFO - '/tools/misc-python/virtualenv.py')}, 22:05:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:05:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:05:33 INFO - 'installer_path': 'installer.exe', 22:05:33 INFO - 'log_level': 'info', 22:05:33 INFO - 'log_name': 'talos', 22:05:33 INFO - 'log_to_console': True, 22:05:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:05:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:05:33 INFO - 'opt_config_files': (), 22:05:33 INFO - 'pip_index': False, 22:05:33 INFO - 'sps_profile': False, 22:05:33 INFO - 'sps_profile_interval': 0, 22:05:33 INFO - 'suite': 'dromaeojs', 22:05:33 INFO - 'system_bits': '32', 22:05:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:05:33 INFO - 'title': 'talos-linux64-ix-056', 22:05:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:05:33 INFO - 'use_talos_json': True, 22:05:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:05:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:05:33 INFO - 'work_dir': 'build'} 22:05:33 INFO - ##### 22:05:33 INFO - ##### Running clobber step. 22:05:33 INFO - ##### 22:05:33 INFO - Running pre-action listener: _resource_record_pre_action 22:05:33 INFO - Running main action method: clobber 22:05:33 INFO - rmtree: /builds/slave/test/build 22:05:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:05:34 INFO - Running post-action listener: _resource_record_post_action 22:05:34 INFO - ##### 22:05:34 INFO - ##### Finished clobber step (success) 22:05:34 INFO - ##### 22:05:34 INFO - ##### 22:05:34 INFO - ##### Running read-buildbot-config step. 22:05:34 INFO - ##### 22:05:34 INFO - Running pre-action listener: _resource_record_pre_action 22:05:34 INFO - Running main action method: read_buildbot_config 22:05:34 INFO - Using buildbot properties: 22:05:34 INFO - { 22:05:34 INFO - "project": "", 22:05:34 INFO - "product": "firefox", 22:05:34 INFO - "script_repo_revision": "production", 22:05:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:05:34 INFO - "repository": "", 22:05:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:05:34 INFO - "buildid": "20160523193033", 22:05:34 INFO - "pgo_build": "True", 22:05:34 INFO - "basedir": "/builds/slave/test", 22:05:34 INFO - "buildnumber": 299, 22:05:34 INFO - "slavename": "talos-linux64-ix-056", 22:05:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:05:34 INFO - "platform": "ubuntu64_hw", 22:05:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:05:34 INFO - "branch": "mozilla-central", 22:05:34 INFO - "repo_path": "mozilla-central", 22:05:34 INFO - "revision": "46fe2115d46a5bb40523b8466341d8f9a26e1bdf", 22:05:34 INFO - "stage_platform": "linux64-pgo", 22:05:34 INFO - "builduid": "06a868e8689c40f29754689df9b8e090", 22:05:34 INFO - "slavebuilddir": "test" 22:05:34 INFO - } 22:05:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:05:34 INFO - Running post-action listener: _resource_record_post_action 22:05:34 INFO - ##### 22:05:34 INFO - ##### Finished read-buildbot-config step (success) 22:05:34 INFO - ##### 22:05:34 INFO - ##### 22:05:34 INFO - ##### Running download-and-extract step. 22:05:34 INFO - ##### 22:05:34 INFO - Running pre-action listener: _resource_record_pre_action 22:05:34 INFO - Running main action method: download_and_extract 22:05:34 INFO - mkdir: /builds/slave/test/build/tests 22:05: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')]} 22:05:34 INFO - https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:05:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:05:40 INFO - Downloaded 1361 bytes. 22:05:40 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:40 INFO - Using the following test package requirements: 22:05:40 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:05:40 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:05:40 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:05:40 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:40 INFO - u'jsshell-linux-x86_64.zip'], 22:05:40 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:05:40 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:05:40 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:05:40 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:05:40 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:05:40 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:05:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:40 INFO - https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:05:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:05:46 INFO - Downloaded 24389070 bytes. 22:05:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:05:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:05:47 INFO - Return code: 0 22:05:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:05: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')]} 22:05:47 INFO - https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:05:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:05:49 INFO - Downloaded 24389070 bytes. 22:05:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:05:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:05:51 INFO - Return code: 0 22:05: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')]} 22:05:51 INFO - https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:05:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:05:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:05:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:05:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:05:54 INFO - Downloaded 10840359 bytes. 22:05:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:05:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:05:54 INFO - Return code: 0 22:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:54 INFO - https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:05:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:05:56 INFO - Downloaded 58598567 bytes. 22:05:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:56 INFO - mkdir: /builds/slave/test/properties 22:05:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:05:56 INFO - Writing to file /builds/slave/test/properties/build_url 22:05:56 INFO - Contents: 22:05:56 INFO - build_url:https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:57 INFO - Running post-action listener: _resource_record_post_action 22:05:57 INFO - Running post-action listener: set_extra_try_arguments 22:05:57 INFO - ##### 22:05:57 INFO - ##### Finished download-and-extract step (success) 22:05:57 INFO - ##### 22:05:57 INFO - ##### 22:05:57 INFO - ##### Running populate-webroot step. 22:05:57 INFO - ##### 22:05:57 INFO - Running pre-action listener: _resource_record_pre_action 22:05:57 INFO - Running main action method: populate_webroot 22:05:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:05:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:05:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:05:57 INFO - '--tppagecycles', 22:05:57 INFO - '7'], 22:05:57 INFO - 'tests': ['tsvgm']}}, 22:05:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:05:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:05:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:05:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:05:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:05:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:05:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:57 INFO - 'talos_options': ['--disable-e10s'], 22:05:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:57 INFO - 'talos_options': ['--disable-e10s'], 22:05:57 INFO - 'tests': ['damp', 'tps']}, 22:05:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:57 INFO - 'tests': ['damp', 'tps']}, 22:05:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:05:57 INFO - 'tests': ['dromaeo_dom']}, 22:05:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:05:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:05:57 INFO - 'tests': ['a11yr', 22:05:57 INFO - 'ts_paint', 22:05:57 INFO - 'tpaint', 22:05:57 INFO - 'sessionrestore', 22:05:57 INFO - 'sessionrestore_no_auto_restore', 22:05:57 INFO - 'tabpaint']}, 22:05:57 INFO - 'other-e10s': {'tests': ['a11yr', 22:05:57 INFO - 'ts_paint', 22:05:57 INFO - 'tpaint', 22:05:57 INFO - 'sessionrestore', 22:05:57 INFO - 'sessionrestore_no_auto_restore', 22:05:57 INFO - 'tabpaint']}, 22:05:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:05:57 INFO - 'ts_paint', 22:05:57 INFO - 'tpaint', 22:05:57 INFO - 'sessionrestore', 22:05:57 INFO - 'sessionrestore_no_auto_restore', 22:05:57 INFO - 'tabpaint']}, 22:05:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:05:57 INFO - 'ts_paint', 22:05:57 INFO - 'tpaint', 22:05:57 INFO - 'sessionrestore', 22:05:57 INFO - 'sessionrestore_no_auto_restore', 22:05:57 INFO - 'tabpaint']}, 22:05:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:05:57 INFO - 'tests': ['a11yr', 22:05:57 INFO - 'ts_paint', 22:05:57 INFO - 'tpaint', 22:05:57 INFO - 'sessionrestore', 22:05:57 INFO - 'sessionrestore_no_auto_restore', 22:05:57 INFO - 'tabpaint']}, 22:05:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:05:57 INFO - 'tests': ['a11yr', 22:05:57 INFO - 'ts_paint', 22:05:57 INFO - 'tpaint', 22:05:57 INFO - 'sessionrestore', 22:05:57 INFO - 'sessionrestore_no_auto_restore', 22:05:57 INFO - 'tabpaint']}, 22:05:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:05:57 INFO - 'tests': ['tsvgx', 22:05:57 INFO - 'tsvgr_opacity', 22:05:57 INFO - 'tart', 22:05:57 INFO - 'tscrollx', 22:05:57 INFO - 'cart']}, 22:05:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:05:57 INFO - 'tsvgr_opacity', 22:05:57 INFO - 'tart', 22:05:57 INFO - 'tscrollx', 22:05:57 INFO - 'cart']}, 22:05:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:57 INFO - 'talos_options': ['--disable-e10s'], 22:05:57 INFO - 'tests': ['tp5o']}, 22:05:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:57 INFO - 'tests': ['tp5o']}, 22:05:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:57 INFO - 'talos_options': ['--disable-e10s', 22:05:57 INFO - '--xperf_path', 22:05:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:57 INFO - 'tests': ['tp5n']}, 22:05:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:57 INFO - 'talos_options': ['--xperf_path', 22:05:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:57 INFO - 'tests': ['tp5n']}}, 22:05:57 INFO - 'talos.zip': {'path': '', 22:05:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:05:57 INFO - Running post-action listener: _resource_record_post_action 22:05:57 INFO - ##### 22:05:57 INFO - ##### Finished populate-webroot step (success) 22:05:57 INFO - ##### 22:05:57 INFO - ##### 22:05:57 INFO - ##### Running create-virtualenv step. 22:05:57 INFO - ##### 22:05:57 INFO - Running pre-action listener: _resource_record_pre_action 22:05:57 INFO - Running main action method: create_virtualenv 22:05:57 INFO - Creating virtualenv /builds/slave/test/build/venv 22:05:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:05:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:05:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:05:57 INFO - Using real prefix '/usr' 22:05:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:05:57 INFO - Installing distribute.............................................................................................................................................................................................done. 22:05:59 INFO - Installing pip.................done. 22:05:59 INFO - Return code: 0 22:05:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:05: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')]} 22:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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')]} 22:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc2ab3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x148f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151e9c0>, '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 0x151f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151fd50>, '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-1464066252.605930-175528650', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05: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', 'pip>=1.5'] in /builds/slave/test/build 22:05: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 pip>=1.5 22:05:59 INFO - Using env: {'DISPLAY': ':0', 22:05:59 INFO - 'HOME': '/home/cltbld', 22:05:59 INFO - 'LANG': 'en_US.UTF-8', 22:05:59 INFO - 'LANGUAGE': 'en_US:en', 22:05:59 INFO - 'LOGNAME': 'cltbld', 22:05:59 INFO - 'MAIL': '/var/mail/cltbld', 22:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:59 INFO - 'MOZ_NO_REMOTE': '1', 22:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:59 INFO - 'NO_EM_RESTART': '1', 22:05:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:59 INFO - 'PWD': '/builds/slave/test', 22:05:59 INFO - 'SHELL': '/bin/bash', 22:05:59 INFO - 'SHLVL': '1', 22:05:59 INFO - 'TERM': 'linux', 22:05:59 INFO - 'TMOUT': '86400', 22:05:59 INFO - 'USER': 'cltbld', 22:05:59 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1464066252.605930-175528650', 22:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:59 INFO - '_': '/tools/buildbot/bin/python'} 22:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:05:59 INFO - Cleaning up... 22:05:59 INFO - Return code: 0 22:05:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:05: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')]} 22:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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')]} 22:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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', '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 0x7ffc2ab3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x148f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151e9c0>, '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 0x151f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151fd50>, '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-1464066252.605930-175528650', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:05: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 psutil>=3.1.1 22:05:59 INFO - Using env: {'DISPLAY': ':0', 22:05:59 INFO - 'HOME': '/home/cltbld', 22:05:59 INFO - 'LANG': 'en_US.UTF-8', 22:05:59 INFO - 'LANGUAGE': 'en_US:en', 22:05:59 INFO - 'LOGNAME': 'cltbld', 22:05:59 INFO - 'MAIL': '/var/mail/cltbld', 22:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:59 INFO - 'MOZ_NO_REMOTE': '1', 22:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:59 INFO - 'NO_EM_RESTART': '1', 22:05:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:59 INFO - 'PWD': '/builds/slave/test', 22:05:59 INFO - 'SHELL': '/bin/bash', 22:05:59 INFO - 'SHLVL': '1', 22:05:59 INFO - 'TERM': 'linux', 22:05:59 INFO - 'TMOUT': '86400', 22:05:59 INFO - 'USER': 'cltbld', 22:05:59 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1464066252.605930-175528650', 22:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:59 INFO - '_': '/tools/buildbot/bin/python'} 22:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:00 INFO - Downloading/unpacking psutil>=3.1.1 22:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:06:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:06:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:06:02 INFO - Installing collected packages: psutil 22:06:02 INFO - Running setup.py install for psutil 22:06:02 INFO - building 'psutil._psutil_linux' extension 22:06:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:06:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:06:02 INFO - building 'psutil._psutil_posix' extension 22:06:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:06:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:06:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:06:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:06:02 INFO - Successfully installed psutil 22:06:02 INFO - Cleaning up... 22:06:02 INFO - Return code: 0 22:06:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:02 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 0x7ffc2ab3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x148f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151e9c0>, '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 0x151f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151fd50>, '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-1464066252.605930-175528650', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:06:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:06:02 INFO - Using env: {'DISPLAY': ':0', 22:06:02 INFO - 'HOME': '/home/cltbld', 22:06:02 INFO - 'LANG': 'en_US.UTF-8', 22:06:02 INFO - 'LANGUAGE': 'en_US:en', 22:06:02 INFO - 'LOGNAME': 'cltbld', 22:06:02 INFO - 'MAIL': '/var/mail/cltbld', 22:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:02 INFO - 'MOZ_NO_REMOTE': '1', 22:06:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:02 INFO - 'NO_EM_RESTART': '1', 22:06:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:02 INFO - 'PWD': '/builds/slave/test', 22:06:02 INFO - 'SHELL': '/bin/bash', 22:06:02 INFO - 'SHLVL': '1', 22:06:02 INFO - 'TERM': 'linux', 22:06:02 INFO - 'TMOUT': '86400', 22:06:02 INFO - 'USER': 'cltbld', 22:06:02 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1464066252.605930-175528650', 22:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:02 INFO - '_': '/tools/buildbot/bin/python'} 22:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:06:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:06:04 INFO - Installing collected packages: mozsystemmonitor 22:06:04 INFO - Running setup.py install for mozsystemmonitor 22:06:04 INFO - Successfully installed mozsystemmonitor 22:06:04 INFO - Cleaning up... 22:06:04 INFO - Return code: 0 22:06:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:06: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')]} 22:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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')]} 22:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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 0x7ffc2ab3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x148f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151e9c0>, '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 0x151f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151fd50>, '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-1464066252.605930-175528650', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:06: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 22:06: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 22:06:04 INFO - Using env: {'DISPLAY': ':0', 22:06:04 INFO - 'HOME': '/home/cltbld', 22:06:04 INFO - 'LANG': 'en_US.UTF-8', 22:06:04 INFO - 'LANGUAGE': 'en_US:en', 22:06:04 INFO - 'LOGNAME': 'cltbld', 22:06:04 INFO - 'MAIL': '/var/mail/cltbld', 22:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:04 INFO - 'MOZ_NO_REMOTE': '1', 22:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:04 INFO - 'NO_EM_RESTART': '1', 22:06:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:04 INFO - 'PWD': '/builds/slave/test', 22:06:04 INFO - 'SHELL': '/bin/bash', 22:06:04 INFO - 'SHLVL': '1', 22:06:04 INFO - 'TERM': 'linux', 22:06:04 INFO - 'TMOUT': '86400', 22:06:04 INFO - 'USER': 'cltbld', 22:06:04 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1464066252.605930-175528650', 22:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:04 INFO - '_': '/tools/buildbot/bin/python'} 22:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:05 INFO - Downloading/unpacking blobuploader==1.2.4 22:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:06 INFO - Downloading blobuploader-1.2.4.tar.gz 22:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:06:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:06:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:07 INFO - Downloading docopt-0.6.1.tar.gz 22:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:06:07 INFO - Installing collected packages: blobuploader, requests, docopt 22:06:07 INFO - Running setup.py install for blobuploader 22:06:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:06:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:06:07 INFO - Running setup.py install for requests 22:06:08 INFO - Running setup.py install for docopt 22:06:08 INFO - Successfully installed blobuploader requests docopt 22:06:08 INFO - Cleaning up... 22:06:08 INFO - Return code: 0 22:06:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:08 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 0x7ffc2ab3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x148f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151e9c0>, '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 0x151f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151fd50>, '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-1464066252.605930-175528650', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:06:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:06:08 INFO - Using env: {'DISPLAY': ':0', 22:06:08 INFO - 'HOME': '/home/cltbld', 22:06:08 INFO - 'LANG': 'en_US.UTF-8', 22:06:08 INFO - 'LANGUAGE': 'en_US:en', 22:06:08 INFO - 'LOGNAME': 'cltbld', 22:06:08 INFO - 'MAIL': '/var/mail/cltbld', 22:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:08 INFO - 'MOZ_NO_REMOTE': '1', 22:06:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:08 INFO - 'NO_EM_RESTART': '1', 22:06:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:08 INFO - 'PWD': '/builds/slave/test', 22:06:08 INFO - 'SHELL': '/bin/bash', 22:06:08 INFO - 'SHLVL': '1', 22:06:08 INFO - 'TERM': 'linux', 22:06:08 INFO - 'TMOUT': '86400', 22:06:08 INFO - 'USER': 'cltbld', 22:06:08 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1464066252.605930-175528650', 22:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:08 INFO - '_': '/tools/buildbot/bin/python'} 22:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:08 INFO - Running setup.py (path:/tmp/pip-eTFjb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:08 INFO - Running setup.py (path:/tmp/pip-9fiSbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:08 INFO - Running setup.py (path:/tmp/pip-rO_ZXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:08 INFO - Running setup.py (path:/tmp/pip-rdGiq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:08 INFO - Running setup.py (path:/tmp/pip-ETz1Sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:08 INFO - Running setup.py (path:/tmp/pip-vsXjKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:09 INFO - Running setup.py (path:/tmp/pip-LMNbzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:09 INFO - Running setup.py (path:/tmp/pip-UHVktf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:09 INFO - Running setup.py (path:/tmp/pip-TAlBNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:09 INFO - Running setup.py (path:/tmp/pip-lRs2sL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:09 INFO - Running setup.py (path:/tmp/pip-dF_5fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:09 INFO - Running setup.py (path:/tmp/pip-6oqLjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:09 INFO - Running setup.py (path:/tmp/pip-IbXJlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:09 INFO - Running setup.py (path:/tmp/pip-MPa0xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:09 INFO - Running setup.py (path:/tmp/pip-HKo3Ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:09 INFO - Running setup.py (path:/tmp/pip-k4sErW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:09 INFO - Running setup.py (path:/tmp/pip-XBppcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:06:09 INFO - Running setup.py install for manifestparser 22:06:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:06:09 INFO - Running setup.py install for mozcrash 22:06:10 INFO - Running setup.py install for mozdebug 22:06:10 INFO - Running setup.py install for mozdevice 22:06:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:06:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:06:10 INFO - Running setup.py install for mozfile 22:06:10 INFO - Running setup.py install for mozhttpd 22:06:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:06:10 INFO - Running setup.py install for mozinfo 22:06:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:06:10 INFO - Running setup.py install for mozInstall 22:06:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:06:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:06:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:06:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:06:10 INFO - Running setup.py install for mozleak 22:06:10 INFO - Running setup.py install for mozlog 22:06:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:06:10 INFO - Running setup.py install for moznetwork 22:06:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:06:11 INFO - Running setup.py install for mozprocess 22:06:11 INFO - Running setup.py install for mozprofile 22:06:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:06:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:06:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:06:11 INFO - Running setup.py install for mozrunner 22:06:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:06:11 INFO - Running setup.py install for mozscreenshot 22:06:11 INFO - Running setup.py install for moztest 22:06:11 INFO - Running setup.py install for mozversion 22:06:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:06:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:06:11 INFO - Cleaning up... 22:06:11 INFO - Return code: 0 22:06:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:11 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 0x7ffc2ab3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x148f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151e9c0>, '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 0x151f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151fd50>, '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-1464066252.605930-175528650', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:06:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:06:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:06:11 INFO - Using env: {'DISPLAY': ':0', 22:06:11 INFO - 'HOME': '/home/cltbld', 22:06:11 INFO - 'LANG': 'en_US.UTF-8', 22:06:11 INFO - 'LANGUAGE': 'en_US:en', 22:06:11 INFO - 'LOGNAME': 'cltbld', 22:06:11 INFO - 'MAIL': '/var/mail/cltbld', 22:06:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:11 INFO - 'MOZ_NO_REMOTE': '1', 22:06:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:11 INFO - 'NO_EM_RESTART': '1', 22:06:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:11 INFO - 'PWD': '/builds/slave/test', 22:06:11 INFO - 'SHELL': '/bin/bash', 22:06:11 INFO - 'SHLVL': '1', 22:06:11 INFO - 'TERM': 'linux', 22:06:11 INFO - 'TMOUT': '86400', 22:06:11 INFO - 'USER': 'cltbld', 22:06:11 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1464066252.605930-175528650', 22:06:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:11 INFO - '_': '/tools/buildbot/bin/python'} 22:06:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:11 INFO - Running setup.py (path:/tmp/pip-6s6aDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06: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)) 22:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:11 INFO - Running setup.py (path:/tmp/pip-6eQ2m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:12 INFO - Running setup.py (path:/tmp/pip-RVJbyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:12 INFO - Running setup.py (path:/tmp/pip-xrBdfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:12 INFO - Running setup.py (path:/tmp/pip-bmwSjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:12 INFO - Running setup.py (path:/tmp/pip-fvlQOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:12 INFO - Running setup.py (path:/tmp/pip-QplvYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:12 INFO - Running setup.py (path:/tmp/pip-TmV5dZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:12 INFO - Running setup.py (path:/tmp/pip-SbvIgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:12 INFO - Running setup.py (path:/tmp/pip-mTk5Ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:12 INFO - Running setup.py (path:/tmp/pip-VWoOp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:12 INFO - Running setup.py (path:/tmp/pip-MNqyym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06: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)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:12 INFO - Running setup.py (path:/tmp/pip-2lCDK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06: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)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:12 INFO - Running setup.py (path:/tmp/pip-XqtCHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06: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)) 22:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:12 INFO - Running setup.py (path:/tmp/pip-xkpuT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:13 INFO - Running setup.py (path:/tmp/pip-w_ogbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:13 INFO - Running setup.py (path:/tmp/pip-EMOzPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:06:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:15 INFO - Downloading blessings-1.6.tar.gz 22:06:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:06:15 INFO - Installing collected packages: blessings 22:06:15 INFO - Running setup.py install for blessings 22:06:15 INFO - Successfully installed blessings 22:06:15 INFO - Cleaning up... 22:06:15 INFO - Return code: 0 22:06:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:15 INFO - Reading from file tmpfile_stdout 22:06:15 INFO - Current package versions: 22:06:15 INFO - argparse == 1.2.1 22:06:15 INFO - blessings == 1.6 22:06:15 INFO - blobuploader == 1.2.4 22:06:15 INFO - docopt == 0.6.1 22:06:15 INFO - manifestparser == 1.1 22:06:15 INFO - mozInstall == 1.12 22:06:15 INFO - mozcrash == 0.17 22:06:15 INFO - mozdebug == 0.1 22:06:15 INFO - mozdevice == 0.48 22:06:15 INFO - mozfile == 1.2 22:06:15 INFO - mozhttpd == 0.7 22:06:15 INFO - mozinfo == 0.9 22:06:15 INFO - mozleak == 0.1 22:06:15 INFO - mozlog == 3.2 22:06:15 INFO - moznetwork == 0.27 22:06:15 INFO - mozprocess == 0.22 22:06:15 INFO - mozprofile == 0.28 22:06:15 INFO - mozrunner == 6.11 22:06:15 INFO - mozscreenshot == 0.1 22:06:15 INFO - mozsystemmonitor == 0.3 22:06:15 INFO - moztest == 0.7 22:06:15 INFO - mozversion == 1.4 22:06:15 INFO - psutil == 3.1.1 22:06:15 INFO - requests == 1.2.3 22:06:15 INFO - wsgiref == 0.1.2 22:06:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06: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')]} 22:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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')]} 22:06:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:15 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 0x7ffc2ab3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x148f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151e9c0>, '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 0x151f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151fd50>, '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-1464066252.605930-175528650', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:06:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:06:15 INFO - Using env: {'DISPLAY': ':0', 22:06:15 INFO - 'HOME': '/home/cltbld', 22:06:15 INFO - 'LANG': 'en_US.UTF-8', 22:06:15 INFO - 'LANGUAGE': 'en_US:en', 22:06:15 INFO - 'LOGNAME': 'cltbld', 22:06:15 INFO - 'MAIL': '/var/mail/cltbld', 22:06:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:15 INFO - 'MOZ_NO_REMOTE': '1', 22:06:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:15 INFO - 'NO_EM_RESTART': '1', 22:06:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:15 INFO - 'PWD': '/builds/slave/test', 22:06:15 INFO - 'SHELL': '/bin/bash', 22:06:15 INFO - 'SHLVL': '1', 22:06:15 INFO - 'TERM': 'linux', 22:06:15 INFO - 'TMOUT': '86400', 22:06:15 INFO - 'USER': 'cltbld', 22:06:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1464066252.605930-175528650', 22:06:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:15 INFO - '_': '/tools/buildbot/bin/python'} 22:06:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:06:15 INFO - Cleaning up... 22:06:15 INFO - Return code: 0 22:06:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:06: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')]} 22:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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')]} 22:06:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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 0x7ffc2ab3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x148f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151e9c0>, '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 0x151f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151fd50>, '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-1464066252.605930-175528650', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:06: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 22:06: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 22:06:15 INFO - Using env: {'DISPLAY': ':0', 22:06:15 INFO - 'HOME': '/home/cltbld', 22:06:15 INFO - 'LANG': 'en_US.UTF-8', 22:06:15 INFO - 'LANGUAGE': 'en_US:en', 22:06:15 INFO - 'LOGNAME': 'cltbld', 22:06:15 INFO - 'MAIL': '/var/mail/cltbld', 22:06:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:15 INFO - 'MOZ_NO_REMOTE': '1', 22:06:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:15 INFO - 'NO_EM_RESTART': '1', 22:06:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:15 INFO - 'PWD': '/builds/slave/test', 22:06:15 INFO - 'SHELL': '/bin/bash', 22:06:15 INFO - 'SHLVL': '1', 22:06:15 INFO - 'TERM': 'linux', 22:06:15 INFO - 'TMOUT': '86400', 22:06:15 INFO - 'USER': 'cltbld', 22:06:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1464066252.605930-175528650', 22:06:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:15 INFO - '_': '/tools/buildbot/bin/python'} 22:06:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:15 INFO - Downloading/unpacking jsonschema 22:06: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 22:06: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 22:06: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 22:06: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 22:06: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 22:06: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 22:06:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:06:17 INFO - Downloading/unpacking functools32 (from jsonschema) 22:06: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 22:06: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 22:06: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 22:06: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 22:06: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 22:06: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 22:06:18 INFO - Downloading functools32-3.2.3-2.tar.gz 22:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:06:18 INFO - warning: no files found matching '*.txt' 22:06:18 INFO - no previously-included directories found matching 'build' 22:06:18 INFO - no previously-included directories found matching 'dist' 22:06:18 INFO - no previously-included directories found matching '.git*' 22:06:18 INFO - Installing collected packages: jsonschema, functools32 22:06:18 INFO - Running setup.py install for functools32 22:06:18 INFO - warning: no files found matching '*.txt' 22:06:18 INFO - no previously-included directories found matching 'build' 22:06:18 INFO - no previously-included directories found matching 'dist' 22:06:18 INFO - no previously-included directories found matching '.git*' 22:06:18 INFO - Successfully installed jsonschema functools32 22:06:18 INFO - Cleaning up... 22:06:18 INFO - Return code: 0 22:06:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:18 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 0x7ffc2ab3af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x148f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151e9c0>, '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 0x151f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151fd50>, '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-1464066252.605930-175528650', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:06:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:06:18 INFO - Using env: {'DISPLAY': ':0', 22:06:18 INFO - 'HOME': '/home/cltbld', 22:06:18 INFO - 'LANG': 'en_US.UTF-8', 22:06:18 INFO - 'LANGUAGE': 'en_US:en', 22:06:18 INFO - 'LOGNAME': 'cltbld', 22:06:18 INFO - 'MAIL': '/var/mail/cltbld', 22:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:18 INFO - 'MOZ_NO_REMOTE': '1', 22:06:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:18 INFO - 'NO_EM_RESTART': '1', 22:06:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:18 INFO - 'PWD': '/builds/slave/test', 22:06:18 INFO - 'SHELL': '/bin/bash', 22:06:18 INFO - 'SHLVL': '1', 22:06:18 INFO - 'TERM': 'linux', 22:06:18 INFO - 'TMOUT': '86400', 22:06:18 INFO - 'USER': 'cltbld', 22:06:18 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1464066252.605930-175528650', 22:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:18 INFO - '_': '/tools/buildbot/bin/python'} 22:06:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:18 INFO - Downloading/unpacking flake8 22:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:06:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:06:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:06:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:06:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:06:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:06:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:06:21 INFO - no previously-included directories found matching 'docs/_build' 22:06:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:06:21 INFO - Running setup.py install for pep8 22:06:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:06:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:06:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:06:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:06:21 INFO - no previously-included directories found matching 'docs/_build' 22:06:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:06:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:06:21 INFO - Cleaning up... 22:06:21 INFO - Return code: 0 22:06:21 INFO - Running post-action listener: _resource_record_post_action 22:06:21 INFO - Running post-action listener: _start_resource_monitoring 22:06:21 INFO - Starting resource monitoring. 22:06:21 INFO - ##### 22:06:21 INFO - ##### Finished create-virtualenv step (success) 22:06:21 INFO - ##### 22:06:21 INFO - ##### 22:06:21 INFO - ##### Running install step. 22:06:21 INFO - ##### 22:06:21 INFO - Running pre-action listener: _resource_record_pre_action 22:06:21 INFO - Running main action method: install 22:06:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:21 INFO - Reading from file tmpfile_stdout 22:06:21 INFO - Detecting whether we're running mozinstall >=1.0... 22:06:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:06:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:06:21 INFO - Reading from file tmpfile_stdout 22:06:21 INFO - Output received: 22:06:21 INFO - Usage: mozinstall [options] installer 22:06:21 INFO - Options: 22:06:21 INFO - -h, --help show this help message and exit 22:06:21 INFO - -d DEST, --destination=DEST 22:06:21 INFO - Directory to install application into. [default: 22:06:21 INFO - "/builds/slave/test"] 22:06:21 INFO - --app=APP Application being installed. [default: firefox] 22:06:21 INFO - mkdir: /builds/slave/test/build/application 22:06:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:06:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:06:38 INFO - Reading from file tmpfile_stdout 22:06:38 INFO - Output received: 22:06:38 INFO - /builds/slave/test/build/application/firefox/firefox 22:06:38 INFO - Running post-action listener: _resource_record_post_action 22:06:38 INFO - ##### 22:06:38 INFO - ##### Finished install step (success) 22:06:38 INFO - ##### 22:06:38 INFO - ##### 22:06:38 INFO - ##### Running run-tests step. 22:06:38 INFO - ##### 22:06:38 INFO - Running pre-action listener: _resource_record_pre_action 22:06:38 INFO - Running main action method: run_tests 22:06:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:06:38 INFO - Python 2.7.3 22:06:38 INFO - Return code: 0 22:06:38 INFO - grabbing minidump binary from tooltool 22:06:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:38 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 0x151e9c0>, '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 0x151f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151fd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:06:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:06:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:06:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:06:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:06:38 INFO - Return code: 0 22:06:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:06:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:06:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:06:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:06:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:06:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:06:39 INFO - Return code: 0 22:06:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.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 22:06:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.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 22:06:39 INFO - Using env: {'DISPLAY': ':0', 22:06:39 INFO - 'HOME': '/home/cltbld', 22:06:39 INFO - 'LANG': 'en_US.UTF-8', 22:06:39 INFO - 'LANGUAGE': 'en_US:en', 22:06:39 INFO - 'LOGNAME': 'cltbld', 22:06:39 INFO - 'MAIL': '/var/mail/cltbld', 22:06:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:06:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:39 INFO - 'MOZ_NO_REMOTE': '1', 22:06:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:39 INFO - 'NO_EM_RESTART': '1', 22:06:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:39 INFO - 'PWD': '/builds/slave/test', 22:06:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:06:39 INFO - 'SHELL': '/bin/bash', 22:06:39 INFO - 'SHLVL': '1', 22:06:39 INFO - 'TERM': 'linux', 22:06:39 INFO - 'TMOUT': '86400', 22:06:39 INFO - 'USER': 'cltbld', 22:06:39 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1464066252.605930-175528650', 22:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:39 INFO - '_': '/tools/buildbot/bin/python'} 22:06:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.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 22:06:39 INFO - mozversion application_buildid: 20160523193033 22:06:39 INFO - mozversion application_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 22:06:39 INFO - mozversion application_display_name: Nightly 22:06:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:06:39 INFO - mozversion application_name: Firefox 22:06:39 INFO - mozversion application_remotingname: firefox 22:06:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:06:39 INFO - mozversion application_vendor: Mozilla 22:06:39 INFO - mozversion application_version: 49.0a1 22:06:39 INFO - mozversion platform_buildid: 20160523193033 22:06:39 INFO - mozversion platform_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 22:06:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:06:39 INFO - mozversion platform_version: 49.0a1 22:06:39 INFO - using testdate: 1464066399 22:06:39 INFO - actual date: 1464066399 22:06:39 INFO - starting webserver on 'localhost:50288' 22:06:39 INFO - SUITE-START | Running 2 tests 22:06:39 INFO - TEST-START | dromaeo_css 22:06:39 INFO - operating with platform_type : linux_ 22:06:39 INFO - Initialising browser for dromaeo_css test... 22:06:39 INFO - TEST-INFO | started process 5389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoRHE7t/profile http://localhost:50288/getInfo.html) 22:06:43 INFO - PROCESS | 5389 | __metrics Screen width/height:1600/1200 22:06:43 INFO - PROCESS | 5389 | colorDepth:24 22:06:43 INFO - PROCESS | 5389 | Browser inner width/height: 1024/697 22:06:43 INFO - PROCESS | 5389 | __metrics 22:06:45 ERROR - PROCESS | 5389 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:06:46 INFO - TEST-INFO | 5389: exit 0 22:06:46 INFO - Browser initialized. 22:06:46 INFO - Running cycle 1/1 for dromaeo_css test... 22:06:46 INFO - TEST-INFO | started process 5473 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoRHE7t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:06:57 INFO - PROCESS | 5473 | RSS: Main: 152485888 22:06:57 INFO - PROCESS | 5473 | 22:07:49 INFO - PROCESS | 5473 | 1464066469018 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:09:56 INFO - PROCESS | 5473 | Cycle 1(1): loaded http://localhost:50288/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50288/tests/dromaeo/cssquery-ext.html) 22:09:57 INFO - PROCESS | 5473 | RSS: Main: 239763456 22:09:57 INFO - PROCESS | 5473 | 22:13:00 INFO - PROCESS | 5473 | Cycle 1(1): loaded http://localhost:50288/tests/dromaeo/cssquery-ext.html (next: http://localhost:50288/tests/dromaeo/cssquery-jquery.html) 22:13:00 INFO - PROCESS | 5473 | RSS: Main: 241131520 22:13:00 INFO - PROCESS | 5473 | 22:16:02 INFO - PROCESS | 5473 | Cycle 1(1): loaded http://localhost:50288/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50288/tests/dromaeo/cssquery-mootools.html) 22:16:03 INFO - PROCESS | 5473 | RSS: Main: 236892160 22:16:03 INFO - PROCESS | 5473 | 22:19:05 INFO - PROCESS | 5473 | Cycle 1(1): loaded http://localhost:50288/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50288/tests/dromaeo/cssquery-prototype.html) 22:19:06 INFO - PROCESS | 5473 | RSS: Main: 238440448 22:19:06 INFO - PROCESS | 5473 | 22:22:08 INFO - PROCESS | 5473 | Cycle 1(1): loaded http://localhost:50288/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50288/tests/dromaeo/cssquery-yui.html) 22:22:09 INFO - PROCESS | 5473 | RSS: Main: 217849856 22:22:09 INFO - PROCESS | 5473 | 22:25:11 INFO - PROCESS | 5473 | Cycle 1(1): loaded http://localhost:50288/tests/dromaeo/cssquery-yui.html (next: http://localhost:50288/tests/dromaeo/cssquery-dojo.html) 22:25:11 INFO - PROCESS | 5473 | RSS: Main: 277901312 22:25:11 INFO - PROCESS | 5473 | 22:25:11 INFO - PROCESS | 5473 | __start_tp_report 22:25:11 INFO - PROCESS | 5473 | _x_x_mozilla_page_load 22:25:11 INFO - PROCESS | 5473 | _x_x_mozilla_page_load_details 22:25:11 INFO - PROCESS | 5473 | |i|pagename|runs| 22:25:11 INFO - PROCESS | 5473 | |0;dojo.html;3100.2249437640576;3121.360121175159;3144.2296637323852;3155.5266709993484;3182.6658200888905;5151.665141765792;5221.295125158103;5367.973160134199;5469.425710300421;5517.144842549406;5212.478752124795;5233.267342572043;5300.0459917214885;5317.149256119022;5354.22363757255;4714.104320179172;4759.690620109682;4794.808207671689;4809.711417314955;4943.530364615356;4898.681585696936;4964.87587810304;4973.825916092937;4980.526849949267;4986.626003049768;4657.88355291117;4707.24684050552;4834.419869615634;4882.682625629341;4906.901861962777;4858.368412106439;4872.171730805771;4881.511848815126;4968.627352948543;5010.173321401964;4906.779194936236;4932.90134197314;4941.629377796644;4949.90100197994;4956.900861982776;5040.34475518184;5172.870678233036;5192.506711862367;5328.6802791832615;5362.65142765719;5738.282714660668;5740.397258287903;5785.27684039495;5787.102999035156;5788.392218817005;5782.508486778629;5825.097109947965;5846.883062338729;5855.941440585582;5856.443637854397;5189.117849965515;5238.528532432099;5297.682139071668;5307.548858347083;5454.427285135043;5852.385499522489;5897.026990546512;5901.616394934315;5903.675297858659;5914;5588.720563971785;5616.85957851057;5621.381648018714;5651.2935883014625;5651.6043876928225;5536.474034966672;5570.08093664549;5593.720313984366;5624.043912534797;5646.632968857011;5652.349979752299;5656.519195868397;5658.179563963202;5659.037963546207;5668.234788303526;5490.533304669068;5605;5617.719114044281;5618.803341883015;5624.831255062355;4300.6129448349175;4328.48058233014;4331.4152589400655;4340.848070317524;4342.0664557119735;3864.671502922227;3871.4386413969814;3877.418387241936;3893.53277606689;3912.8043597819997;5207.635465517377;5246.580273578104;5407.972960135174;5426.403095659474;5484;5647.040003199466;5703.800366987219;5708.571857110734;5708.743106560215;5742.397048309951;5441.5102640762525;5469.453054694499;5470.671759694431;5493.945060549423;5503.532199762985;4732.574068333797;4963.776630051656;4965.627577931669;4977.32806071183;5015;6010.188624535719;6115.453310713017;6117.827404800917;6118.131909153758;6122.12371750169;5862.149988251765;5868.383819699041;5898.292204935436;5904;5905;5603.943960560342;5801.274840644919;5813.273340832396;6085.57400981927;6103.389661033861;5546.722663866871;5593.356763972195;5596.5242954347705;5600.691961942132;5636.2954630671165;5672.546196304368;5688.5733569981585;5711.828645140652;5716.657000579979;5717.028105222039;5395.838124856261;5495.697736624524;5498.615096943176;5535.8892822144135;5587.0502014656795;5568.888622227614;5572.944270557243;5576.079946808731;5580.1629755537;5587.161925711013;5420.647657902224;5458.6724796512335;5481.804241128006;5486.972565137149;5535.5848311376;5382.919256211082;5581.386047534686;5621.213030175861;5652.265205523256;5767.567432442667;5592.133219351007;5604.775808967595;5605.215269862304;5616.494515493625;5649.12438572022 22:25:11 INFO - PROCESS | 5473 | |1;ext.html;599.2090440618383;633.0725487161308;634.1692382978297;645.5803727577078;651.2217899609966;31852;32551.348973020555;43413.56586434135;44224.11551768963;44260.77869610656;42525.7873710631;46503.30245046327;46641.53358466415;46659.53340466594;46741.7662911685;8612.8708069379;8743.213110820025;8790.384673072887;8797.956010219972;8802.339824513187;8697.695580654678;8828.602712877935;8836.8674469883;8842.690505832297;8844.204021638052;8814.911850881474;8887.600057997406;8891.199792018717;8892.510911899843;8896.777580560472;8820.735377938672;8834.999999999936;8864;8872.512011839346;8874.29005679544;8702.651893924236;8762.956185219096;8776;8778.64885404583;8786.64853405863;42923.35614965778;43479;43483.78258108721;43516;43533.56466435359;3589.46158076288;3594.784312941206;3695;3709.4250391189407;3720.21875406165;30281.848590757127;33162.66837331644;33163.50254746181;33355.33289334224;35386;4272.978635106804;4322.935155972663;4360.433143691332;4366.934495982563;4503;340509;343911.2435502577;345367;345861.27069364744;348824;57593.71203143957;63942.68028659827;64928.35071649317;65549.34450655528;65823.67088164529;67232;67332;67460;67501;67888.6605566969;17478.388256410966;17511.562210944572;17684.381046663304;17690.203940822437;17726;11960.461779219955;12590.748185036247;13142.277174755289;13412.798808017887;13425;21011.8949405252;21038.368848934555;21205.681914771292;21357.67963480549;21391.46521336979;7725.575093369806;7755.806104847424;7774.416918731118;7917.683292668229;8094.826250193807;1026.5072765072807;1057.6509751781894;1096.9012788849043;1140.960066397672;1254.1221145198397;33670.326593467995;33750.66249337525;33815.154621134665;33909.660903391145;34152.06763844929;1163.3950345820126;1169.2984209474248;1185.4309931233056;1198.4606926882764;1237.1525505029083;17529.386471473437;17714.114294285235;17771.289148434178;17841.46475605734;17841.643167136586;503073;503612;506698;507390;509268;58799.70600146972;60466.69766651138;60670;60750.08874866881;60846.69576652177;1284.89720822334;1372.3207012528862;1379.717157982611;1398.853120422341;1424.4587056918306;31715.938463999766;34459.84750965462;34476.655233447345;34684.65315346814;34814.82592587021;52072.73963630158;52237;52504.474955249956;52586;52762;391006;391451;392113;392475;392714;28428;38262;43751.34372984472;44612.10775784401;44639.330410044524;46421;47015.29477057915;49828.75085624549;50870.745646271534;51095.744521277156;40648.39027414532;42990.78504607582;43191;45549.54450455453;45736.771316143204;42251;42544.57455425406;43113;43121;43442.34836477392;42364.78817605892;43217.567824321355;43326;43451.34822977595;45491.9057284846;35511.644883550834;38353.23293534169;38774.4183837237;39481.80259098686;39516.40725389064;28881.42237155287;29030.27424314409;29071.273218169714;29202;29428.26429339198 22:25:11 INFO - PROCESS | 5473 | |2;jquery.html;386.7776028783449;395.40886374869575;395.63403851437414;401.5863660429757;413.1262380066161;14328.211948342838;17881.731774023367;17913.910430447846;19256.90371548142;19275.13261903214;16022.839771602281;16971.74542381865;18366.81633183668;19709.60580788387;19710.605787884233;5499.587530935176;5662.886742265152;5684.2326285951485;5687.4312568743;5690.260266165393;4065.1869626074904;5489.752961116759;5661;5992.430719081702;6265.9686701566425;6306.180196574439;6314.052892066181;6329.8417539561415;6338.366163383648;6341.904871426932;6182.659953702567;6259.874802503947;6322.810315690536;6323.05154226865;6339.04914262862;5349.43830897754;5560.165975103726;5569.387367389584;5569.387367389624;5586.944130558683;21598.352049438385;21858;23096.7296798676;23350.18274360389;23480.76519234803;2352.5294941011693;2395.8802059896934;2397.8081753459683;2422.0917156066475;2918.9124326270244;9614.807703845954;10256.743581410525;13946.93026534871;14135.646608834759;14592.124472531683;1828.5794267318458;1846.649136664043;1901.1729897494633;1921.3947606503907;1962.4112766170092;78067;80569.99999999942;81811.5909420455;82796;83341.58329208376;10713.303635263683;11020;11049.28179668319;11179.4969226385;11182.440877956069;13882.167069975834;14386.640333991525;15714.13572253537;15833.129177895096;15847.445339413065;8174.836503269901;8369.455985360932;8391.874121888177;8538.359623028298;8617.138286171457;8198.221168988937;8261.421700480909;8291.58542072906;8387.53218186815;8439.198276163756;4702.623790096784;4713.670043097019;4773.64197685175;4806.279058141236;4819.49395313487;1848.8058753830924;1950.444123424817;1980.5939782344585;1988.3935399702941;2028.665270230425;1011.1556850030161;1036.0520124086377;1047.7223535763028;1080.9783804324027;1118.981726628764;18749.81250187508;19083.427497175107;19135.80864191368;19397.076128363173;19441.22235110608;1004.3873237325225;1007.6422869881234;1033.2405681823852;1067.3222503710044;1075.6988043347876;10621.097206737359;10793.78412431747;10816.945915270373;10850.348979061282;10857.565697372016;473703;480289;489343;490846;495353;92922;95735;95818.52090739501;96146;97166.51416743013;1004.7538353103537;1006.758377989292;1006.9779174138256;1052.3633201912885;1054.267284237463;16936.814422990767;17040.403585874436;17118.743218851978;17246.310147593955;17451.47645570635;39346.803265983486;39586.40620390754;39946;40778.388324174564;40872;53012;53476.19785703295;56284;58265.417345826;60220;12777.23336599807;13418.329083545588;13425.194488330731;13475.32623368808;13529.458821647024;14102.224377659328;14257.50098746539;14868.925655371653;14962.40150393972;15022.173780442181;13042.739145216852;13149.605511834277;13250.536231232247;13258.668533286746;13382.13016153947;12714.237145771283;12857.807132893202;12932;13102.820746132513;13255.625099033312;12841.871581284067;12970.740585188432;12997.870021300045;13067.477300907858;13112.73774524485;13153.145045572008;13219.47122115143;13265.734685306434;13427.462901484221;13453.394597242952;12799.232046077264;12836.679083022998;12845.486180552673;13206;13220.40508177115 22:25:11 INFO - PROCESS | 5473 | |3;mootools.html;1250.306080125531;1517.8178618565773;1539.5073576455536;1682.9831701682988;1849.1863580024797;8623.784405389868;8953.462792232462;9001.369904106707;9232.769180770467;9247.445153290808;8980.236679882219;9175.128362805523;9183.127602877732;9184.770380740485;9185.173334399902;2059.2895451069357;2288.7596802335765;2358.646203069536;2412.155745489082;2449.865257410842;2454.3005243505604;2474.5669507836146;2488.042103790036;2489.626556016594;2490.8915532588007;2336.5093330400637;2349.659299401594;2355.9293221203393;2359.3865594945264;2359.9646005309846;2051.1282704850455;2319.118734880743;2391.354334329739;2393.3537944754994;2405.9037638494437;2331.988340058289;2392.569337519246;2398.8200884933526;2403.327068420845;2406.4344878953434;9091.545422728837;9095.909040909572;9102.271818254523;9119.133682300171;9136;5308.973455132738;5457.863553411157;5502.064649009678;5503.009458297505;5505.146702261156;3447.276072024878;3458.204612939013;3509.438489841639;3579.266250418658;3582.0328511302064;1074.897884700952;1087.1302957633861;1108.3737688206218;1111.3276467737064;1132.9943350283195;12597.685057873536;12707.046971477177;12765.361731913366;12768.297743624096;12780.744385112339;4597.402337696078;4683.929741053887;4799.544043315879;4814.037192561432;4825.324454576362;10040.146587539995;10061.39631622105;10063.641408409918;10065.69802905914;10076.848847267296;4757.3101900224965;5457.372402173722;5457.372402173801;5471.6169868108855;5476.780928762884;4729.243321068612;4828.686135401188;4894.388201474816;4905.386826646669;4921.5078492151215;3901.785401802928;3987.8404863805704;4031.0728532437115;4063.309237429644;4072.205919845602;1188.2870277833404;1190.8451901252781;1195.4321697193764;1204.030755242021;1204.0969273045216;873.6374404622138;913.4108500017452;947.9146876781123;963.4460185393427;1007.9748006299754;5977.731002104829;5978.103284507359;6031.487323577458;6198.473129783929;6222;911.252772726358;912.0833562270009;942.34036174678;949.9145076943108;995.8406654935176;4030.133521292936;4158.272302347077;4169.249535083714;4191.392248124005;4259.765712885821;10822;11215.327080375204;11294.322340659586;11377.658670239905;11384.886151138548;11164.609238676607;11246.43767811606;11252.66242012741;11276.605318813967;11280.605178818701;8187.631556579968;8252.133525979805;8310.376721745894;8332.208440198172;8492.108328625529;5985.4912332450485;6031.065184896348;6098.054801505774;6145.84635384119;6212.720427580679;10446.895531044593;10752.677419677422;10763.730906727395;10768.730781730519;10769.676909692722;10388.636397726043;10682.62610808618;10792.892071079188;10920.781584368428;10951.83572246433;7324.08448943882;7888.290053895177;8098.716544921135;8101.918980810352;8227.670893164446;8835.64657413722;8857.557122143997;8893.955530222307;8937.687180948895;8950.776230594287;7841.294283514511;8023.4784738991775;8070.596470176585;8140.837183256183;8212.958935205286;7368.078990126234;7692.307692307719;7734.961325193338;7844.294013538581;8078.555679437452;7187.9640601796655;7345.412367010733;7439.107307123179;7445.4415918805225;7446.813829654086;5120.129577971828;5176.482351764794;5264.868378290574;5273.31446911899;5289.2330612061805;5034.798608055637;5128.666636668754;5134.691918484903;5195.350581177353;5246.213068039825 22:25:11 INFO - PROCESS | 5473 | |4;prototype.html;318.33785725690564;329.78399148557696;333.58635292237625;333.74468531573336;336.05065689427346;5275;5300.602454815889;5313.8937221255555;5328.520433161015;5386.622936394454;5248.664344366749;5255.160693757183;5259.658122222062;5266.947330526694;5299.364076310848;3966.186931679006;3991.5210174779068;4151.522574903879;4172.812223449952;4178.540360560336;4107.589241075883;4156.817670722864;4163.126593033147;4164.167166566684;4181.519125300584;3948.881533553998;4039.4344791729036;4106.753594784308;4118.670506359484;4149.273877071516;4005.239004589119;4109.9108736184935;4123.587641235882;4140.420341152241;4151.584841515854;3612.1330880588735;3628.7118073084202;3640.9635903640888;3643.034595832107;3648.215633638754;5076.847694569169;5087.465816089294;5091.312672789163;5124.4106927703415;5153.665011774223;4542.568455996675;4666.300054991744;4671.906561868744;4679.181143299909;4723.622110231173;5406.243125962368;5459.262999495057;5484.561235101222;5497.642653227567;5538.252335934638;4011.2579172853116;4031.9193616127804;4037.9097643636346;4038.454808600831;4077.2864748668862;5714.228579141846;5739.253896993406;5743.798967036176;5774.913376299359;5786.321966629673;4041.939370909439;4045.615666511677;4095.5904409558807;4101.031504877676;4112.629863312316;4149.195100131453;4166.541680415152;4179.9791001045405;4182.53992060873;4192.622663960197;4381.233284175257;4655.464621568495;4682.929756053662;4713.34013238147;4759.309900064541;5455.263539422207;5497.615166938276;5509.319657504419;5531.529819965268;5553.77848860459;1397.5667543061684;1413.971720565583;1415.2852809331223;1421.6730152064877;1421.722764060998;890.8930928288518;900.3202582050641;910.5538286239695;911.8358695434753;944.5560586524323;5313.840584782462;5333.906549157491;5335.626506144533;5341.172118321666;5343.145096784496;5614.69119198448;5640.32316122068;5645.407232240637;5651.519620832275;5658.151277308437;5132.538071573576;5326.070661136359;5328.813491527778;5355.892882142335;5438.456154384609;3026.243439140215;3046.192758918888;3050.8627111780024;3051.832149231769;3052.9389412212076;5951.018082016505;5952.166696662471;5955.642661440327;5985.820425387245;6006.339302676702;5544.833654990357;5777.971110144423;5790.699301398163;5815.156802263648;5821.2141360916585;5862.325832529228;5936.1392598073035;5944.7027648617395;5987.6108052976415;6020.608660437058;5656.377798442086;5666.405027472056;5773.97113014449;5841.561882858718;5851.356350801323;5553.888922221614;5590.86022849432;5610.663360198401;5662.66024038559;5676.318841739017;5860.326062502696;5878.412158784087;5882.470577648032;5895.174675545341;5926.585139040226;3909.980450097731;4049.5826460738504;4064.7357921734992;4069.8575549855614;4074.490688663917;5680.715964201856;5691.044723916838;5695.515881149983;5700.344460386943;5744.655320680772;5371.543418809289;5386.5152136307925;5521.171824226398;5536.418676039119;5596.832095037155;5476.233327334096;5507.614466987273;5523.751431185526;5564.55483561306;5570.693611851387;5146.76839542229;5505.174223866292;5505.256790333254;5526.889462210814;5548.55611551083;5552.500274975271;5560.054790685513;5560.777568897199;5571.721413929206;5580.693061881636;5460.535854452416;5479;5484.232207490874;5494.450554944633;5522.282103326542 22:25:11 INFO - PROCESS | 5473 | |5;yui.html;807.2411932783184;832.3923535818848;853.2065179383173;857.9914200857993;862.5299211929502;14162.858371416283;14670;14830.110193388377;14847.925760371183;14948.177850218233;12795.296258705768;12856.10007299487;12859.357032148402;12859.549915752927;12897.935510322459;668.8862893308125;807.4347956430493;815.4414226255034;818.7748369198488;824.2005254902765;815.3436483630704;817.7424111404919;818.3780326951501;820.1880138662741;822.391430341546;712.8324843661735;865.8354912566592;866.6706651472432;867.0679020053436;868.3617541107282;825.830704705537;838.2413915406536;841.6423020216414;847.1189962439056;848.8514509960733;621.8227805075531;622.6295354264206;623.183629445428;623.2209737827716;623.8190924631851;13656.522021729292;13836.23900685472;13838.584842454742;13871.861281387159;13903.582892513241;12584.4337004835;12714.74570508594;12835.87164128356;12922;13313.93488520916;11218.775624487545;11436.153095929996;11612.36132012739;11674.533018679243;12102.273863568125;4299.720518166309;4300.526942036374;4318.481782186126;4457.465104187519;4500.819967201308;56686;56929.430705693245;57460.42539574592;59558.404415955716;59706.40293597095;10576.153907687367;10662.680119596425;10760.94619526913;10869.945650271698;10926.617568385069;20583.38249852507;20669.58660826775;20670.79329206719;20726.378208653765;20726.481837954172;7202.747903823341;7306.305900939402;7331.9266807332315;7340.522866013692;7641.2740789624895;4682.227432473604;4682.274247491576;4730.59789917854;4781.378420805273;4794.7362895040405;3362.159460134966;3454.274602333488;3454.5836207154944;3459.41189997701;3464.5842498900292;609.5032548472981;615.5137441421227;618.2951435363689;620.996895015522;635.9554831161774;557.3089369182185;576.1559315602663;604.7550741949517;650.1547987616175;660.5805313625787;8988.685396011108;9112;9209.17117459432;9237.214836738818;9395.765105872408;634.1565717595587;706.717313074764;726.9055022847102;747.439420434674;748.0948052856105;4192.517860446027;4195.16096780639;4196.4099041246955;4217.957820421757;4313.981817101472;64915.675421623535;66458;67397.3260267391;67516;67547.66226168838;57676.25104368202;58127.418725812204;59559;59562.702186488794;59914.70042649759;51037;51709.7414512925;53357.46642533525;54941;55032.17451738227;8307.376946728898;8369.83260334802;8480.06719260893;8528.786780330542;8607.655693772429;35742.64257357393;36006.45990310095;36359.636403635624;36667.44998825073;37535.81232093822;43888.34167487426;47969.280460793816;47982.520174797806;48107.27839082346;48465.27302090542;10514.894851051391;10545.894541054799;10698.14414846826;10709.629909741216;11004.669859904216;11102.944485277521;11309.434528273718;11352.489137988976;11443.771124577295;11642.476088575864;10948;11112.722181945193;11133.88866111361;11152.776944461228;11293;11079.778404431705;11436.656900293005;11516.769664606829;11537.88462115368;11847.628098519346;11338.376389298668;11344;11379.886201137882;11720.41397930083;11773.470193841127;8680.392372534114;8934.374593778633;9025.142611451902;9058.50178240203;9318.62725491;8564.957175214084;8628.956855215683;8709.433886797338;9308.860367094363;9437.764055898382 22:25:11 INFO - PROCESS | 5473 | __end_tp_report 22:25:11 INFO - PROCESS | 5473 | __start_cc_report 22:25:11 INFO - PROCESS | 5473 | _x_x_mozilla_cycle_collect,996 22:25:11 INFO - PROCESS | 5473 | __end_cc_report 22:25:11 INFO - PROCESS | 5473 | __startTimestamp1464067511860__endTimestamp 22:25:11 INFO - PROCESS | 5473 | 22:25:11 INFO - PROCESS | 5473 | ------- Summary: start ------- 22:25:11 INFO - PROCESS | 5473 | Number of tests: 6 22:25:11 INFO - PROCESS | 5473 | 22:25:11 INFO - PROCESS | 5473 | [#0] dojo.html Cycles:165 Average:5311.51 Median:5503.53 stddev:608.87 (11.1%) stddev-sans-first:585.50 22:25:11 INFO - PROCESS | 5473 | Values: 3100.2 3121.4 3144.2 3155.5 3182.7 5151.7 5221.3 5368.0 5469.4 5517.1 5212.5 5233.3 5300.0 5317.1 5354.2 4714.1 4759.7 4794.8 4809.7 4943.5 4898.7 4964.9 4973.8 4980.5 4986.6 4657.9 4707.2 4834.4 4882.7 4906.9 4858.4 4872.2 4881.5 4968.6 5010.2 4906.8 4932.9 4941.6 4949.9 4956.9 5040.3 5172.9 5192.5 5328.7 5362.7 5738.3 5740.4 5785.3 5787.1 5788.4 5782.5 5825.1 5846.9 5855.9 5856.4 5189.1 5238.5 5297.7 5307.5 5454.4 5852.4 5897.0 5901.6 5903.7 5914.0 5588.7 5616.9 5621.4 5651.3 5651.6 5536.5 5570.1 5593.7 5624.0 5646.6 5652.3 5656.5 5658.2 5659.0 5668.2 5490.5 5605.0 5617.7 5618.8 5624.8 4300.6 4328.5 4331.4 4340.8 4342.1 3864.7 3871.4 3877.4 3893.5 3912.8 5207.6 5246.6 5408.0 5426.4 5484.0 5647.0 5703.8 5708.6 5708.7 5742.4 5441.5 5469.5 5470.7 5493.9 5503.5 4732.6 4963.8 4965.6 4977.3 5015.0 6010.2 6115.5 6117.8 6118.1 6122.1 5862.1 5868.4 5898.3 5904.0 5905.0 5603.9 5801.3 5813.3 6085.6 6103.4 5546.7 5593.4 5596.5 5600.7 5636.3 5672.5 5688.6 5711.8 5716.7 5717.0 5395.8 5495.7 5498.6 5535.9 5587.1 5568.9 5572.9 5576.1 5580.2 5587.2 5420.6 5458.7 5481.8 5487.0 5535.6 5382.9 5581.4 5621.2 5652.3 5767.6 5592.1 5604.8 5605.2 5616.5 5649.1 22:25:11 INFO - PROCESS | 5473 | 22:25:11 INFO - PROCESS | 5473 | [#1] ext.html Cycles:180 Average:59341.54 Median:23770.25 stddev:110806.36 (466.2%) stddev-sans-first:111029.43 22:25:11 INFO - PROCESS | 5473 | Values: 599.2 633.1 634.2 645.6 651.2 31852.0 32551.3 43413.6 44224.1 44260.8 42525.8 46503.3 46641.5 46659.5 46741.8 8612.9 8743.2 8790.4 8798.0 8802.3 8697.7 8828.6 8836.9 8842.7 8844.2 8814.9 8887.6 8891.2 8892.5 8896.8 8820.7 8835.0 8864.0 8872.5 8874.3 8702.7 8763.0 8776.0 8778.6 8786.6 42923.4 43479.0 43483.8 43516.0 43533.6 3589.5 3594.8 3695.0 3709.4 3720.2 30281.8 33162.7 33163.5 33355.3 35386.0 4273.0 4322.9 4360.4 4366.9 4503.0 340509.0 343911.2 345367.0 345861.3 348824.0 57593.7 63942.7 64928.4 65549.3 65823.7 67232.0 67332.0 67460.0 67501.0 67888.7 17478.4 17511.6 17684.4 17690.2 17726.0 11960.5 12590.7 13142.3 13412.8 13425.0 21011.9 21038.4 21205.7 21357.7 21391.5 7725.6 7755.8 7774.4 7917.7 8094.8 1026.5 1057.7 1096.9 1141.0 1254.1 33670.3 33750.7 33815.2 33909.7 34152.1 1163.4 1169.3 1185.4 1198.5 1237.2 17529.4 17714.1 17771.3 17841.5 17841.6 503073.0 503612.0 506698.0 507390.0 509268.0 58799.7 60466.7 60670.0 60750.1 60846.7 1284.9 1372.3 1379.7 1398.9 1424.5 31715.9 34459.8 34476.7 34684.7 34814.8 52072.7 52237.0 52504.5 52586.0 52762.0 391006.0 391451.0 392113.0 392475.0 392714.0 28428.0 38262.0 43751.3 44612.1 44639.3 46421.0 47015.3 49828.8 50870.7 51095.7 40648.4 42990.8 43191.0 45549.5 45736.8 42251.0 42544.6 43113.0 43121.0 43442.3 42364.8 43217.6 43326.0 43451.3 45491.9 35511.6 38353.2 38774.4 39481.8 39516.4 28881.4 29030.3 29071.3 29202.0 29428.3 22:25:11 INFO - PROCESS | 5473 | 22:25:11 INFO - PROCESS | 5473 | [#2] jquery.html Cycles:180 Average:29376.87 Median:19419.15 stddev:80098.69 (412.5%) stddev-sans-first:80293.81 22:25:11 INFO - PROCESS | 5473 | Values: 386.8 395.4 395.6 401.6 413.1 14328.2 17881.7 17913.9 19256.9 19275.1 16022.8 16971.7 18366.8 19709.6 19710.6 5499.6 5662.9 5684.2 5687.4 5690.3 4065.2 5489.8 5661.0 5992.4 6266.0 6306.2 6314.1 6329.8 6338.4 6341.9 6182.7 6259.9 6322.8 6323.1 6339.0 5349.4 5560.2 5569.4 5569.4 5586.9 21598.4 21858.0 23096.7 23350.2 23480.8 2352.5 2395.9 2397.8 2422.1 2918.9 9614.8 10256.7 13946.9 14135.6 14592.1 1828.6 1846.6 1901.2 1921.4 1962.4 78067.0 80570.0 81811.6 82796.0 83341.6 10713.3 11020.0 11049.3 11179.5 11182.4 13882.2 14386.6 15714.1 15833.1 15847.4 8174.8 8369.5 8391.9 8538.4 8617.1 8198.2 8261.4 8291.6 8387.5 8439.2 4702.6 4713.7 4773.6 4806.3 4819.5 1848.8 1950.4 1980.6 1988.4 2028.7 1011.2 1036.1 1047.7 1081.0 1119.0 18749.8 19083.4 19135.8 19397.1 19441.2 1004.4 1007.6 1033.2 1067.3 1075.7 10621.1 10793.8 10816.9 10850.3 10857.6 473703.0 480289.0 489343.0 490846.0 495353.0 92922.0 95735.0 95818.5 96146.0 97166.5 1004.8 1006.8 1007.0 1052.4 1054.3 16936.8 17040.4 17118.7 17246.3 17451.5 39346.8 39586.4 39946.0 40778.4 40872.0 53012.0 53476.2 56284.0 58265.4 60220.0 12777.2 13418.3 13425.2 13475.3 13529.5 14102.2 14257.5 14868.9 14962.4 15022.2 13042.7 13149.6 13250.5 13258.7 13382.1 12714.2 12857.8 12932.0 13102.8 13255.6 12841.9 12970.7 12997.9 13067.5 13112.7 13153.1 13219.5 13265.7 13427.5 13453.4 12799.2 12836.7 12845.5 13206.0 13220.4 22:25:11 INFO - PROCESS | 5473 | 22:25:11 INFO - PROCESS | 5473 | [#3] mootools.html Cycles:180 Average:5957.86 Median:4806.79 stddev:3421.59 (71.2%) stddev-sans-first:3412.89 22:25:11 INFO - PROCESS | 5473 | Values: 1250.3 1517.8 1539.5 1683.0 1849.2 8623.8 8953.5 9001.4 9232.8 9247.4 8980.2 9175.1 9183.1 9184.8 9185.2 2059.3 2288.8 2358.6 2412.2 2449.9 2454.3 2474.6 2488.0 2489.6 2490.9 2336.5 2349.7 2355.9 2359.4 2360.0 2051.1 2319.1 2391.4 2393.4 2405.9 2332.0 2392.6 2398.8 2403.3 2406.4 9091.5 9095.9 9102.3 9119.1 9136.0 5309.0 5457.9 5502.1 5503.0 5505.1 3447.3 3458.2 3509.4 3579.3 3582.0 1074.9 1087.1 1108.4 1111.3 1133.0 12597.7 12707.0 12765.4 12768.3 12780.7 4597.4 4683.9 4799.5 4814.0 4825.3 10040.1 10061.4 10063.6 10065.7 10076.8 4757.3 5457.4 5457.4 5471.6 5476.8 4729.2 4828.7 4894.4 4905.4 4921.5 3901.8 3987.8 4031.1 4063.3 4072.2 1188.3 1190.8 1195.4 1204.0 1204.1 873.6 913.4 947.9 963.4 1008.0 5977.7 5978.1 6031.5 6198.5 6222.0 911.3 912.1 942.3 949.9 995.8 4030.1 4158.3 4169.2 4191.4 4259.8 10822.0 11215.3 11294.3 11377.7 11384.9 11164.6 11246.4 11252.7 11276.6 11280.6 8187.6 8252.1 8310.4 8332.2 8492.1 5985.5 6031.1 6098.1 6145.8 6212.7 10446.9 10752.7 10763.7 10768.7 10769.7 10388.6 10682.6 10792.9 10920.8 10951.8 7324.1 7888.3 8098.7 8101.9 8227.7 8835.6 8857.6 8894.0 8937.7 8950.8 7841.3 8023.5 8070.6 8140.8 8213.0 7368.1 7692.3 7735.0 7844.3 8078.6 7188.0 7345.4 7439.1 7445.4 7446.8 5120.1 5176.5 5264.9 5273.3 5289.2 5034.8 5128.7 5134.7 5195.4 5246.2 22:25:11 INFO - PROCESS | 5473 | 22:25:11 INFO - PROCESS | 5473 | [#4] prototype.html Cycles:180 Average:4673.79 Median:5338.40 stddev:1377.81 (25.8%) stddev-sans-first:1342.33 22:25:11 INFO - PROCESS | 5473 | Values: 318.3 329.8 333.6 333.7 336.1 5275.0 5300.6 5313.9 5328.5 5386.6 5248.7 5255.2 5259.7 5266.9 5299.4 3966.2 3991.5 4151.5 4172.8 4178.5 4107.6 4156.8 4163.1 4164.2 4181.5 3948.9 4039.4 4106.8 4118.7 4149.3 4005.2 4109.9 4123.6 4140.4 4151.6 3612.1 3628.7 3641.0 3643.0 3648.2 5076.8 5087.5 5091.3 5124.4 5153.7 4542.6 4666.3 4671.9 4679.2 4723.6 5406.2 5459.3 5484.6 5497.6 5538.3 4011.3 4031.9 4037.9 4038.5 4077.3 5714.2 5739.3 5743.8 5774.9 5786.3 4041.9 4045.6 4095.6 4101.0 4112.6 4149.2 4166.5 4180.0 4182.5 4192.6 4381.2 4655.5 4682.9 4713.3 4759.3 5455.3 5497.6 5509.3 5531.5 5553.8 1397.6 1414.0 1415.3 1421.7 1421.7 890.9 900.3 910.6 911.8 944.6 5313.8 5333.9 5335.6 5341.2 5343.1 5614.7 5640.3 5645.4 5651.5 5658.2 5132.5 5326.1 5328.8 5355.9 5438.5 3026.2 3046.2 3050.9 3051.8 3052.9 5951.0 5952.2 5955.6 5985.8 6006.3 5544.8 5778.0 5790.7 5815.2 5821.2 5862.3 5936.1 5944.7 5987.6 6020.6 5656.4 5666.4 5774.0 5841.6 5851.4 5553.9 5590.9 5610.7 5662.7 5676.3 5860.3 5878.4 5882.5 5895.2 5926.6 3910.0 4049.6 4064.7 4069.9 4074.5 5680.7 5691.0 5695.5 5700.3 5744.7 5371.5 5386.5 5521.2 5536.4 5596.8 5476.2 5507.6 5523.8 5564.6 5570.7 5146.8 5505.2 5505.3 5526.9 5548.6 5552.5 5560.1 5560.8 5571.7 5580.7 5460.5 5479.0 5484.2 5494.5 5522.3 22:25:11 INFO - PROCESS | 5473 | 22:25:11 INFO - PROCESS | 5473 | [#5] yui.html Cycles:180 Average:15017.87 Median:51373.37 stddev:18376.75 (35.8%) stddev-sans-first:18397.32 22:25:11 INFO - PROCESS | 5473 | Values: 807.2 832.4 853.2 858.0 862.5 14162.9 14670.0 14830.1 14847.9 14948.2 12795.3 12856.1 12859.4 12859.5 12897.9 668.9 807.4 815.4 818.8 824.2 815.3 817.7 818.4 820.2 822.4 712.8 865.8 866.7 867.1 868.4 825.8 838.2 841.6 847.1 848.9 621.8 622.6 623.2 623.2 623.8 13656.5 13836.2 13838.6 13871.9 13903.6 12584.4 12714.7 12835.9 12922.0 13313.9 11218.8 11436.2 11612.4 11674.5 12102.3 4299.7 4300.5 4318.5 4457.5 4500.8 56686.0 56929.4 57460.4 59558.4 59706.4 10576.2 10662.7 10760.9 10869.9 10926.6 20583.4 20669.6 20670.8 20726.4 20726.5 7202.7 7306.3 7331.9 7340.5 7641.3 4682.2 4682.3 4730.6 4781.4 4794.7 3362.2 3454.3 3454.6 3459.4 3464.6 609.5 615.5 618.3 621.0 636.0 557.3 576.2 604.8 650.2 660.6 8988.7 9112.0 9209.2 9237.2 9395.8 634.2 706.7 726.9 747.4 748.1 4192.5 4195.2 4196.4 4218.0 4314.0 64915.7 66458.0 67397.3 67516.0 67547.7 57676.3 58127.4 59559.0 59562.7 59914.7 51037.0 51709.7 53357.5 54941.0 55032.2 8307.4 8369.8 8480.1 8528.8 8607.7 35742.6 36006.5 36359.6 36667.4 37535.8 43888.3 47969.3 47982.5 48107.3 48465.3 10514.9 10545.9 10698.1 10709.6 11004.7 11102.9 11309.4 11352.5 11443.8 11642.5 10948.0 11112.7 11133.9 11152.8 11293.0 11079.8 11436.7 11516.8 11537.9 11847.6 11338.4 11344.0 11379.9 11720.4 11773.5 8680.4 8934.4 9025.1 9058.5 9318.6 8565.0 8629.0 8709.4 9308.9 9437.8 22:25:11 INFO - PROCESS | 5473 | -------- Summary: end -------- 22:25:11 INFO - PROCESS | 5473 | 22:25:12 INFO - TEST-INFO | 5473: exit 0 22:25:12 INFO - TEST-OK | dromaeo_css | took 1113017ms 22:25:12 INFO - TEST-START | kraken 22:25:12 INFO - operating with platform_type : linux_ 22:25:12 INFO - Initialising browser for kraken test... 22:25:12 INFO - TEST-INFO | started process 5655 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_P7hyM/profile http://localhost:50288/getInfo.html) 22:25:16 INFO - PROCESS | 5655 | __metrics Screen width/height:1600/1200 22:25:16 INFO - PROCESS | 5655 | colorDepth:24 22:25:16 INFO - PROCESS | 5655 | Browser inner width/height: 1024/697 22:25:16 INFO - PROCESS | 5655 | __metrics 22:25:17 ERROR - PROCESS | 5655 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:25:18 INFO - TEST-INFO | 5655: exit 0 22:25:18 INFO - Browser initialized. 22:25:18 INFO - Running cycle 1/1 for kraken test... 22:25:18 INFO - TEST-INFO | started process 5735 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_P7hyM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:25:30 INFO - PROCESS | 5735 | RSS: Main: 153247744 22:25:30 INFO - PROCESS | 5735 | 22:25:33 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50288/tests/kraken/driver.html?testName=audio-beat-detection) 22:25:34 INFO - PROCESS | 5735 | RSS: Main: 209174528 22:25:34 INFO - PROCESS | 5735 | 22:25:36 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50288/tests/kraken/driver.html?testName=audio-dft) 22:25:37 INFO - PROCESS | 5735 | RSS: Main: 197308416 22:25:37 INFO - PROCESS | 5735 | 22:25:40 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50288/tests/kraken/driver.html?testName=audio-fft) 22:25:41 INFO - PROCESS | 5735 | RSS: Main: 266412032 22:25:41 INFO - PROCESS | 5735 | 22:25:43 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50288/tests/kraken/driver.html?testName=audio-oscillator) 22:25:44 INFO - PROCESS | 5735 | RSS: Main: 175247360 22:25:44 INFO - PROCESS | 5735 | 22:25:46 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50288/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:25:46 INFO - PROCESS | 5735 | RSS: Main: 179212288 22:25:46 INFO - PROCESS | 5735 | 22:25:50 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50288/tests/kraken/driver.html?testName=imaging-darkroom) 22:25:50 INFO - PROCESS | 5735 | RSS: Main: 215588864 22:25:50 INFO - PROCESS | 5735 | 22:25:54 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50288/tests/kraken/driver.html?testName=imaging-desaturate) 22:25:55 INFO - PROCESS | 5735 | RSS: Main: 214097920 22:25:55 INFO - PROCESS | 5735 | 22:25:57 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50288/tests/kraken/driver.html?testName=json-parse-financial) 22:25:58 INFO - PROCESS | 5735 | RSS: Main: 214712320 22:25:58 INFO - PROCESS | 5735 | 22:26:00 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50288/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:26:00 INFO - PROCESS | 5735 | RSS: Main: 203407360 22:26:00 INFO - PROCESS | 5735 | 22:26:02 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50288/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:26:03 INFO - PROCESS | 5735 | RSS: Main: 206336000 22:26:03 INFO - PROCESS | 5735 | 22:26:05 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50288/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:26:06 INFO - PROCESS | 5735 | RSS: Main: 215691264 22:26:06 INFO - PROCESS | 5735 | 22:26:08 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50288/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:26:09 INFO - PROCESS | 5735 | RSS: Main: 225349632 22:26:09 INFO - PROCESS | 5735 | 22:26:12 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50288/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:26:12 INFO - PROCESS | 5735 | RSS: Main: 221712384 22:26:12 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | Cycle 1(1): loaded http://localhost:50288/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50288/tests/kraken/driver.html?testName=ai-astar) 22:26:14 INFO - PROCESS | 5735 | RSS: Main: 267251712 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | __start_tp_report 22:26:14 INFO - PROCESS | 5735 | _x_x_mozilla_page_load 22:26:14 INFO - PROCESS | 5735 | _x_x_mozilla_page_load_details 22:26:14 INFO - PROCESS | 5735 | |i|pagename|runs| 22:26:14 INFO - PROCESS | 5735 | |0;ai-astar;111;106;110;110;108;110;110;109;108;108 22:26:14 INFO - PROCESS | 5735 | |1;audio-beat-detection;163;129;124;208;125;132;152;127;163;132 22:26:14 INFO - PROCESS | 5735 | |2;audio-dft;161;161;162;162;160;163;161;161;163;161 22:26:14 INFO - PROCESS | 5735 | |3;audio-fft;76;74;73;141;76;75;76;75;131;76 22:26:14 INFO - PROCESS | 5735 | |4;audio-oscillator;93;93;173;91;93;92;126;93;93;133 22:26:14 INFO - PROCESS | 5735 | |5;imaging-gaussian-blur;117;116;119;118;118;118;118;118;118;117 22:26:14 INFO - PROCESS | 5735 | |6;imaging-darkroom;147;147;150;145;145;146;148;146;146;146 22:26:14 INFO - PROCESS | 5735 | |7;imaging-desaturate;96;97;96;96;95;96;95;96;95;96 22:26:14 INFO - PROCESS | 5735 | |8;json-parse-financial;57;56;55;56;56;56;57;108;54;56 22:26:14 INFO - PROCESS | 5735 | |9;json-stringify-tinderbox;61;61;61;61;62;60;65;62;63;62 22:26:14 INFO - PROCESS | 5735 | |10;stanford-crypto-aes;78;78;79;79;79;80;80;79;78;79 22:26:14 INFO - PROCESS | 5735 | |11;stanford-crypto-ccm;130;121;119;124;135;121;119;125;189;126 22:26:14 INFO - PROCESS | 5735 | |12;stanford-crypto-pbkdf2;160;170;168;163;160;167;164;166;171;161 22:26:14 INFO - PROCESS | 5735 | |13;stanford-crypto-sha256-iterative;67;65;62;65;62;66;68;62;67;62 22:26:14 INFO - PROCESS | 5735 | __end_tp_report 22:26:14 INFO - PROCESS | 5735 | __start_cc_report 22:26:14 INFO - PROCESS | 5735 | _x_x_mozilla_cycle_collect,1901 22:26:14 INFO - PROCESS | 5735 | __end_cc_report 22:26:14 INFO - PROCESS | 5735 | __startTimestamp1464067574786__endTimestamp 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | ------- Summary: start ------- 22:26:14 INFO - PROCESS | 5735 | Number of tests: 14 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.00 stddev:1.49 (1.4%) stddev-sans-first:1.39 22:26:14 INFO - PROCESS | 5735 | Values: 111.0 106.0 110.0 110.0 108.0 110.0 110.0 109.0 108.0 108.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#1] audio-beat-detection Cycles:10 Average:145.50 Median:142.00 stddev:26.71 (18.8%) stddev-sans-first:27.57 22:26:14 INFO - PROCESS | 5735 | Values: 163.0 129.0 124.0 208.0 125.0 132.0 152.0 127.0 163.0 132.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:0.97 (0.6%) stddev-sans-first:1.01 22:26:14 INFO - PROCESS | 5735 | Values: 161.0 161.0 162.0 162.0 160.0 163.0 161.0 161.0 163.0 161.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#3] audio-fft Cycles:10 Average:87.30 Median:75.50 stddev:25.79 (34.2%) stddev-sans-first:27.03 22:26:14 INFO - PROCESS | 5735 | Values: 76.0 74.0 73.0 141.0 76.0 75.0 76.0 75.0 131.0 76.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#4] audio-oscillator Cycles:10 Average:108.00 Median:93.00 stddev:27.58 (29.7%) stddev-sans-first:28.71 22:26:14 INFO - PROCESS | 5735 | Values: 93.0 93.0 173.0 91.0 93.0 92.0 126.0 93.0 93.0 133.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.82 (0.7%) stddev-sans-first:0.83 22:26:14 INFO - PROCESS | 5735 | Values: 117.0 116.0 119.0 118.0 118.0 118.0 118.0 118.0 118.0 117.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:146.50 stddev:1.51 (1.0%) stddev-sans-first:1.59 22:26:14 INFO - PROCESS | 5735 | Values: 147.0 147.0 150.0 145.0 145.0 146.0 148.0 146.0 146.0 146.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 22:26:14 INFO - PROCESS | 5735 | Values: 96.0 97.0 96.0 96.0 95.0 96.0 95.0 96.0 95.0 96.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:56.00 stddev:16.50 (29.5%) stddev-sans-first:17.44 22:26:14 INFO - PROCESS | 5735 | Values: 57.0 56.0 55.0 56.0 56.0 56.0 57.0 108.0 54.0 56.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.00 stddev:1.40 (2.3%) stddev-sans-first:1.45 22:26:14 INFO - PROCESS | 5735 | Values: 61.0 61.0 61.0 61.0 62.0 60.0 65.0 62.0 63.0 62.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:79.00 stddev:0.74 (0.9%) stddev-sans-first:0.71 22:26:14 INFO - PROCESS | 5735 | Values: 78.0 78.0 79.0 79.0 79.0 80.0 80.0 79.0 78.0 79.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#11] stanford-crypto-ccm Cycles:10 Average:130.90 Median:125.50 stddev:21.03 (16.8%) stddev-sans-first:22.30 22:26:14 INFO - PROCESS | 5735 | Values: 130.0 121.0 119.0 124.0 135.0 121.0 119.0 125.0 189.0 126.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:166.50 stddev:4.03 (2.4%) stddev-sans-first:3.84 22:26:14 INFO - PROCESS | 5735 | Values: 160.0 170.0 168.0 163.0 160.0 167.0 164.0 166.0 171.0 161.0 22:26:14 INFO - PROCESS | 5735 | 22:26:14 INFO - PROCESS | 5735 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.50 stddev:2.41 (3.7%) stddev-sans-first:2.40 22:26:14 INFO - PROCESS | 5735 | Values: 67.0 65.0 62.0 65.0 62.0 66.0 68.0 62.0 67.0 62.0 22:26:14 INFO - PROCESS | 5735 | -------- Summary: end -------- 22:26:14 INFO - PROCESS | 5735 | 22:26:15 INFO - TEST-INFO | 5735: exit 0 22:26:15 INFO - TEST-OK | kraken | took 63153ms 22:26:15 INFO - SUITE-END | took 1176s 22:26:15 INFO - Completed test suite (00:19:36) 22:26:15 INFO - javascript benchmark 22:26:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3100.2249437640576, 3121.360121175159, 3144.2296637323852, 3155.5266709993484, 3182.6658200888905, 5151.665141765792, 5221.295125158103, 5367.973160134199, 5469.425710300421, 5517.144842549406, 5212.478752124795, 5233.267342572043, 5300.0459917214885, 5317.149256119022, 5354.22363757255, 4714.104320179172, 4759.690620109682, 4794.808207671689, 4809.711417314955, 4943.530364615356, 4898.681585696936, 4964.87587810304, 4973.825916092937, 4980.526849949267, 4986.626003049768, 4657.88355291117, 4707.24684050552, 4834.419869615634, 4882.682625629341, 4906.901861962777, 4858.368412106439, 4872.171730805771, 4881.511848815126, 4968.627352948543, 5010.173321401964, 4906.779194936236, 4932.90134197314, 4941.629377796644, 4949.90100197994, 4956.900861982776, 5040.34475518184, 5172.870678233036, 5192.506711862367, 5328.6802791832615, 5362.65142765719, 5738.282714660668, 5740.397258287903, 5785.27684039495, 5787.102999035156, 5788.392218817005, 5782.508486778629, 5825.097109947965, 5846.883062338729, 5855.941440585582, 5856.443637854397, 5189.117849965515, 5238.528532432099, 5297.682139071668, 5307.548858347083, 5454.427285135043, 5852.385499522489, 5897.026990546512, 5901.616394934315, 5903.675297858659, 5914.0, 5588.720563971785, 5616.85957851057, 5621.381648018714, 5651.2935883014625, 5651.6043876928225, 5536.474034966672, 5570.08093664549, 5593.720313984366, 5624.043912534797, 5646.632968857011, 5652.349979752299, 5656.519195868397, 5658.179563963202, 5659.037963546207, 5668.234788303526, 5490.533304669068, 5605.0, 5617.719114044281, 5618.803341883015, 5624.831255062355, 4300.6129448349175, 4328.48058233014, 4331.4152589400655, 4340.848070317524, 4342.0664557119735, 3864.671502922227, 3871.4386413969814, 3877.418387241936, 3893.53277606689, 3912.8043597819997, 5207.635465517377, 5246.580273578104, 5407.972960135174, 5426.403095659474, 5484.0, 5647.040003199466, 5703.800366987219, 5708.571857110734, 5708.743106560215, 5742.397048309951, 5441.5102640762525, 5469.453054694499, 5470.671759694431, 5493.945060549423, 5503.532199762985, 4732.574068333797, 4963.776630051656, 4965.627577931669, 4977.32806071183, 5015.0, 6010.188624535719, 6115.453310713017, 6117.827404800917, 6118.131909153758, 6122.12371750169, 5862.149988251765, 5868.383819699041, 5898.292204935436, 5904.0, 5905.0, 5603.943960560342, 5801.274840644919, 5813.273340832396, 6085.57400981927, 6103.389661033861, 5546.722663866871, 5593.356763972195, 5596.5242954347705, 5600.691961942132, 5636.2954630671165, 5672.546196304368, 5688.5733569981585, 5711.828645140652, 5716.657000579979, 5717.028105222039, 5395.838124856261, 5495.697736624524, 5498.615096943176, 5535.8892822144135, 5587.0502014656795, 5568.888622227614, 5572.944270557243, 5576.079946808731, 5580.1629755537, 5587.161925711013, 5420.647657902224, 5458.6724796512335, 5481.804241128006, 5486.972565137149, 5535.5848311376, 5382.919256211082, 5581.386047534686, 5621.213030175861, 5652.265205523256, 5767.567432442667, 5592.133219351007, 5604.775808967595, 5605.215269862304, 5616.494515493625, 5649.12438572022], "value": 5270.744205396977, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.2090440618383, 633.0725487161308, 634.1692382978297, 645.5803727577078, 651.2217899609966, 31852.0, 32551.348973020555, 43413.56586434135, 44224.11551768963, 44260.77869610656, 42525.7873710631, 46503.30245046327, 46641.53358466415, 46659.53340466594, 46741.7662911685, 8612.8708069379, 8743.213110820025, 8790.384673072887, 8797.956010219972, 8802.339824513187, 8697.695580654678, 8828.602712877935, 8836.8674469883, 8842.690505832297, 8844.204021638052, 8814.911850881474, 8887.600057997406, 8891.199792018717, 8892.510911899843, 8896.777580560472, 8820.735377938672, 8834.999999999936, 8864.0, 8872.512011839346, 8874.29005679544, 8702.651893924236, 8762.956185219096, 8776.0, 8778.64885404583, 8786.64853405863, 42923.35614965778, 43479.0, 43483.78258108721, 43516.0, 43533.56466435359, 3589.46158076288, 3594.784312941206, 3695.0, 3709.4250391189407, 3720.21875406165, 30281.848590757127, 33162.66837331644, 33163.50254746181, 33355.33289334224, 35386.0, 4272.978635106804, 4322.935155972663, 4360.433143691332, 4366.934495982563, 4503.0, 340509.0, 343911.2435502577, 345367.0, 345861.27069364744, 348824.0, 57593.71203143957, 63942.68028659827, 64928.35071649317, 65549.34450655528, 65823.67088164529, 67232.0, 67332.0, 67460.0, 67501.0, 67888.6605566969, 17478.388256410966, 17511.562210944572, 17684.381046663304, 17690.203940822437, 17726.0, 11960.461779219955, 12590.748185036247, 13142.277174755289, 13412.798808017887, 13425.0, 21011.8949405252, 21038.368848934555, 21205.681914771292, 21357.67963480549, 21391.46521336979, 7725.575093369806, 7755.806104847424, 7774.416918731118, 7917.683292668229, 8094.826250193807, 1026.5072765072807, 1057.6509751781894, 1096.9012788849043, 1140.960066397672, 1254.1221145198397, 33670.326593467995, 33750.66249337525, 33815.154621134665, 33909.660903391145, 34152.06763844929, 1163.3950345820126, 1169.2984209474248, 1185.4309931233056, 1198.4606926882764, 1237.1525505029083, 17529.386471473437, 17714.114294285235, 17771.289148434178, 17841.46475605734, 17841.643167136586, 503073.0, 503612.0, 506698.0, 507390.0, 509268.0, 58799.70600146972, 60466.69766651138, 60670.0, 60750.08874866881, 60846.69576652177, 1284.89720822334, 1372.3207012528862, 1379.717157982611, 1398.853120422341, 1424.4587056918306, 31715.938463999766, 34459.84750965462, 34476.655233447345, 34684.65315346814, 34814.82592587021, 52072.73963630158, 52237.0, 52504.474955249956, 52586.0, 52762.0, 391006.0, 391451.0, 392113.0, 392475.0, 392714.0, 28428.0, 38262.0, 43751.34372984472, 44612.10775784401, 44639.330410044524, 46421.0, 47015.29477057915, 49828.75085624549, 50870.745646271534, 51095.744521277156, 40648.39027414532, 42990.78504607582, 43191.0, 45549.54450455453, 45736.771316143204, 42251.0, 42544.57455425406, 43113.0, 43121.0, 43442.34836477392, 42364.78817605892, 43217.567824321355, 43326.0, 43451.34822977595, 45491.9057284846, 35511.644883550834, 38353.23293534169, 38774.4183837237, 39481.80259098686, 39516.40725389064, 28881.42237155287, 29030.27424314409, 29071.273218169714, 29202.0, 29428.26429339198], "value": 20881.008975279303, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.7776028783449, 395.40886374869575, 395.63403851437414, 401.5863660429757, 413.1262380066161, 14328.211948342838, 17881.731774023367, 17913.910430447846, 19256.90371548142, 19275.13261903214, 16022.839771602281, 16971.74542381865, 18366.81633183668, 19709.60580788387, 19710.605787884233, 5499.587530935176, 5662.886742265152, 5684.2326285951485, 5687.4312568743, 5690.260266165393, 4065.1869626074904, 5489.752961116759, 5661.0, 5992.430719081702, 6265.9686701566425, 6306.180196574439, 6314.052892066181, 6329.8417539561415, 6338.366163383648, 6341.904871426932, 6182.659953702567, 6259.874802503947, 6322.810315690536, 6323.05154226865, 6339.04914262862, 5349.43830897754, 5560.165975103726, 5569.387367389584, 5569.387367389624, 5586.944130558683, 21598.352049438385, 21858.0, 23096.7296798676, 23350.18274360389, 23480.76519234803, 2352.5294941011693, 2395.8802059896934, 2397.8081753459683, 2422.0917156066475, 2918.9124326270244, 9614.807703845954, 10256.743581410525, 13946.93026534871, 14135.646608834759, 14592.124472531683, 1828.5794267318458, 1846.649136664043, 1901.1729897494633, 1921.3947606503907, 1962.4112766170092, 78067.0, 80569.99999999942, 81811.5909420455, 82796.0, 83341.58329208376, 10713.303635263683, 11020.0, 11049.28179668319, 11179.4969226385, 11182.440877956069, 13882.167069975834, 14386.640333991525, 15714.13572253537, 15833.129177895096, 15847.445339413065, 8174.836503269901, 8369.455985360932, 8391.874121888177, 8538.359623028298, 8617.138286171457, 8198.221168988937, 8261.421700480909, 8291.58542072906, 8387.53218186815, 8439.198276163756, 4702.623790096784, 4713.670043097019, 4773.64197685175, 4806.279058141236, 4819.49395313487, 1848.8058753830924, 1950.444123424817, 1980.5939782344585, 1988.3935399702941, 2028.665270230425, 1011.1556850030161, 1036.0520124086377, 1047.7223535763028, 1080.9783804324027, 1118.981726628764, 18749.81250187508, 19083.427497175107, 19135.80864191368, 19397.076128363173, 19441.22235110608, 1004.3873237325225, 1007.6422869881234, 1033.2405681823852, 1067.3222503710044, 1075.6988043347876, 10621.097206737359, 10793.78412431747, 10816.945915270373, 10850.348979061282, 10857.565697372016, 473703.0, 480289.0, 489343.0, 490846.0, 495353.0, 92922.0, 95735.0, 95818.52090739501, 96146.0, 97166.51416743013, 1004.7538353103537, 1006.758377989292, 1006.9779174138256, 1052.3633201912885, 1054.267284237463, 16936.814422990767, 17040.403585874436, 17118.743218851978, 17246.310147593955, 17451.47645570635, 39346.803265983486, 39586.40620390754, 39946.0, 40778.388324174564, 40872.0, 53012.0, 53476.19785703295, 56284.0, 58265.417345826, 60220.0, 12777.23336599807, 13418.329083545588, 13425.194488330731, 13475.32623368808, 13529.458821647024, 14102.224377659328, 14257.50098746539, 14868.925655371653, 14962.40150393972, 15022.173780442181, 13042.739145216852, 13149.605511834277, 13250.536231232247, 13258.668533286746, 13382.13016153947, 12714.237145771283, 12857.807132893202, 12932.0, 13102.820746132513, 13255.625099033312, 12841.871581284067, 12970.740585188432, 12997.870021300045, 13067.477300907858, 13112.73774524485, 13153.145045572008, 13219.47122115143, 13265.734685306434, 13427.462901484221, 13453.394597242952, 12799.232046077264, 12836.679083022998, 12845.486180552673, 13206.0, 13220.40508177115], "value": 9695.502803176547, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1250.306080125531, 1517.8178618565773, 1539.5073576455536, 1682.9831701682988, 1849.1863580024797, 8623.784405389868, 8953.462792232462, 9001.369904106707, 9232.769180770467, 9247.445153290808, 8980.236679882219, 9175.128362805523, 9183.127602877732, 9184.770380740485, 9185.173334399902, 2059.2895451069357, 2288.7596802335765, 2358.646203069536, 2412.155745489082, 2449.865257410842, 2454.3005243505604, 2474.5669507836146, 2488.042103790036, 2489.626556016594, 2490.8915532588007, 2336.5093330400637, 2349.659299401594, 2355.9293221203393, 2359.3865594945264, 2359.9646005309846, 2051.1282704850455, 2319.118734880743, 2391.354334329739, 2393.3537944754994, 2405.9037638494437, 2331.988340058289, 2392.569337519246, 2398.8200884933526, 2403.327068420845, 2406.4344878953434, 9091.545422728837, 9095.909040909572, 9102.271818254523, 9119.133682300171, 9136.0, 5308.973455132738, 5457.863553411157, 5502.064649009678, 5503.009458297505, 5505.146702261156, 3447.276072024878, 3458.204612939013, 3509.438489841639, 3579.266250418658, 3582.0328511302064, 1074.897884700952, 1087.1302957633861, 1108.3737688206218, 1111.3276467737064, 1132.9943350283195, 12597.685057873536, 12707.046971477177, 12765.361731913366, 12768.297743624096, 12780.744385112339, 4597.402337696078, 4683.929741053887, 4799.544043315879, 4814.037192561432, 4825.324454576362, 10040.146587539995, 10061.39631622105, 10063.641408409918, 10065.69802905914, 10076.848847267296, 4757.3101900224965, 5457.372402173722, 5457.372402173801, 5471.6169868108855, 5476.780928762884, 4729.243321068612, 4828.686135401188, 4894.388201474816, 4905.386826646669, 4921.5078492151215, 3901.785401802928, 3987.8404863805704, 4031.0728532437115, 4063.309237429644, 4072.205919845602, 1188.2870277833404, 1190.8451901252781, 1195.4321697193764, 1204.030755242021, 1204.0969273045216, 873.6374404622138, 913.4108500017452, 947.9146876781123, 963.4460185393427, 1007.9748006299754, 5977.731002104829, 5978.103284507359, 6031.487323577458, 6198.473129783929, 6222.0, 911.252772726358, 912.0833562270009, 942.34036174678, 949.9145076943108, 995.8406654935176, 4030.133521292936, 4158.272302347077, 4169.249535083714, 4191.392248124005, 4259.765712885821, 10822.0, 11215.327080375204, 11294.322340659586, 11377.658670239905, 11384.886151138548, 11164.609238676607, 11246.43767811606, 11252.66242012741, 11276.605318813967, 11280.605178818701, 8187.631556579968, 8252.133525979805, 8310.376721745894, 8332.208440198172, 8492.108328625529, 5985.4912332450485, 6031.065184896348, 6098.054801505774, 6145.84635384119, 6212.720427580679, 10446.895531044593, 10752.677419677422, 10763.730906727395, 10768.730781730519, 10769.676909692722, 10388.636397726043, 10682.62610808618, 10792.892071079188, 10920.781584368428, 10951.83572246433, 7324.08448943882, 7888.290053895177, 8098.716544921135, 8101.918980810352, 8227.670893164446, 8835.64657413722, 8857.557122143997, 8893.955530222307, 8937.687180948895, 8950.776230594287, 7841.294283514511, 8023.4784738991775, 8070.596470176585, 8140.837183256183, 8212.958935205286, 7368.078990126234, 7692.307692307719, 7734.961325193338, 7844.294013538581, 8078.555679437452, 7187.9640601796655, 7345.412367010733, 7439.107307123179, 7445.4415918805225, 7446.813829654086, 5120.129577971828, 5176.482351764794, 5264.868378290574, 5273.31446911899, 5289.2330612061805, 5034.798608055637, 5128.666636668754, 5134.691918484903, 5195.350581177353, 5246.213068039825], "value": 4732.9538446598945, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.33785725690564, 329.78399148557696, 333.58635292237625, 333.74468531573336, 336.05065689427346, 5275.0, 5300.602454815889, 5313.8937221255555, 5328.520433161015, 5386.622936394454, 5248.664344366749, 5255.160693757183, 5259.658122222062, 5266.947330526694, 5299.364076310848, 3966.186931679006, 3991.5210174779068, 4151.522574903879, 4172.812223449952, 4178.540360560336, 4107.589241075883, 4156.817670722864, 4163.126593033147, 4164.167166566684, 4181.519125300584, 3948.881533553998, 4039.4344791729036, 4106.753594784308, 4118.670506359484, 4149.273877071516, 4005.239004589119, 4109.9108736184935, 4123.587641235882, 4140.420341152241, 4151.584841515854, 3612.1330880588735, 3628.7118073084202, 3640.9635903640888, 3643.034595832107, 3648.215633638754, 5076.847694569169, 5087.465816089294, 5091.312672789163, 5124.4106927703415, 5153.665011774223, 4542.568455996675, 4666.300054991744, 4671.906561868744, 4679.181143299909, 4723.622110231173, 5406.243125962368, 5459.262999495057, 5484.561235101222, 5497.642653227567, 5538.252335934638, 4011.2579172853116, 4031.9193616127804, 4037.9097643636346, 4038.454808600831, 4077.2864748668862, 5714.228579141846, 5739.253896993406, 5743.798967036176, 5774.913376299359, 5786.321966629673, 4041.939370909439, 4045.615666511677, 4095.5904409558807, 4101.031504877676, 4112.629863312316, 4149.195100131453, 4166.541680415152, 4179.9791001045405, 4182.53992060873, 4192.622663960197, 4381.233284175257, 4655.464621568495, 4682.929756053662, 4713.34013238147, 4759.309900064541, 5455.263539422207, 5497.615166938276, 5509.319657504419, 5531.529819965268, 5553.77848860459, 1397.5667543061684, 1413.971720565583, 1415.2852809331223, 1421.6730152064877, 1421.722764060998, 890.8930928288518, 900.3202582050641, 910.5538286239695, 911.8358695434753, 944.5560586524323, 5313.840584782462, 5333.906549157491, 5335.626506144533, 5341.172118321666, 5343.145096784496, 5614.69119198448, 5640.32316122068, 5645.407232240637, 5651.519620832275, 5658.151277308437, 5132.538071573576, 5326.070661136359, 5328.813491527778, 5355.892882142335, 5438.456154384609, 3026.243439140215, 3046.192758918888, 3050.8627111780024, 3051.832149231769, 3052.9389412212076, 5951.018082016505, 5952.166696662471, 5955.642661440327, 5985.820425387245, 6006.339302676702, 5544.833654990357, 5777.971110144423, 5790.699301398163, 5815.156802263648, 5821.2141360916585, 5862.325832529228, 5936.1392598073035, 5944.7027648617395, 5987.6108052976415, 6020.608660437058, 5656.377798442086, 5666.405027472056, 5773.97113014449, 5841.561882858718, 5851.356350801323, 5553.888922221614, 5590.86022849432, 5610.663360198401, 5662.66024038559, 5676.318841739017, 5860.326062502696, 5878.412158784087, 5882.470577648032, 5895.174675545341, 5926.585139040226, 3909.980450097731, 4049.5826460738504, 4064.7357921734992, 4069.8575549855614, 4074.490688663917, 5680.715964201856, 5691.044723916838, 5695.515881149983, 5700.344460386943, 5744.655320680772, 5371.543418809289, 5386.5152136307925, 5521.171824226398, 5536.418676039119, 5596.832095037155, 5476.233327334096, 5507.614466987273, 5523.751431185526, 5564.55483561306, 5570.693611851387, 5146.76839542229, 5505.174223866292, 5505.256790333254, 5526.889462210814, 5548.55611551083, 5552.500274975271, 5560.054790685513, 5560.777568897199, 5571.721413929206, 5580.693061881636, 5460.535854452416, 5479.0, 5484.232207490874, 5494.450554944633, 5522.282103326542], "value": 4231.560639141265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [807.2411932783184, 832.3923535818848, 853.2065179383173, 857.9914200857993, 862.5299211929502, 14162.858371416283, 14670.0, 14830.110193388377, 14847.925760371183, 14948.177850218233, 12795.296258705768, 12856.10007299487, 12859.357032148402, 12859.549915752927, 12897.935510322459, 668.8862893308125, 807.4347956430493, 815.4414226255034, 818.7748369198488, 824.2005254902765, 815.3436483630704, 817.7424111404919, 818.3780326951501, 820.1880138662741, 822.391430341546, 712.8324843661735, 865.8354912566592, 866.6706651472432, 867.0679020053436, 868.3617541107282, 825.830704705537, 838.2413915406536, 841.6423020216414, 847.1189962439056, 848.8514509960733, 621.8227805075531, 622.6295354264206, 623.183629445428, 623.2209737827716, 623.8190924631851, 13656.522021729292, 13836.23900685472, 13838.584842454742, 13871.861281387159, 13903.582892513241, 12584.4337004835, 12714.74570508594, 12835.87164128356, 12922.0, 13313.93488520916, 11218.775624487545, 11436.153095929996, 11612.36132012739, 11674.533018679243, 12102.273863568125, 4299.720518166309, 4300.526942036374, 4318.481782186126, 4457.465104187519, 4500.819967201308, 56686.0, 56929.430705693245, 57460.42539574592, 59558.404415955716, 59706.40293597095, 10576.153907687367, 10662.680119596425, 10760.94619526913, 10869.945650271698, 10926.617568385069, 20583.38249852507, 20669.58660826775, 20670.79329206719, 20726.378208653765, 20726.481837954172, 7202.747903823341, 7306.305900939402, 7331.9266807332315, 7340.522866013692, 7641.2740789624895, 4682.227432473604, 4682.274247491576, 4730.59789917854, 4781.378420805273, 4794.7362895040405, 3362.159460134966, 3454.274602333488, 3454.5836207154944, 3459.41189997701, 3464.5842498900292, 609.5032548472981, 615.5137441421227, 618.2951435363689, 620.996895015522, 635.9554831161774, 557.3089369182185, 576.1559315602663, 604.7550741949517, 650.1547987616175, 660.5805313625787, 8988.685396011108, 9112.0, 9209.17117459432, 9237.214836738818, 9395.765105872408, 634.1565717595587, 706.717313074764, 726.9055022847102, 747.439420434674, 748.0948052856105, 4192.517860446027, 4195.16096780639, 4196.4099041246955, 4217.957820421757, 4313.981817101472, 64915.675421623535, 66458.0, 67397.3260267391, 67516.0, 67547.66226168838, 57676.25104368202, 58127.418725812204, 59559.0, 59562.702186488794, 59914.70042649759, 51037.0, 51709.7414512925, 53357.46642533525, 54941.0, 55032.17451738227, 8307.376946728898, 8369.83260334802, 8480.06719260893, 8528.786780330542, 8607.655693772429, 35742.64257357393, 36006.45990310095, 36359.636403635624, 36667.44998825073, 37535.81232093822, 43888.34167487426, 47969.280460793816, 47982.520174797806, 48107.27839082346, 48465.27302090542, 10514.894851051391, 10545.894541054799, 10698.14414846826, 10709.629909741216, 11004.669859904216, 11102.944485277521, 11309.434528273718, 11352.489137988976, 11443.771124577295, 11642.476088575864, 10948.0, 11112.722181945193, 11133.88866111361, 11152.776944461228, 11293.0, 11079.778404431705, 11436.656900293005, 11516.769664606829, 11537.88462115368, 11847.628098519346, 11338.376389298668, 11344.0, 11379.886201137882, 11720.41397930083, 11773.470193841127, 8680.392372534114, 8934.374593778633, 9025.142611451902, 9058.50178240203, 9318.62725491, 8564.957175214084, 8628.956855215683, 8709.433886797338, 9308.860367094363, 9437.764055898382], "value": 6583.540207413881, "unit": "score"}], "name": "dromaeo_css", "value": 7211.9966887723795, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 110.0, 110.0, 108.0, 110.0, 110.0, 109.0, 108.0, 108.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 129.0, 124.0, 208.0, 125.0, 132.0, 152.0, 127.0, 163.0, 132.0], "value": 145.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 160.0, 163.0, 161.0, 161.0, 163.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 73.0, 141.0, 76.0, 75.0, 76.0, 75.0, 131.0, 76.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 173.0, 91.0, 93.0, 92.0, 126.0, 93.0, 93.0, 133.0], "value": 108.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 119.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 150.0, 145.0, 145.0, 146.0, 148.0, 146.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 56.0, 56.0, 56.0, 57.0, 108.0, 54.0, 56.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 62.0, 60.0, 65.0, 62.0, 63.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 79.0, 79.0, 80.0, 80.0, 79.0, 78.0, 79.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 121.0, 119.0, 124.0, 135.0, 121.0, 119.0, 125.0, 189.0, 126.0], "value": 130.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 170.0, 168.0, 163.0, 160.0, 167.0, 164.0, 166.0, 171.0, 161.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 62.0, 65.0, 62.0, 66.0, 68.0, 62.0, 67.0, 62.0], "value": 64.6, "unit": "score"}], "name": "kraken", "value": 1533.7, "alertThreshold": 2.0}]} 22:26:16 INFO - Return code: 0 22:26:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:26:16 ERROR - # TBPL SUCCESS # 22:26:16 INFO - Running post-action listener: _resource_record_post_action 22:26:16 INFO - ##### 22:26:16 INFO - ##### Finished run-tests step (success) 22:26:16 INFO - ##### 22:26:16 INFO - Running post-run listener: _resource_record_post_run 22:26:16 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 537260032; Read time: 8; Write time: 682632 22:26:16 INFO - TinderboxPrint: CPU usage
13.2% 22:26:16 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 22:26:16 INFO - TinderboxPrint: I/O write bytes / time
537,260,032 / 682,632 22:26:16 INFO - TinderboxPrint: CPU idle
8,271.0 (86.8%) 22:26:16 INFO - TinderboxPrint: CPU user
1,207.6 (12.7%) 22:26:16 INFO - TinderboxPrint: Swap in / out
0 / 0 22:26:16 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 246980608; Read time: 0; Write time: 354404 22:26:16 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290279424; Read time: 8; Write time: 328228 22:26:16 INFO - Running post-run listener: _upload_blobber_files 22:26:16 INFO - Blob upload gear active. 22:26:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:26:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:26:16 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:26:16 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:26:17 INFO - (blobuploader) - INFO - Open directory for files ... 22:26:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:26:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:26:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:21 INFO - (blobuploader) - INFO - Done attempting. 22:26:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:26:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:26:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:22 INFO - (blobuploader) - INFO - Done attempting. 22:26:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:26:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:26:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:23 INFO - (blobuploader) - INFO - Done attempting. 22:26:23 INFO - (blobuploader) - INFO - Iteration through files over. 22:26:23 INFO - Return code: 0 22:26:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:26:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:26:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/229fced0490b5e594788769d408a18646c14ab36018a2ae8b89ac9410a020624c236b04c5b64478b3263a38e874314d0c91d7723c0fa6f84fe6a3094b5762dbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e4dedb1b44ccc455f4fc20c57d4d24b60a97bb7ce29905271751fdfe7b45b1c488fbb1fe1a74a717c5029a27403f1c7b5c6b8cb9f8655db0920f9849ce87278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4cfe3209471dc23bdb4e4bd0221918a941c8b6e30ca705e0183c6e6648279045d665a22ed458ad710829ea0becc078b9daa1ce9764a777a35ca7d87f07fe02f"} 22:26:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:26:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:26:23 INFO - Contents: 22:26:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/229fced0490b5e594788769d408a18646c14ab36018a2ae8b89ac9410a020624c236b04c5b64478b3263a38e874314d0c91d7723c0fa6f84fe6a3094b5762dbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e4dedb1b44ccc455f4fc20c57d4d24b60a97bb7ce29905271751fdfe7b45b1c488fbb1fe1a74a717c5029a27403f1c7b5c6b8cb9f8655db0920f9849ce87278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4cfe3209471dc23bdb4e4bd0221918a941c8b6e30ca705e0183c6e6648279045d665a22ed458ad710829ea0becc078b9daa1ce9764a777a35ca7d87f07fe02f"} 22:26:23 INFO - Running post-run listener: copy_logs_to_upload_dir 22:26:23 INFO - Copying logs to upload dir... 22:26:23 INFO - mkdir: /builds/slave/test/build/upload/logs 22:26:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.629666 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-05-23 22:26:22.887301) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 22:26:22.891197) ========= 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-1464066252.605930-175528650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/229fced0490b5e594788769d408a18646c14ab36018a2ae8b89ac9410a020624c236b04c5b64478b3263a38e874314d0c91d7723c0fa6f84fe6a3094b5762dbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e4dedb1b44ccc455f4fc20c57d4d24b60a97bb7ce29905271751fdfe7b45b1c488fbb1fe1a74a717c5029a27403f1c7b5c6b8cb9f8655db0920f9849ce87278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4cfe3209471dc23bdb4e4bd0221918a941c8b6e30ca705e0183c6e6648279045d665a22ed458ad710829ea0becc078b9daa1ce9764a777a35ca7d87f07fe02f"} build_url:https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011351 build_url: 'https://queue.taskcluster.net/v1/task/VxDfRuLmQiCtEQVsg9s41A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/229fced0490b5e594788769d408a18646c14ab36018a2ae8b89ac9410a020624c236b04c5b64478b3263a38e874314d0c91d7723c0fa6f84fe6a3094b5762dbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e4dedb1b44ccc455f4fc20c57d4d24b60a97bb7ce29905271751fdfe7b45b1c488fbb1fe1a74a717c5029a27403f1c7b5c6b8cb9f8655db0920f9849ce87278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4cfe3209471dc23bdb4e4bd0221918a941c8b6e30ca705e0183c6e6648279045d665a22ed458ad710829ea0becc078b9daa1ce9764a777a35ca7d87f07fe02f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 22:26:22.918932) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:26:22.919531) ========= 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-1464066252.605930-175528650 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:26:23.001462) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 22:26:23.001941) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 22:26:23.002289) ========= ========= Total master_lag: 0.19 =========