builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-023 starttime: 1461531863.6 results: success (0) buildid: 20160424113022 builduid: c1ecf460aaab4fa08a9ae89ec785c637 revision: 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:23.604581) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:23.605636) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:23.606066) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:23.691290) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:23.692400) ========= 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=6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-24 14:04:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.5M=0s 2016-04-24 14:04:24 (67.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141511 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:23.864797) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:23.865164) ========= 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=6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.080871 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:23.959849) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:23.960976) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 --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=6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-24 14:04:24,253 truncating revision to first 12 chars 2016-04-24 14:04:24,253 Setting DEBUG logging. 2016-04-24 14:04:24,253 attempt 1/10 2016-04-24 14:04:24,253 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/13cbfc180fe6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-24 14:04:24,610 unpacking tar archive at: fx-team-13cbfc180fe6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.663291 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:24.638913) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:24.639252) ========= script_repo_revision: 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:24.639733) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:24.640044) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:24.648378) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-04-24 14:04:24.648705) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:04:25 INFO - MultiFileLogger online at 20160424 14:04:25 in /builds/slave/test 14:04:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:04:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:04:25 INFO - {'append_to_log': False, 14:04:25 INFO - 'base_work_dir': '/builds/slave/test', 14:04:25 INFO - 'blob_upload_branch': 'Fx-Team', 14:04:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:04:25 INFO - 'branch': 'Fx-Team', 14:04:25 INFO - 'buildbot_json_path': 'buildprops.json', 14:04:25 INFO - 'config_files': ('talos/linux_config.py',), 14:04:25 INFO - 'default_actions': ('clobber', 14:04:25 INFO - 'read-buildbot-config', 14:04:25 INFO - 'download-and-extract', 14:04:25 INFO - 'populate-webroot', 14:04:25 INFO - 'create-virtualenv', 14:04:25 INFO - 'install', 14:04:25 INFO - 'run-tests'), 14:04:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:04:25 INFO - 'download_minidump_stackwalk': True, 14:04:25 INFO - 'download_symbols': 'ondemand', 14:04:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:04:25 INFO - 'tooltool.py': '/tools/tooltool.py', 14:04:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:04:25 INFO - '/tools/misc-python/virtualenv.py')}, 14:04:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:04:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:04:25 INFO - 'installer_path': 'installer.exe', 14:04:25 INFO - 'log_level': 'info', 14:04:25 INFO - 'log_name': 'talos', 14:04:25 INFO - 'log_to_console': True, 14:04:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:04:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:04:25 INFO - 'opt_config_files': (), 14:04:25 INFO - 'pip_index': False, 14:04:25 INFO - 'sps_profile': False, 14:04:25 INFO - 'sps_profile_interval': 0, 14:04:25 INFO - 'suite': 'dromaeojs-e10s', 14:04:25 INFO - 'system_bits': '32', 14:04:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:04:25 INFO - 'title': 'talos-linux64-ix-023', 14:04:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:04:25 INFO - 'use_talos_json': True, 14:04:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:04:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:04:25 INFO - 'work_dir': 'build'} 14:04:25 INFO - ##### 14:04:25 INFO - ##### Running clobber step. 14:04:25 INFO - ##### 14:04:25 INFO - Running pre-action listener: _resource_record_pre_action 14:04:25 INFO - Running main action method: clobber 14:04:25 INFO - rmtree: /builds/slave/test/build 14:04:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:04:26 INFO - Running post-action listener: _resource_record_post_action 14:04:26 INFO - ##### 14:04:26 INFO - ##### Running read-buildbot-config step. 14:04:26 INFO - ##### 14:04:26 INFO - Running pre-action listener: _resource_record_pre_action 14:04:26 INFO - Running main action method: read_buildbot_config 14:04:26 INFO - Using buildbot properties: 14:04:26 INFO - { 14:04:26 INFO - "project": "", 14:04:26 INFO - "product": "firefox", 14:04:26 INFO - "script_repo_revision": "production", 14:04:26 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 14:04:26 INFO - "repository": "", 14:04:26 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 14:04:26 INFO - "buildid": "20160424113022", 14:04:26 INFO - "pgo_build": "True", 14:04:26 INFO - "basedir": "/builds/slave/test", 14:04:26 INFO - "buildnumber": 294, 14:04:26 INFO - "slavename": "talos-linux64-ix-023", 14:04:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:04:26 INFO - "platform": "ubuntu64_hw", 14:04:26 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 14:04:26 INFO - "branch": "fx-team", 14:04:26 INFO - "repo_path": "integration/fx-team", 14:04:26 INFO - "revision": "13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2", 14:04:26 INFO - "stage_platform": "linux64-pgo", 14:04:26 INFO - "builduid": "c1ecf460aaab4fa08a9ae89ec785c637", 14:04:26 INFO - "slavebuilddir": "test" 14:04:26 INFO - } 14:04:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 14:04:26 INFO - Running post-action listener: _resource_record_post_action 14:04:26 INFO - ##### 14:04:26 INFO - ##### Running download-and-extract step. 14:04:26 INFO - ##### 14:04:26 INFO - Running pre-action listener: _resource_record_pre_action 14:04:26 INFO - Running main action method: download_and_extract 14:04:26 INFO - mkdir: /builds/slave/test/build/tests 14:04:26 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')]} 14:04:26 INFO - https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:04:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 14:04:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 14:04:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 14:04:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:04:37 INFO - Downloaded 1361 bytes. 14:04:37 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 14:04:37 INFO - Using the following test package requirements: 14:04:37 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 14:04:37 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:37 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:04:37 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:37 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:04:37 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:37 INFO - u'jsshell-linux-x86_64.zip'], 14:04:37 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:37 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:04:37 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 14:04:37 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:37 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:04:37 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:37 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:04:37 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:37 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:04:37 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:37 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:04:37 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:04:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:04:37 INFO - https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:04:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 14:04:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 14:04:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 14:04:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:04:47 INFO - Downloaded 23857749 bytes. 14:04:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:04:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:04:48 INFO - Return code: 0 14:04:48 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:04:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:04:48 INFO - https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:04:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 14:04:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 14:04:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 14:04:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:04:51 INFO - Downloaded 23857749 bytes. 14:04:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:04:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:04:53 INFO - Return code: 0 14:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:04:53 INFO - https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:04:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 14:04:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 14:04:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 14:04:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:04:55 INFO - Downloaded 10840145 bytes. 14:04:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:04:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:04:55 INFO - Return code: 0 14:04:55 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')]} 14:04:55 INFO - https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:04:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 14:04:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 14:04:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 14:04:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:04:56 INFO - Downloaded 58339313 bytes. 14:04:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 14:04:56 INFO - mkdir: /builds/slave/test/properties 14:04:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:04:56 INFO - Writing to file /builds/slave/test/properties/build_url 14:04:56 INFO - Contents: 14:04:56 INFO - build_url:https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 14:04:57 INFO - Running post-action listener: _resource_record_post_action 14:04:57 INFO - Running post-action listener: set_extra_try_arguments 14:04:57 INFO - ##### 14:04:57 INFO - ##### Running populate-webroot step. 14:04:57 INFO - ##### 14:04:57 INFO - Running pre-action listener: _resource_record_pre_action 14:04:57 INFO - Running main action method: populate_webroot 14:04:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:04:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:04:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:04:57 INFO - '--tppagecycles', 14:04:57 INFO - '7'], 14:04:57 INFO - 'tests': ['tsvgm']}}, 14:04:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:04:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:04:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:04:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:04:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:04:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:04:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:04:57 INFO - 'talos_options': ['--disable-e10s'], 14:04:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:04:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:04:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:04:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:04:57 INFO - 'talos_options': ['--disable-e10s'], 14:04:57 INFO - 'tests': ['damp', 'tps']}, 14:04:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:04:57 INFO - 'tests': ['damp', 'tps']}, 14:04:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:04:57 INFO - 'tests': ['dromaeo_dom']}, 14:04:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:04:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:04:57 INFO - 'tests': ['a11yr', 14:04:57 INFO - 'ts_paint', 14:04:57 INFO - 'tpaint', 14:04:57 INFO - 'sessionrestore', 14:04:57 INFO - 'sessionrestore_no_auto_restore', 14:04:57 INFO - 'tabpaint']}, 14:04:57 INFO - 'other-e10s': {'tests': ['a11yr', 14:04:57 INFO - 'ts_paint', 14:04:57 INFO - 'tpaint', 14:04:57 INFO - 'sessionrestore', 14:04:57 INFO - 'sessionrestore_no_auto_restore', 14:04:57 INFO - 'tabpaint']}, 14:04:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:04:57 INFO - 'ts_paint', 14:04:57 INFO - 'tpaint', 14:04:57 INFO - 'sessionrestore', 14:04:57 INFO - 'sessionrestore_no_auto_restore', 14:04:57 INFO - 'tabpaint']}, 14:04:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:04:57 INFO - 'ts_paint', 14:04:57 INFO - 'tpaint', 14:04:57 INFO - 'sessionrestore', 14:04:57 INFO - 'sessionrestore_no_auto_restore', 14:04:57 INFO - 'tabpaint']}, 14:04:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:04:57 INFO - 'tests': ['a11yr', 14:04:57 INFO - 'ts_paint', 14:04:57 INFO - 'tpaint', 14:04:57 INFO - 'sessionrestore', 14:04:57 INFO - 'sessionrestore_no_auto_restore', 14:04:57 INFO - 'tabpaint']}, 14:04:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:04:57 INFO - 'tests': ['a11yr', 14:04:57 INFO - 'ts_paint', 14:04:57 INFO - 'tpaint', 14:04:57 INFO - 'sessionrestore', 14:04:57 INFO - 'sessionrestore_no_auto_restore', 14:04:57 INFO - 'tabpaint']}, 14:04:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:04:57 INFO - 'tests': ['tsvgx', 14:04:57 INFO - 'tsvgr_opacity', 14:04:57 INFO - 'tart', 14:04:57 INFO - 'tscrollx', 14:04:57 INFO - 'cart']}, 14:04:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:04:57 INFO - 'tsvgr_opacity', 14:04:57 INFO - 'tart', 14:04:57 INFO - 'tscrollx', 14:04:57 INFO - 'cart']}, 14:04:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:04:57 INFO - 'talos_options': ['--disable-e10s'], 14:04:57 INFO - 'tests': ['tp5o']}, 14:04:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:04:57 INFO - 'tests': ['tp5o']}, 14:04:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:04:57 INFO - 'talos_options': ['--disable-e10s', 14:04:57 INFO - '--xperf_path', 14:04:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:04:57 INFO - 'tests': ['tp5n']}, 14:04:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:04:57 INFO - 'talos_options': ['--xperf_path', 14:04:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:04:57 INFO - 'tests': ['tp5n']}}, 14:04:57 INFO - 'talos.zip': {'path': '', 14:04:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:04:57 INFO - Running post-action listener: _resource_record_post_action 14:04:57 INFO - ##### 14:04:57 INFO - ##### Running create-virtualenv step. 14:04:57 INFO - ##### 14:04:57 INFO - Running pre-action listener: _resource_record_pre_action 14:04:57 INFO - Running main action method: create_virtualenv 14:04:57 INFO - Creating virtualenv /builds/slave/test/build/venv 14:04: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 14:04:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:04:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:04:57 INFO - Using real prefix '/usr' 14:04:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:04:58 INFO - Installing distribute.............................................................................................................................................................................................done. 14:05:00 INFO - Installing pip.................done. 14:05:00 INFO - Return code: 0 14:05:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:05:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb22b418f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241b410>, '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 0x241c310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241c7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', '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 14:05:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:05:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:05:00 INFO - Using env: {'DISPLAY': ':0', 14:05:00 INFO - 'HOME': '/home/cltbld', 14:05:00 INFO - 'LANG': 'en_US.UTF-8', 14:05:00 INFO - 'LANGUAGE': 'en_US:en', 14:05:00 INFO - 'LOGNAME': 'cltbld', 14:05:00 INFO - 'MAIL': '/var/mail/cltbld', 14:05:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:00 INFO - 'MOZ_NO_REMOTE': '1', 14:05:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:00 INFO - 'NO_EM_RESTART': '1', 14:05:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:00 INFO - 'PWD': '/builds/slave/test', 14:05:00 INFO - 'SHELL': '/bin/bash', 14:05:00 INFO - 'SHLVL': '1', 14:05:00 INFO - 'TERM': 'linux', 14:05:00 INFO - 'TMOUT': '86400', 14:05:00 INFO - 'USER': 'cltbld', 14:05:00 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', 14:05:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:00 INFO - '_': '/tools/buildbot/bin/python'} 14:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:01 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 14:05:01 INFO - Cleaning up... 14:05:01 INFO - Return code: 0 14:05:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb22b418f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241b410>, '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 0x241c310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241c7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', '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 14:05:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 14:05:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 14:05:01 INFO - Using env: {'DISPLAY': ':0', 14:05:01 INFO - 'HOME': '/home/cltbld', 14:05:01 INFO - 'LANG': 'en_US.UTF-8', 14:05:01 INFO - 'LANGUAGE': 'en_US:en', 14:05:01 INFO - 'LOGNAME': 'cltbld', 14:05:01 INFO - 'MAIL': '/var/mail/cltbld', 14:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:01 INFO - 'MOZ_NO_REMOTE': '1', 14:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:01 INFO - 'NO_EM_RESTART': '1', 14:05:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:01 INFO - 'PWD': '/builds/slave/test', 14:05:01 INFO - 'SHELL': '/bin/bash', 14:05:01 INFO - 'SHLVL': '1', 14:05:01 INFO - 'TERM': 'linux', 14:05:01 INFO - 'TMOUT': '86400', 14:05:01 INFO - 'USER': 'cltbld', 14:05:01 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', 14:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:01 INFO - '_': '/tools/buildbot/bin/python'} 14:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:01 INFO - Downloading/unpacking psutil>=0.7.1 14:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:05:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:05:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:05:03 INFO - Installing collected packages: psutil 14:05:03 INFO - Running setup.py install for psutil 14:05:03 INFO - building 'psutil._psutil_linux' extension 14:05:03 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 14:05:04 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 14:05:04 INFO - building 'psutil._psutil_posix' extension 14:05:04 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 14:05:04 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 14:05:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:05:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:05:04 INFO - Successfully installed psutil 14:05:04 INFO - Cleaning up... 14:05:04 INFO - Return code: 0 14:05:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:05: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')]} 14:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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')]} 14:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb22b418f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241b410>, '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 0x241c310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241c7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', '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 14:05: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 14:05: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 mozsystemmonitor==0.0.0 14:05:04 INFO - Using env: {'DISPLAY': ':0', 14:05:04 INFO - 'HOME': '/home/cltbld', 14:05:04 INFO - 'LANG': 'en_US.UTF-8', 14:05:04 INFO - 'LANGUAGE': 'en_US:en', 14:05:04 INFO - 'LOGNAME': 'cltbld', 14:05:04 INFO - 'MAIL': '/var/mail/cltbld', 14:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:04 INFO - 'MOZ_NO_REMOTE': '1', 14:05:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:04 INFO - 'NO_EM_RESTART': '1', 14:05:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:04 INFO - 'PWD': '/builds/slave/test', 14:05:04 INFO - 'SHELL': '/bin/bash', 14:05:04 INFO - 'SHLVL': '1', 14:05:04 INFO - 'TERM': 'linux', 14:05:04 INFO - 'TMOUT': '86400', 14:05:04 INFO - 'USER': 'cltbld', 14:05:04 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', 14:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:04 INFO - '_': '/tools/buildbot/bin/python'} 14:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:05:06 INFO - Installing collected packages: mozsystemmonitor 14:05:06 INFO - Running setup.py install for mozsystemmonitor 14:05:06 INFO - Successfully installed mozsystemmonitor 14:05:06 INFO - Cleaning up... 14:05:07 INFO - Return code: 0 14:05:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:07 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 0x7fb22b418f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241b410>, '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 0x241c310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241c7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', '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 14:05:07 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 14:05:07 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 14:05:07 INFO - Using env: {'DISPLAY': ':0', 14:05:07 INFO - 'HOME': '/home/cltbld', 14:05:07 INFO - 'LANG': 'en_US.UTF-8', 14:05:07 INFO - 'LANGUAGE': 'en_US:en', 14:05:07 INFO - 'LOGNAME': 'cltbld', 14:05:07 INFO - 'MAIL': '/var/mail/cltbld', 14:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:07 INFO - 'MOZ_NO_REMOTE': '1', 14:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:07 INFO - 'NO_EM_RESTART': '1', 14:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:07 INFO - 'PWD': '/builds/slave/test', 14:05:07 INFO - 'SHELL': '/bin/bash', 14:05:07 INFO - 'SHLVL': '1', 14:05:07 INFO - 'TERM': 'linux', 14:05:07 INFO - 'TMOUT': '86400', 14:05:07 INFO - 'USER': 'cltbld', 14:05:07 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', 14:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:07 INFO - '_': '/tools/buildbot/bin/python'} 14:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:07 INFO - Downloading/unpacking blobuploader==1.2.4 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05:09 INFO - Downloading blobuploader-1.2.4.tar.gz 14:05:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:05:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:05:09 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 14:05:09 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 14:05:09 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 14:05:09 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 14:05:09 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 14:05:09 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 14:05:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:05:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:05:09 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 14:05:09 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 14:05:09 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 14:05:09 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 14:05:09 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 14:05:09 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 14:05:09 INFO - Downloading docopt-0.6.1.tar.gz 14:05:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:05:09 INFO - Installing collected packages: blobuploader, requests, docopt 14:05:09 INFO - Running setup.py install for blobuploader 14:05:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:05:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:05:09 INFO - Running setup.py install for requests 14:05:10 INFO - Running setup.py install for docopt 14:05:10 INFO - Successfully installed blobuploader requests docopt 14:05:10 INFO - Cleaning up... 14:05:10 INFO - Return code: 0 14:05:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:10 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 0x7fb22b418f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241b410>, '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 0x241c310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241c7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', '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 14:05:10 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 14:05:10 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 14:05:10 INFO - Using env: {'DISPLAY': ':0', 14:05:10 INFO - 'HOME': '/home/cltbld', 14:05:10 INFO - 'LANG': 'en_US.UTF-8', 14:05:10 INFO - 'LANGUAGE': 'en_US:en', 14:05:10 INFO - 'LOGNAME': 'cltbld', 14:05:10 INFO - 'MAIL': '/var/mail/cltbld', 14:05:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:10 INFO - 'MOZ_NO_REMOTE': '1', 14:05:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:10 INFO - 'NO_EM_RESTART': '1', 14:05:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:10 INFO - 'PWD': '/builds/slave/test', 14:05:10 INFO - 'SHELL': '/bin/bash', 14:05:10 INFO - 'SHLVL': '1', 14:05:10 INFO - 'TERM': 'linux', 14:05:10 INFO - 'TMOUT': '86400', 14:05:10 INFO - 'USER': 'cltbld', 14:05:10 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', 14:05:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:10 INFO - '_': '/tools/buildbot/bin/python'} 14:05:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:05:10 INFO - Running setup.py (path:/tmp/pip-8ZY0X5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:05:10 INFO - Running setup.py (path:/tmp/pip-X_GE0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:05:10 INFO - Running setup.py (path:/tmp/pip-TCbBWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:05:10 INFO - Running setup.py (path:/tmp/pip-1tE9yP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:05:10 INFO - Running setup.py (path:/tmp/pip-A1vtfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:05:11 INFO - Running setup.py (path:/tmp/pip-Ia0WzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:05:11 INFO - Running setup.py (path:/tmp/pip-n9TCqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:05:11 INFO - Running setup.py (path:/tmp/pip-BUarIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:05:11 INFO - Running setup.py (path:/tmp/pip-rhrweb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:05:11 INFO - Running setup.py (path:/tmp/pip-utWpoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:05:11 INFO - Running setup.py (path:/tmp/pip-6ppHOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:05:11 INFO - Running setup.py (path:/tmp/pip-TiVtos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:05:11 INFO - Running setup.py (path:/tmp/pip-MY7rzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:05:11 INFO - Running setup.py (path:/tmp/pip-hbraQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:05:11 INFO - Running setup.py (path:/tmp/pip-9ZgM9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:05:11 INFO - Running setup.py (path:/tmp/pip-70Yhqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:05:11 INFO - Running setup.py (path:/tmp/pip-_F9QHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:05:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:05:11 INFO - Running setup.py install for manifestparser 14:05:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:05:11 INFO - Running setup.py install for mozcrash 14:05:12 INFO - Running setup.py install for mozdebug 14:05:12 INFO - Running setup.py install for mozdevice 14:05:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:05:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:05:12 INFO - Running setup.py install for mozfile 14:05:12 INFO - Running setup.py install for mozhttpd 14:05:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:05:12 INFO - Running setup.py install for mozinfo 14:05:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:05:12 INFO - Running setup.py install for mozInstall 14:05:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:05:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:05:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:05:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:05:12 INFO - Running setup.py install for mozleak 14:05:12 INFO - Running setup.py install for mozlog 14:05:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:05:12 INFO - Running setup.py install for moznetwork 14:05:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:05:13 INFO - Running setup.py install for mozprocess 14:05:13 INFO - Running setup.py install for mozprofile 14:05:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:05:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:05:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:05:13 INFO - Running setup.py install for mozrunner 14:05:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:05:13 INFO - Running setup.py install for mozscreenshot 14:05:13 INFO - Running setup.py install for moztest 14:05:13 INFO - Running setup.py install for mozversion 14:05:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:05:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:05:13 INFO - Cleaning up... 14:05:13 INFO - Return code: 0 14:05:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:05:13 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')]} 14:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:13 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')]} 14:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:13 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 0x7fb22b418f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241b410>, '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 0x241c310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241c7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', '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 14:05:13 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 14:05:13 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 14:05:13 INFO - Using env: {'DISPLAY': ':0', 14:05:13 INFO - 'HOME': '/home/cltbld', 14:05:13 INFO - 'LANG': 'en_US.UTF-8', 14:05:13 INFO - 'LANGUAGE': 'en_US:en', 14:05:13 INFO - 'LOGNAME': 'cltbld', 14:05:13 INFO - 'MAIL': '/var/mail/cltbld', 14:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:13 INFO - 'MOZ_NO_REMOTE': '1', 14:05:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:13 INFO - 'NO_EM_RESTART': '1', 14:05:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:13 INFO - 'PWD': '/builds/slave/test', 14:05:13 INFO - 'SHELL': '/bin/bash', 14:05:13 INFO - 'SHLVL': '1', 14:05:13 INFO - 'TERM': 'linux', 14:05:13 INFO - 'TMOUT': '86400', 14:05:13 INFO - 'USER': 'cltbld', 14:05:13 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', 14:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:13 INFO - '_': '/tools/buildbot/bin/python'} 14:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:05:13 INFO - Running setup.py (path:/tmp/pip-IvNuNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:05:13 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)) 14:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:05:13 INFO - Running setup.py (path:/tmp/pip-7RcfZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:05:14 INFO - Running setup.py (path:/tmp/pip-0GnSfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:05:14 INFO - Running setup.py (path:/tmp/pip-hB3jz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:05:14 INFO - Running setup.py (path:/tmp/pip-ZL2pBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:05:14 INFO - Running setup.py (path:/tmp/pip-te_Zyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:05:14 INFO - Running setup.py (path:/tmp/pip-r8aNfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:05:14 INFO - Running setup.py (path:/tmp/pip-le8Fm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:05:14 INFO - Running setup.py (path:/tmp/pip-zbHk5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:05:14 INFO - Running setup.py (path:/tmp/pip-xgl1OH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:05:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:05:14 INFO - Running setup.py (path:/tmp/pip-BMiV5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:05:14 INFO - Running setup.py (path:/tmp/pip-fLjOSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:05:14 INFO - Running setup.py (path:/tmp/pip-axMiHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:05:14 INFO - Running setup.py (path:/tmp/pip-HO5DQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:05:14 INFO - Running setup.py (path:/tmp/pip-CBv2oe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:05:14 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)) 14:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:05:14 INFO - Running setup.py (path:/tmp/pip-Za6zbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:05:15 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)) 14:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:05:15 INFO - Running setup.py (path:/tmp/pip-G7d1WI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:05:15 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)) 14:05:15 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)) 14:05:15 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)) 14:05:15 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)) 14:05:15 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)) 14:05:15 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)) 14:05:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05:16 INFO - Downloading blessings-1.6.tar.gz 14:05:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:05:17 INFO - Installing collected packages: blessings 14:05:17 INFO - Running setup.py install for blessings 14:05:17 INFO - Successfully installed blessings 14:05:17 INFO - Cleaning up... 14:05:17 INFO - Return code: 0 14:05:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:05:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:05:17 INFO - Reading from file tmpfile_stdout 14:05:17 INFO - Current package versions: 14:05:17 INFO - argparse == 1.2.1 14:05:17 INFO - blessings == 1.6 14:05:17 INFO - blobuploader == 1.2.4 14:05:17 INFO - docopt == 0.6.1 14:05:17 INFO - manifestparser == 1.1 14:05:17 INFO - mozInstall == 1.12 14:05:17 INFO - mozcrash == 0.17 14:05:17 INFO - mozdebug == 0.1 14:05:17 INFO - mozdevice == 0.48 14:05:17 INFO - mozfile == 1.2 14:05:17 INFO - mozhttpd == 0.7 14:05:17 INFO - mozinfo == 0.9 14:05:17 INFO - mozleak == 0.1 14:05:17 INFO - mozlog == 3.1 14:05:17 INFO - moznetwork == 0.27 14:05:17 INFO - mozprocess == 0.22 14:05:17 INFO - mozprofile == 0.28 14:05:17 INFO - mozrunner == 6.11 14:05:17 INFO - mozscreenshot == 0.1 14:05:17 INFO - mozsystemmonitor == 0.0 14:05:17 INFO - moztest == 0.7 14:05:17 INFO - mozversion == 1.4 14:05:17 INFO - psutil == 3.1.1 14:05:17 INFO - requests == 1.2.3 14:05:17 INFO - wsgiref == 0.1.2 14:05:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:17 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 0x7fb22b418f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241b410>, '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 0x241c310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241c7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', '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 14:05:17 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 14:05:17 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 14:05:17 INFO - Using env: {'DISPLAY': ':0', 14:05:17 INFO - 'HOME': '/home/cltbld', 14:05:17 INFO - 'LANG': 'en_US.UTF-8', 14:05:17 INFO - 'LANGUAGE': 'en_US:en', 14:05:17 INFO - 'LOGNAME': 'cltbld', 14:05:17 INFO - 'MAIL': '/var/mail/cltbld', 14:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:17 INFO - 'MOZ_NO_REMOTE': '1', 14:05:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:17 INFO - 'NO_EM_RESTART': '1', 14:05:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:17 INFO - 'PWD': '/builds/slave/test', 14:05:17 INFO - 'SHELL': '/bin/bash', 14:05:17 INFO - 'SHLVL': '1', 14:05:17 INFO - 'TERM': 'linux', 14:05:17 INFO - 'TMOUT': '86400', 14:05:17 INFO - 'USER': 'cltbld', 14:05:17 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', 14:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:17 INFO - '_': '/tools/buildbot/bin/python'} 14:05:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:17 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)) 14:05:17 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)) 14:05:17 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)) 14:05:17 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)) 14:05:17 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)) 14:05:17 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)) 14:05:17 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)) 14:05:17 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)) 14:05:17 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)) 14:05:17 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)) 14:05:17 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)) 14:05:17 INFO - Cleaning up... 14:05:17 INFO - Return code: 0 14:05:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb22b418f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241b410>, '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 0x241c310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241c7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', '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 14:05:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:05:17 INFO - Using env: {'DISPLAY': ':0', 14:05:17 INFO - 'HOME': '/home/cltbld', 14:05:17 INFO - 'LANG': 'en_US.UTF-8', 14:05:17 INFO - 'LANGUAGE': 'en_US:en', 14:05:17 INFO - 'LOGNAME': 'cltbld', 14:05:17 INFO - 'MAIL': '/var/mail/cltbld', 14:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:17 INFO - 'MOZ_NO_REMOTE': '1', 14:05:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:17 INFO - 'NO_EM_RESTART': '1', 14:05:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:17 INFO - 'PWD': '/builds/slave/test', 14:05:17 INFO - 'SHELL': '/bin/bash', 14:05:17 INFO - 'SHLVL': '1', 14:05:17 INFO - 'TERM': 'linux', 14:05:17 INFO - 'TMOUT': '86400', 14:05:17 INFO - 'USER': 'cltbld', 14:05:17 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', 14:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:17 INFO - '_': '/tools/buildbot/bin/python'} 14:05:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:17 INFO - Downloading/unpacking jsonschema 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:05:19 INFO - Downloading/unpacking functools32 (from jsonschema) 14:05:19 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 14:05:19 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 14:05:19 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 14:05:19 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 14:05:19 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 14:05:19 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 14:05:20 INFO - Downloading functools32-3.2.3-2.tar.gz 14:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:05:20 INFO - warning: no files found matching '*.txt' 14:05:20 INFO - no previously-included directories found matching 'build' 14:05:20 INFO - no previously-included directories found matching 'dist' 14:05:20 INFO - no previously-included directories found matching '.git*' 14:05:20 INFO - Installing collected packages: jsonschema, functools32 14:05:20 INFO - Running setup.py install for functools32 14:05:20 INFO - warning: no files found matching '*.txt' 14:05:20 INFO - no previously-included directories found matching 'build' 14:05:20 INFO - no previously-included directories found matching 'dist' 14:05:20 INFO - no previously-included directories found matching '.git*' 14:05:20 INFO - Successfully installed jsonschema functools32 14:05:20 INFO - Cleaning up... 14:05:20 INFO - Return code: 0 14:05:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb22b418f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241b410>, '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 0x241c310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241c7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', '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 14:05:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:05:20 INFO - Using env: {'DISPLAY': ':0', 14:05:20 INFO - 'HOME': '/home/cltbld', 14:05:20 INFO - 'LANG': 'en_US.UTF-8', 14:05:20 INFO - 'LANGUAGE': 'en_US:en', 14:05:20 INFO - 'LOGNAME': 'cltbld', 14:05:20 INFO - 'MAIL': '/var/mail/cltbld', 14:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:20 INFO - 'MOZ_NO_REMOTE': '1', 14:05:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:20 INFO - 'NO_EM_RESTART': '1', 14:05:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:20 INFO - 'PWD': '/builds/slave/test', 14:05:20 INFO - 'SHELL': '/bin/bash', 14:05:20 INFO - 'SHLVL': '1', 14:05:20 INFO - 'TERM': 'linux', 14:05:20 INFO - 'TMOUT': '86400', 14:05:20 INFO - 'USER': 'cltbld', 14:05:20 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', 14:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:20 INFO - '_': '/tools/buildbot/bin/python'} 14:05:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:20 INFO - Downloading/unpacking flake8 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:05:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:05:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:05:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:05:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:05:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:05:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:05:23 INFO - no previously-included directories found matching 'docs/_build' 14:05:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:05:23 INFO - Running setup.py install for pep8 14:05:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:05:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:05:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:05:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:05:23 INFO - no previously-included directories found matching 'docs/_build' 14:05:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:05:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:05:23 INFO - Cleaning up... 14:05:23 INFO - Return code: 0 14:05:23 INFO - Running post-action listener: _resource_record_post_action 14:05:23 INFO - Running post-action listener: _start_resource_monitoring 14:05:23 INFO - Starting resource monitoring. 14:05:23 INFO - ##### 14:05:23 INFO - ##### Running install step. 14:05:23 INFO - ##### 14:05:23 INFO - Running pre-action listener: _resource_record_pre_action 14:05:23 INFO - Running main action method: install 14:05:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:05:23 INFO - Reading from file tmpfile_stdout 14:05:23 INFO - Detecting whether we're running mozinstall >=1.0... 14:05:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:05:23 INFO - Reading from file tmpfile_stdout 14:05:23 INFO - Output received: 14:05:23 INFO - Usage: mozinstall [options] installer 14:05:23 INFO - Options: 14:05:23 INFO - -h, --help show this help message and exit 14:05:23 INFO - -d DEST, --destination=DEST 14:05:23 INFO - Directory to install application into. [default: 14:05:23 INFO - "/builds/slave/test"] 14:05:23 INFO - --app=APP Application being installed. [default: firefox] 14:05:23 INFO - mkdir: /builds/slave/test/build/application 14:05:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:05:40 INFO - Reading from file tmpfile_stdout 14:05:40 INFO - Output received: 14:05:40 INFO - /builds/slave/test/build/application/firefox/firefox 14:05:40 INFO - Running post-action listener: _resource_record_post_action 14:05:40 INFO - ##### 14:05:40 INFO - ##### Running run-tests step. 14:05:40 INFO - ##### 14:05:40 INFO - Running pre-action listener: _resource_record_pre_action 14:05:40 INFO - Running main action method: run_tests 14:05:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:05:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:05:40 INFO - Python 2.7.3 14:05:40 INFO - Return code: 0 14:05:40 INFO - grabbing minidump binary from tooltool 14: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')]} 14:05:40 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241b410>, '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 0x241c310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241c7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:05:40 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 14:05:40 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 14:05:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:05:40 INFO - Return code: 0 14:05:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:05:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:05:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:05:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:05:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:05:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:05:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:05:41 INFO - Return code: 0 14:05:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:05:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:05:41 INFO - Using env: {'DISPLAY': ':0', 14:05:41 INFO - 'HOME': '/home/cltbld', 14:05:41 INFO - 'LANG': 'en_US.UTF-8', 14:05:41 INFO - 'LANGUAGE': 'en_US:en', 14:05:41 INFO - 'LOGNAME': 'cltbld', 14:05:41 INFO - 'MAIL': '/var/mail/cltbld', 14:05:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:05:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:41 INFO - 'MOZ_NO_REMOTE': '1', 14:05:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:05:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:41 INFO - 'NO_EM_RESTART': '1', 14:05:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:41 INFO - 'PWD': '/builds/slave/test', 14:05:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:05:41 INFO - 'SHELL': '/bin/bash', 14:05:41 INFO - 'SHLVL': '1', 14:05:41 INFO - 'TERM': 'linux', 14:05:41 INFO - 'TMOUT': '86400', 14:05:41 INFO - 'USER': 'cltbld', 14:05:41 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162', 14:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:41 INFO - '_': '/tools/buildbot/bin/python'} 14:05:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:05:41 INFO - mozversion application_buildid: 20160424113022 14:05:41 INFO - mozversion application_changeset: 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 14:05:41 INFO - mozversion application_display_name: Nightly 14:05:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:05:41 INFO - mozversion application_name: Firefox 14:05:41 INFO - mozversion application_remotingname: firefox 14:05:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:05:41 INFO - mozversion application_vendor: Mozilla 14:05:41 INFO - mozversion application_version: 48.0a1 14:05:41 INFO - mozversion platform_buildid: 20160424113022 14:05:41 INFO - mozversion platform_changeset: 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 14:05:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:05:41 INFO - mozversion platform_version: 48.0a1 14:05:41 INFO - using testdate: 1461531941 14:05:41 INFO - actual date: 1461531941 14:05:41 INFO - starting webserver on 'localhost:44704' 14:05:41 INFO - SUITE-START | Running 2 tests 14:05:41 INFO - TEST-START | dromaeo_css 14:05:41 INFO - operating with platform_type : linux_ 14:05:41 INFO - Initialising browser for dromaeo_css test... 14:05:42 INFO - TEST-INFO | started process 3326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNePRJ9/profile http://localhost:44704/getInfo.html) 14:05:48 INFO - PROCESS | 3326 | 14:05:48 INFO - PROCESS | 3326 | (process:3396): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:05:49 INFO - PROCESS | 3326 | __metrics Screen width/height:1600/1200 14:05:49 INFO - PROCESS | 3326 | colorDepth:24 14:05:49 INFO - PROCESS | 3326 | Browser inner width/height: 1024/697 14:05:49 INFO - PROCESS | 3326 | __metrics 14:05:49 ERROR - PROCESS | 3326 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 14:05:50 INFO - TEST-INFO | 3326: exit 0 14:05:50 INFO - Browser initialized. 14:05:50 INFO - Running cycle 1/1 for dromaeo_css test... 14:05:50 INFO - TEST-INFO | started process 3445 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNePRJ9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:05:52 INFO - PROCESS | 3445 | 14:05:52 INFO - PROCESS | 3445 | (process:3507): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:06:02 INFO - PROCESS | 3445 | RSS: Main: 164663296 14:06:02 INFO - PROCESS | 3445 | 14:06:51 INFO - PROCESS | 3445 | 1461532011927 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] 14:08:58 INFO - PROCESS | 3445 | Cycle 1(1): loaded http://localhost:44704/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44704/tests/dromaeo/cssquery-ext.html) 14:09:00 INFO - PROCESS | 3445 | RSS: Main: 168484864 14:09:00 INFO - PROCESS | 3445 | 14:12:02 INFO - PROCESS | 3445 | Cycle 1(1): loaded http://localhost:44704/tests/dromaeo/cssquery-ext.html (next: http://localhost:44704/tests/dromaeo/cssquery-jquery.html) 14:12:02 INFO - PROCESS | 3445 | RSS: Main: 166191104 14:12:02 INFO - PROCESS | 3445 | 14:15:04 INFO - PROCESS | 3445 | Cycle 1(1): loaded http://localhost:44704/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44704/tests/dromaeo/cssquery-mootools.html) 14:15:05 INFO - PROCESS | 3445 | RSS: Main: 167612416 14:15:05 INFO - PROCESS | 3445 | 14:18:06 INFO - PROCESS | 3445 | Cycle 1(1): loaded http://localhost:44704/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44704/tests/dromaeo/cssquery-prototype.html) 14:18:07 INFO - PROCESS | 3445 | RSS: Main: 164896768 14:18:07 INFO - PROCESS | 3445 | 14:21:09 INFO - PROCESS | 3445 | Cycle 1(1): loaded http://localhost:44704/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44704/tests/dromaeo/cssquery-yui.html) 14:21:10 INFO - PROCESS | 3445 | RSS: Main: 166096896 14:21:10 INFO - PROCESS | 3445 | 14:24:12 INFO - PROCESS | 3445 | Cycle 1(1): loaded http://localhost:44704/tests/dromaeo/cssquery-yui.html (next: http://localhost:44704/tests/dromaeo/cssquery-dojo.html) 14:24:12 INFO - PROCESS | 3445 | RSS: Main: 168554496 14:24:12 INFO - PROCESS | 3445 | 14:24:12 INFO - PROCESS | 3445 | __start_tp_report 14:24:12 INFO - PROCESS | 3445 | _x_x_mozilla_page_load 14:24:12 INFO - PROCESS | 3445 | _x_x_mozilla_page_load_details 14:24:12 INFO - PROCESS | 3445 | |i|pagename|runs| 14:24:12 INFO - PROCESS | 3445 | |0;dojo.html;3150.7951983121097;3178.856951437185;3183.7930534515253;3210.0690799668096;3235.91910202245;5236.136037553799;5273.024490469261;5286.814961476349;5341.973290133554;5388.43421440749;5124.461931497195;5232.005918875411;5316.149416093426;5345.465453454653;5375.650582712131;4772.093302272575;4826.32431459596;4889.437714662806;4890.853274401774;4942.752862356886;602.6460083505202;4639.257718764999;4770.0459908018365;4794.2329227323635;4841.225403935371;4754.524547545252;4768.046390721852;4774;4788.545088216614;4816.686915350491;4760.666753327268;4796.736179510161;4804.063207674505;4826.227803551415;4849.9030019399415;4688.812447502096;4800.279958006292;4857.344258525124;4873.731944743037;4893.75531223441;5190.974045129788;5205.141151709963;5217.3999990000875;5219.269302297681;5246.711430871301;5448.346198456171;5666.065099258617;5703.201551782754;5704.1443783432815;5706.971465142689;5777.393373695743;5793.362730099686;5813.47678708914;5853.64878107315;5869.706514674292;5227.111391063529;5229.686218826844;5233.581313494911;5235.188545775411;5245.134552798783;5579.218909352694;5655.123455864347;5663.490285874291;5667.206591077253;5710.628809127476;5554.166874968787;5558.360788509292;5599.832005039855;5611.523020543218;5636.210930469738;5667.206591077253;5705.2012718219485;5708.686022268732;5710.7430165642645;5712.65724056558;5637.464440878192;5649.802256921071;5675.4324567543745;5684.147377893267;5723.141528770718;5626.634268772516;5633.6619802811965;5657.2928383952;5662.518685911744;5673.035583950738;4271.039016221387;4301;4307.978460107679;4312.805923733376;4348.412964249849;3837.13664425502;3874.8256328465286;3893.0851249956236;3903.121797595575;3911.9608803911597;5361.276227709287;5400.324959380077;5431.022415965085;5437.32033495813;5450.91823622646;5604.271444712243;5661.122526008476;5729.54163666914;5760.42395760429;5767.452092051249;5266.526012658879;5366.7853285868905;5384.084705599979;5387.6228663993925;5444.373897001817;4858.6356023297685;4915.901681966412;4922.089413458521;4932.383452068491;4945.703257804544;5677.460641239076;5694.06048002075;5902.439268269508;5906.911396329058;5921.3782552831335;5682.772689092472;5712.228849105484;5726.942730572808;5734.2258795062135;5769.307683077974;5799.797007104731;5830.416958304136;5832.912506312324;5834.416558344132;5847.356790752929;5480.506754392124;5579.246801681721;5587.413321601173;5667.291588551431;5732.541396688338;5612.7754889803955;5654.15187721845;5660.066089095253;5674.687892165971;5735.598508104393;5464.153056276284;5478.397376288684;5490.450954904478;5520.254765606592;5529.972350138223;5478.780848766005;5491.75287112073;5517.44825517445;5528.530074943675;5549.722513874371;5358.437364076716;5378.489043540857;5415.106507426231;5420.186971954081;5455.1544510600925;5504.642108415789;5519.889602207853;5534.667919924817;5538.224648549287;5542.972285138549;5343.732813359395;5394.379646340564;5423.674579525241;5434.402215756343;5446.101393270066 14:24:12 INFO - PROCESS | 3445 | |1;ext.html;592.9377415371385;649.2760571962262;659.5679829711538;667.1094089390666;691.5574032619121;37352.626473735254;38149.23701525968;38424;38485.80757096214;38505.807470962674;39397.60602393975;39576.20847583046;39658;39893;39911.80044099775;8541.572921353938;8682.435641683302;8782.560871956408;8788.51663158526;8790.384673072887;8721.476711397305;8763.255123314526;8819.95590022049;8855.33584981129;8860.556972151398;8864.069272726367;8874.2456891164;8887.15572020657;8887.511186884718;8907.465552066864;8803.691870784492;8806.779830504256;8874.778130546723;8876.689315873913;8894.066123057082;8782.297416206751;8870.955645221733;8884.466931984101;8896.733098007071;8897.599608017656;31354.686453135404;40597;40743.18513629711;40873.79563102195;41163.794181028905;3120.687931206884;3138.10563989262;3152.3537674776617;3220.6940340667597;3241.33552621712;28181.85909070462;28363.432731345256;28738.568921466194;28770;28953.276168095756;4079.63283304504;4085;4104.938425923614;4184.623383895434;4268;180645;181235;181863;182700;183617;32370;34690.47964280538;35574.28851422957;35671.82164089163;35675.28649427049;33313.66686333154;33433.16417089592;33469.330613387596;33684;33687.32625347479;14921.850781492263;15118.09291442517;15119.319630616648;15122.168280744665;15134.243287835565;12775.169613975062;12904.032197585218;12904.354782260849;13065.804012939814;13466.394012269275;8359.456635318686;8394.580270986426;8441.113683063313;8450.154984501623;8510.106438823834;3263.184203949013;3267.281198136406;3303.620083690358;3324.0858763840138;3363.2264579146445;1039.4906495816995;1082.5290998415558;1133.9489722962487;1165.0504838556549;1165.5046605192758;30924.917627882915;31359.37281254408;31723;32148.678513215036;32338.514922276183;1112.7385064509833;1127.3630398824637;1157.7626586549736;1176.4823477669797;1223.7858374784378;16566.17169141538;16652.50042498727;16818.57953551171;16881.493555193363;16918.407855725236;450441;455223;455933;457108;458810;56035;57570.136447953315;57688.71155644279;57852.4214757847;57898.7105064472;1308.2918358996976;1339.41065930992;1340.7117469743857;1341.4298922957703;1483.4585376337775;30296.394072117993;31004.68995310018;31828.204294892814;31841.363172735953;32528.186795330304;49980.500194997585;50473.49526504688;50885.491145088075;50899.236511451614;50922.74538627283;373442;373804;377258;377934;377950;27709.58435623427;28162.29594260078;28305.71694283031;44201.77899110484;45354.54645453503;42044.90348899454;42127.36808947807;42363.57636423596;42375.788121059195;46733.532664672915;38917.61082389253;39640.40539391854;39763.80118099391;40004.199916000936;40471.59528404678;38167.2366552673;38201.23597528089;39175.41236881392;39584.40623390709;40116;36627.633723662424;39753;42041.36937945872;42229.57770422257;42867;33152.50271245885;34808.30383392267;34881.65118348886;35567.82216088903;35680;27553.17340479789;27689.584656230574;27748;27893.60531973434;27905 14:24:12 INFO - PROCESS | 3445 | |2;jquery.html;384.8999260192349;384.90762217067913;394.5758309816947;395.4503240495711;399.7151906503964;13846.446142154302;17440.38958636448;17674.73487897681;18219;18241;17629.471115866512;17791.288348466078;17918.64162716745;18462;18511.814881851176;5448.536874365664;5520.641158324706;5636.971815140919;5662.094064949609;5712.22884910536;5652.06740887753;5654.37801841797;5665;5676.943230567704;5685.090385538315;5446.537044351235;5573.386927437979;5723.055695810186;5723.456271654186;5734.397888221739;5680.318361796591;5698.202251684746;5719.6854173020465;5735.4551317624755;5736.426357364272;5022.246663000543;5283.233931079972;5319.414864364917;5326.547243484309;5329.973350133263;11320.716982075432;11430.828537571942;11448.71378215543;12264.080193985486;12282.877171228352;1980.742503474539;2060.9690854637192;2094.73268672454;2098.31804663485;2132.2217390652454;9650.565724542412;9736.610535578568;10080.344777589433;10976.670699879016;14263.429462821474;1853.369854249548;1862.5995410986568;1926.5857840564206;1946.5036415714044;1988.1848442138653;80765.59617201876;80812.59593701994;81072.59463702644;82702.17297827065;82852;10143.442110683984;10144.087032166994;10270.845937310947;10329.793404131875;10405.739856503496;12828.615141545768;13136;14282.428702851978;14282.500112496013;14353.85646143546;6110.26676798787;6193.4425901669065;7245.710171593183;8113.675452981933;8188.2630563249595;7792.025996750406;7798;8054.5569993650915;8062.193780621891;8078.555679437687;4481;4485.820567177277;4518;4525.909481810345;4619.8450387403145;1823.9088045597666;1900.268396667293;1938.321587444383;1938.6607343715075;1977.2684106880547;1001.4742260313249;1031.896810318962;1064.5369264370024;1079.3469950679882;1080.6109800471825;17611.119444028005;17655.73516397255;17946.19242134081;18411.907940460213;19011.239550418104;1004.6533945788691;1014.051861509491;1014.4521958142668;1023.534291897185;1067.6156583629886;10152.695419137437;10605.73485662849;10701.571937122584;10705.571777128827;10705.83941240882;465037;465208;471051;493908;500052;91190;94451.52774236085;94557;96835;97920;994.4928086675848;1008.2690049714046;1010.2170817616407;1031.515187861704;1039.740064983754;16334.591635208739;16480.505584832474;16736;16861.325546978474;16913.66172676564;29530.409391811612;37316;38885.80557097196;39426.21147576975;40433.39349909695;49764.760585773;52145.478545214064;52263;52831;53003.20495192498;5302.39022512416;5458.918116228339;7801.804954876174;7887.329576986021;8011.759647210593;11418.714532136763;11966.820497692368;12361.64021957602;12427.31649759272;12627.621171364874;11390.487428065922;11416.31502109876;11432.085433165494;11486.712832179262;11493.597724079616;10981.3960232188;10984.560617575207;11218.270812397168;11321.377324247245;11575.594854180063;6299.307076221704;8275.50346979183;11171.944140279571;11732.472038758448;11923;7801.375889928679;7822.72620458304;7915.960420198093;8067.193280671886;8397.748067557983;11426.485808138488;11447.427628618369;11745.47145378477;11956.820647690118;12080.21478603888 14:24:12 INFO - PROCESS | 3445 | |3;mootools.html;1495.5139579636618;1545.3277824146496;1702.9489115326544;1767.8497327727143;1819.3086627081707;7015.508914375983;7410.814729631762;7456.366208872246;7692.076950765909;7821.608919554021;7398.704051837934;7403.777886663409;7403.888941665866;7472.103347598282;7480.4389670774635;2045.0797141286444;2059.0734169623656;2062.8762274263518;2071.6167509010806;2082.5834833033377;2059.979400206001;2080.6046851098245;2097.402240361497;2100.33839340608;2100.674395468705;2070.33749200256;2087.217293514932;2092.163134746106;2093.916243350272;2095.706601075843;2056.0233888902726;2058.207590077823;2064.0917996081675;2065.8967051647433;2068.9379318620468;2007.65869802134;2013.2047841102828;2014.3151328548363;2022.5651484930663;2025.4430031741242;7461.776146715607;7536.547807131589;7545.094588649343;7553.018107645971;7556.433267504959;4694.835680751157;4819.180739274332;4842.322074909515;4850.854474365775;4860.8298709545;3079.2609773654162;3148.7481001520055;3165.6517783043846;3191.5850939377965;3238.789478683878;1011.3224139826334;1043.2592859070069;1043.3948309980194;1049.6798476464676;1063.558623171383;10446;10448;10468.79062418755;10478.63324783629;10488.58045678172;4085.632293093636;4116.75299482032;4233.407322974786;4243.448351714257;4309.676774241944;8828.205461508494;8909.777255568662;8912.777180570538;8913.5097569633;8960.417572857743;4839.975800120977;4856.368672072608;4858.416989961157;4905.9754701226975;4914.287428322873;4038.434619153321;4353.978230108829;4426.1369033038245;4510.368548403226;4511.887202819956;3631.782093074424;3670.724695647837;3715.368387374153;3746.1758413149064;3807.9048023799073;1212.927224366541;1241.0815996162773;1242.9005679545614;1248.5318005747845;1277.2336598041102;875.9868601970976;1003.5233264199593;1010.2019404670228;1015.0864222200079;1025.477006726575;5428.4028756836715;5645.858853528694;5764.279465066867;5768.480836724715;5776.768929242783;409.2653686632502;413.1839616756941;418.1073408273358;418.54169684195756;421.51736261979863;3991.3214753491975;4013.819378127991;4026.275270451288;4033.8991525212105;4125.938110928339;9200.171984521425;9499.192568631745;9583.90416095844;9586.233101351874;9605.855912161323;9454.101860323259;9517.428954262767;9539.3322467428;9543.141117299338;9547;7111.7866464006165;7361.043064401593;7423.183449820515;7425.851482970418;7459.70161193557;5320.707361095177;5347.304850369427;5388.110961691277;5479.6164268500825;5598.440155984532;8376.53929033909;8647.437916535406;8682.565871706263;8742.169493898069;8898.332625053017;8433.957830211055;8545.145485451405;8679.956600216958;8680;8724.607392667218;6367.331430199948;6722.731090756315;6880.346367095118;6905.585664860125;6971.930280697128;7517.548947063194;7517.887231691639;7574.3183113520045;7576.507527010726;7614.885776713243;6727.125473688389;6930.7227710891;6946.8610627788175;6974.407175390149;7038.436925046087;6673.89880669684;6736.157980252468;6812.863742725019;6819.522633415613;6843.486738494732;6211.81364559064;6363.681815909279;6411.71147298382;6420.839479013062;6438.6136831790245;4866.975665121652;4883.60931125502;4955.900881982412;4963.354763880672;4987.476314986874;4912.263160525807;4938.000109955474;4990.950090499194;4992.15133427311;5069.518395752398 14:24:12 INFO - PROCESS | 3445 | |4;prototype.html;321.52735478846097;322.0355829440412;331.09280571234825;331.49281599153295;334.1779223111146;5808.941910580893;5916.674582897945;5929.762809487626;6008.386351936911;6055.152278680983;5958.702064896759;5964.314103878055;5976.416249693834;5976.52187824974;5991.550633702468;4538.432695913011;4539.432570928634;4562.783237817331;4585.2663573828195;4607.769611519428;4449.84425545106;4577.793999270041;4578.267477203648;4593.150267200562;4593.701409408394;4510.556621880999;4510.954890451087;4535.138323718499;4536.319552067183;4553.453585569736;4527.864164075083;4540.137373898949;4540.636749060101;4542.136993971135;4546.659000574954;3898.8440462381473;3972.443857859902;3994.1612261424843;3995.820188091514;3996.442276942759;5659.320881494205;5719.742611582489;5722.914156287659;5741.971290143564;5775.711214439303;5284.022455845642;5309.840704778863;5317.787288508455;5323.22813192085;5337.11937530307;6041.426064523863;6242.969909964896;6300.842478938017;6301.96849015752;6303.9684801575695;4352.042550638855;4391.121775644884;4391.98984233628;4403.141387429452;4426.790576831803;6395.456386207178;6570.671466426659;6581.572197807127;6589.901151482732;6603.603783772989;4486.506484286726;4493.595576398075;4505.977470112629;4512.495564961412;4521.1409832131785;4511.872031992002;4520.773961301922;4529.275315949432;4533.5013148553635;4546.590806827401;5136.845894623168;5221.295125158055;5258.447862974409;5266.7366631668265;5267.815626453055;6174.9073763893575;6333.20834895638;6368.172137622079;6373.649449280241;6390.616563006234;1558.3377064747438;1570.225645021119;1574.6457047164527;1574.7795308656569;1576.8107827060596;1017.9134773544183;1018.2414101494434;1044.3942513342242;1049.8057859295902;1059.5443959097508;6127.640858269509;6129.264488261437;6145.66198859067;6157.384261573807;6177.1969643946895;6482.870342593122;6521.67391630426;6536.306986552582;6547.377999090078;6553.082568440422;5955.702214889238;6087.878242435215;6117.908231376533;6142.648617304186;6169.475594574422;3494.3011397720556;3507.877224297137;3523.2777280657915;3535.8939231823088;3540.46892966052;6567.868642627216;6722.6302553359055;6736.225334086643;6778.796636100925;6780.525363224626;6580.2103747549645;6612.735490580323;6643.933560664428;6702.731890724316;6717.361850624183;6558.770443034471;6707.295733947963;6710.127683401127;6809.625470599066;6817.420519255819;6465.866264132919;6557.114789503356;6562.212534495891;6573.647439805638;6596.934030659632;6382.138411314599;6414.807555773335;6462.644554549545;6475.838104047248;6492.220933487823;6716.731330746716;6719.932800672126;6721.966390168213;6731.427828634425;6748;4355.455568053993;4364.781760912006;4377.956220437883;4380.080183161572;4391.407160033483;6279.277883043508;6293.276273228455;6383.170187875547;6527.869442611026;6537.725143596953;6387.808365749035;6394.616323020634;6395.456386207038;6412.839679007876;6416.743330266737;6227.719752611234;6325.387026308446;6337.96831015842;6348.841278968062;6410.423061924449;6288.874222515433;6299.338569450142;6303.936960630335;6310.810675679737;6324.683765811783;6409.679516024087;6454.290028097;6455.806325810233;6476.125723027331;6490.545661803629;5968.31364393083;6288.434040936155;6301.96849015752;6318.494520438446;6320.525960553069 14:24:12 INFO - PROCESS | 3445 | |5;yui.html;714.3428046197498;766.4175226827616;766.9271419215172;788.2826627768729;797.9082405523363;14241.287935603217;14629.195394253311;14754.483593074245;14783.334749936252;14828.481003164892;12509.812352814692;12537;12580.370981450913;12590.244585324886;12612.747745045117;691.6887400669718;812.0864027968536;828.1345993436873;836.5817091454273;840.1136800675285;825.2737590920012;826.7023871406288;827.9420440569132;836.1429534726893;837.8324335132997;879.028673315983;880.9911900880974;883.5802993578031;884.3676771108657;885.4775682347414;878.2534845381439;881.0352663833124;882.858742601184;882.9293656507464;883.2845395229884;647.5078940005629;648.0182523476936;648.5298158834854;649.5940037476577;650.2327253840466;12654.55709050188;12792.680182995406;12799.552015679406;12805.615831525069;12838.229706217657;14519.20144392058;14568.635784105376;14573.92713036428;14599;14698.779518307234;11391.829122563167;11540.653780386601;11631.418429078512;11650.533978640844;11661.12541559378;3830.8561858907333;3895.7662540247675;3905.0627849316243;3930.3711406174875;3971.8808435746973;55725.1641225382;56202.43797562013;58994;59629;59655;10545.894541054644;10851.457427128613;10905.291156074829;11125.276857004268;11194.160437967023;19318.517037073903;19580.804191957897;21340.466488337916;21466.356009319745;21469.785302146778;7817.8827317590285;7857.174996625272;7868.921310786819;7979;8159.184081591912;4630.4443466784205;4634.95365046352;4669.929951050737;4674.252119660838;4718.882027949328;3437.7077948374167;3470.583529976419;3477.652234776502;3484.7212223022098;3484.8606055757487;546.2516352596967;551.6193826259868;604.9243844519435;632.3202557250974;634.6255709131634;678.7726111752618;696.7213114754038;747.9252074792478;776.6699152860125;806.4274365200656;8928.15182557656;8958.820823583492;9221.723348299562;9334.813303734023;9613.134817866425;543.5488544508049;606.7906572232573;689.6241548356157;753.3182469864781;779.3375630713848;4346.087321662423;4346.304591265382;4518.502964673883;4531.546845315508;4547.522510136454;69391.30608693848;69396.65301673459;70719.6464017687;70836;71026.289737103;60127;60221.698891505264;61408.692956534935;61672;62656.686716566124;55587.444125559035;56150.15774763382;56581.7170914151;56665.716671417205;56949.430505694414;8466;8721.215090641872;8765.386422950332;8904.599293031959;9178.67874624385;29700.70299297066;30644.540331895485;30770.230744231572;30879;31963.36073278475;41773;41862.581374185866;41894.790526047174;42079.36880946727;44085.33871991858;11791.76416471683;11926.105542084551;11948.880511194777;12027.939860300643;12199.268043917393;13210.471581136648;13310.46758129664;13451.260180690157;13496.595102146952;13865.722685546434;12596.496140154657;13024.8046279304;13058;13186.538471153464;13455.596332110052;12099.939500302795;12613.810792838298;12767.93616031914;12824.23054616726;12882.35588220604;10325;11275.210735248782;11295.491702873225;11314.60398886035;12808.10343275999;10661.57353705874;10687.358538644907;10789.136869050613;10855.294405863593;10862.293950893167;10638.635462118269;10675.199360048182;10927.344359338465;10977.890221098007;11009.119270458184 14:24:12 INFO - PROCESS | 3445 | __end_tp_report 14:24:12 INFO - PROCESS | 3445 | __start_cc_report 14:24:12 INFO - PROCESS | 3445 | _x_x_mozilla_cycle_collect,334 14:24:12 INFO - PROCESS | 3445 | __end_cc_report 14:24:12 INFO - PROCESS | 3445 | __startTimestamp1461533052921__endTimestamp 14:24:12 INFO - PROCESS | 3445 | 14:24:12 INFO - PROCESS | 3445 | ------- Summary: start ------- 14:24:12 INFO - PROCESS | 3445 | Number of tests: 6 14:24:12 INFO - PROCESS | 3445 | 14:24:12 INFO - PROCESS | 3445 | [#0] dojo.html Cycles:165 Average:5235.50 Median:5448.35 stddev:686.10 (12.6%) stddev-sans-first:668.42 14:24:12 INFO - PROCESS | 3445 | Values: 3150.8 3178.9 3183.8 3210.1 3235.9 5236.1 5273.0 5286.8 5342.0 5388.4 5124.5 5232.0 5316.1 5345.5 5375.7 4772.1 4826.3 4889.4 4890.9 4942.8 602.6 4639.3 4770.0 4794.2 4841.2 4754.5 4768.0 4774.0 4788.5 4816.7 4760.7 4796.7 4804.1 4826.2 4849.9 4688.8 4800.3 4857.3 4873.7 4893.8 5191.0 5205.1 5217.4 5219.3 5246.7 5448.3 5666.1 5703.2 5704.1 5707.0 5777.4 5793.4 5813.5 5853.6 5869.7 5227.1 5229.7 5233.6 5235.2 5245.1 5579.2 5655.1 5663.5 5667.2 5710.6 5554.2 5558.4 5599.8 5611.5 5636.2 5667.2 5705.2 5708.7 5710.7 5712.7 5637.5 5649.8 5675.4 5684.1 5723.1 5626.6 5633.7 5657.3 5662.5 5673.0 4271.0 4301.0 4308.0 4312.8 4348.4 3837.1 3874.8 3893.1 3903.1 3912.0 5361.3 5400.3 5431.0 5437.3 5450.9 5604.3 5661.1 5729.5 5760.4 5767.5 5266.5 5366.8 5384.1 5387.6 5444.4 4858.6 4915.9 4922.1 4932.4 4945.7 5677.5 5694.1 5902.4 5906.9 5921.4 5682.8 5712.2 5726.9 5734.2 5769.3 5799.8 5830.4 5832.9 5834.4 5847.4 5480.5 5579.2 5587.4 5667.3 5732.5 5612.8 5654.2 5660.1 5674.7 5735.6 5464.2 5478.4 5490.5 5520.3 5530.0 5478.8 5491.8 5517.4 5528.5 5549.7 5358.4 5378.5 5415.1 5420.2 5455.2 5504.6 5519.9 5534.7 5538.2 5543.0 5343.7 5394.4 5423.7 5434.4 5446.1 14:24:12 INFO - PROCESS | 3445 | 14:24:12 INFO - PROCESS | 3445 | [#1] ext.html Cycles:180 Average:49190.88 Median:208049.62 stddev:94779.45 (45.6%) stddev-sans-first:94975.10 14:24:12 INFO - PROCESS | 3445 | Values: 592.9 649.3 659.6 667.1 691.6 37352.6 38149.2 38424.0 38485.8 38505.8 39397.6 39576.2 39658.0 39893.0 39911.8 8541.6 8682.4 8782.6 8788.5 8790.4 8721.5 8763.3 8820.0 8855.3 8860.6 8864.1 8874.2 8887.2 8887.5 8907.5 8803.7 8806.8 8874.8 8876.7 8894.1 8782.3 8871.0 8884.5 8896.7 8897.6 31354.7 40597.0 40743.2 40873.8 41163.8 3120.7 3138.1 3152.4 3220.7 3241.3 28181.9 28363.4 28738.6 28770.0 28953.3 4079.6 4085.0 4104.9 4184.6 4268.0 180645.0 181235.0 181863.0 182700.0 183617.0 32370.0 34690.5 35574.3 35671.8 35675.3 33313.7 33433.2 33469.3 33684.0 33687.3 14921.9 15118.1 15119.3 15122.2 15134.2 12775.2 12904.0 12904.4 13065.8 13466.4 8359.5 8394.6 8441.1 8450.2 8510.1 3263.2 3267.3 3303.6 3324.1 3363.2 1039.5 1082.5 1133.9 1165.1 1165.5 30924.9 31359.4 31723.0 32148.7 32338.5 1112.7 1127.4 1157.8 1176.5 1223.8 16566.2 16652.5 16818.6 16881.5 16918.4 450441.0 455223.0 455933.0 457108.0 458810.0 56035.0 57570.1 57688.7 57852.4 57898.7 1308.3 1339.4 1340.7 1341.4 1483.5 30296.4 31004.7 31828.2 31841.4 32528.2 49980.5 50473.5 50885.5 50899.2 50922.7 373442.0 373804.0 377258.0 377934.0 377950.0 27709.6 28162.3 28305.7 44201.8 45354.5 42044.9 42127.4 42363.6 42375.8 46733.5 38917.6 39640.4 39763.8 40004.2 40471.6 38167.2 38201.2 39175.4 39584.4 40116.0 36627.6 39753.0 42041.4 42229.6 42867.0 33152.5 34808.3 34881.7 35567.8 35680.0 27553.2 27689.6 27748.0 27893.6 27905.0 14:24:12 INFO - PROCESS | 3445 | 14:24:12 INFO - PROCESS | 3445 | [#2] jquery.html Cycles:180 Average:27785.69 Median:2024.58 stddev:79219.90 (3912.9%) stddev-sans-first:79415.41 14:24:12 INFO - PROCESS | 3445 | Values: 384.9 384.9 394.6 395.5 399.7 13846.4 17440.4 17674.7 18219.0 18241.0 17629.5 17791.3 17918.6 18462.0 18511.8 5448.5 5520.6 5637.0 5662.1 5712.2 5652.1 5654.4 5665.0 5676.9 5685.1 5446.5 5573.4 5723.1 5723.5 5734.4 5680.3 5698.2 5719.7 5735.5 5736.4 5022.2 5283.2 5319.4 5326.5 5330.0 11320.7 11430.8 11448.7 12264.1 12282.9 1980.7 2061.0 2094.7 2098.3 2132.2 9650.6 9736.6 10080.3 10976.7 14263.4 1853.4 1862.6 1926.6 1946.5 1988.2 80765.6 80812.6 81072.6 82702.2 82852.0 10143.4 10144.1 10270.8 10329.8 10405.7 12828.6 13136.0 14282.4 14282.5 14353.9 6110.3 6193.4 7245.7 8113.7 8188.3 7792.0 7798.0 8054.6 8062.2 8078.6 4481.0 4485.8 4518.0 4525.9 4619.8 1823.9 1900.3 1938.3 1938.7 1977.3 1001.5 1031.9 1064.5 1079.3 1080.6 17611.1 17655.7 17946.2 18411.9 19011.2 1004.7 1014.1 1014.5 1023.5 1067.6 10152.7 10605.7 10701.6 10705.6 10705.8 465037.0 465208.0 471051.0 493908.0 500052.0 91190.0 94451.5 94557.0 96835.0 97920.0 994.5 1008.3 1010.2 1031.5 1039.7 16334.6 16480.5 16736.0 16861.3 16913.7 29530.4 37316.0 38885.8 39426.2 40433.4 49764.8 52145.5 52263.0 52831.0 53003.2 5302.4 5458.9 7801.8 7887.3 8011.8 11418.7 11966.8 12361.6 12427.3 12627.6 11390.5 11416.3 11432.1 11486.7 11493.6 10981.4 10984.6 11218.3 11321.4 11575.6 6299.3 8275.5 11171.9 11732.5 11923.0 7801.4 7822.7 7916.0 8067.2 8397.7 11426.5 11447.4 11745.5 11956.8 12080.2 14:24:12 INFO - PROCESS | 3445 | 14:24:12 INFO - PROCESS | 3445 | [#3] mootools.html Cycles:180 Average:5159.11 Median:4913.28 stddev:2811.28 (57.2%) stddev-sans-first:2805.69 14:24:12 INFO - PROCESS | 3445 | Values: 1495.5 1545.3 1702.9 1767.8 1819.3 7015.5 7410.8 7456.4 7692.1 7821.6 7398.7 7403.8 7403.9 7472.1 7480.4 2045.1 2059.1 2062.9 2071.6 2082.6 2060.0 2080.6 2097.4 2100.3 2100.7 2070.3 2087.2 2092.2 2093.9 2095.7 2056.0 2058.2 2064.1 2065.9 2068.9 2007.7 2013.2 2014.3 2022.6 2025.4 7461.8 7536.5 7545.1 7553.0 7556.4 4694.8 4819.2 4842.3 4850.9 4860.8 3079.3 3148.7 3165.7 3191.6 3238.8 1011.3 1043.3 1043.4 1049.7 1063.6 10446.0 10448.0 10468.8 10478.6 10488.6 4085.6 4116.8 4233.4 4243.4 4309.7 8828.2 8909.8 8912.8 8913.5 8960.4 4840.0 4856.4 4858.4 4906.0 4914.3 4038.4 4354.0 4426.1 4510.4 4511.9 3631.8 3670.7 3715.4 3746.2 3807.9 1212.9 1241.1 1242.9 1248.5 1277.2 876.0 1003.5 1010.2 1015.1 1025.5 5428.4 5645.9 5764.3 5768.5 5776.8 409.3 413.2 418.1 418.5 421.5 3991.3 4013.8 4026.3 4033.9 4125.9 9200.2 9499.2 9583.9 9586.2 9605.9 9454.1 9517.4 9539.3 9543.1 9547.0 7111.8 7361.0 7423.2 7425.9 7459.7 5320.7 5347.3 5388.1 5479.6 5598.4 8376.5 8647.4 8682.6 8742.2 8898.3 8434.0 8545.1 8680.0 8680.0 8724.6 6367.3 6722.7 6880.3 6905.6 6971.9 7517.5 7517.9 7574.3 7576.5 7614.9 6727.1 6930.7 6946.9 6974.4 7038.4 6673.9 6736.2 6812.9 6819.5 6843.5 6211.8 6363.7 6411.7 6420.8 6438.6 4867.0 4883.6 4955.9 4963.4 4987.5 4912.3 4938.0 4991.0 4992.2 5069.5 14:24:12 INFO - PROCESS | 3445 | 14:24:12 INFO - PROCESS | 3445 | [#4] prototype.html Cycles:180 Average:5290.13 Median:6024.91 stddev:1602.15 (26.6%) stddev-sans-first:1562.64 14:24:12 INFO - PROCESS | 3445 | Values: 321.5 322.0 331.1 331.5 334.2 5808.9 5916.7 5929.8 6008.4 6055.2 5958.7 5964.3 5976.4 5976.5 5991.6 4538.4 4539.4 4562.8 4585.3 4607.8 4449.8 4577.8 4578.3 4593.2 4593.7 4510.6 4511.0 4535.1 4536.3 4553.5 4527.9 4540.1 4540.6 4542.1 4546.7 3898.8 3972.4 3994.2 3995.8 3996.4 5659.3 5719.7 5722.9 5742.0 5775.7 5284.0 5309.8 5317.8 5323.2 5337.1 6041.4 6243.0 6300.8 6302.0 6304.0 4352.0 4391.1 4392.0 4403.1 4426.8 6395.5 6570.7 6581.6 6589.9 6603.6 4486.5 4493.6 4506.0 4512.5 4521.1 4511.9 4520.8 4529.3 4533.5 4546.6 5136.8 5221.3 5258.4 5266.7 5267.8 6174.9 6333.2 6368.2 6373.6 6390.6 1558.3 1570.2 1574.6 1574.8 1576.8 1017.9 1018.2 1044.4 1049.8 1059.5 6127.6 6129.3 6145.7 6157.4 6177.2 6482.9 6521.7 6536.3 6547.4 6553.1 5955.7 6087.9 6117.9 6142.6 6169.5 3494.3 3507.9 3523.3 3535.9 3540.5 6567.9 6722.6 6736.2 6778.8 6780.5 6580.2 6612.7 6643.9 6702.7 6717.4 6558.8 6707.3 6710.1 6809.6 6817.4 6465.9 6557.1 6562.2 6573.6 6596.9 6382.1 6414.8 6462.6 6475.8 6492.2 6716.7 6719.9 6722.0 6731.4 6748.0 4355.5 4364.8 4378.0 4380.1 4391.4 6279.3 6293.3 6383.2 6527.9 6537.7 6387.8 6394.6 6395.5 6412.8 6416.7 6227.7 6325.4 6338.0 6348.8 6410.4 6288.9 6299.3 6303.9 6310.8 6324.7 6409.7 6454.3 6455.8 6476.1 6490.5 5968.3 6288.4 6302.0 6318.5 6320.5 14:24:12 INFO - PROCESS | 3445 | 14:24:12 INFO - PROCESS | 3445 | [#5] yui.html Cycles:180 Average:15283.74 Median:24215.82 stddev:18550.81 (76.6%) stddev-sans-first:18570.59 14:24:12 INFO - PROCESS | 3445 | Values: 714.3 766.4 766.9 788.3 797.9 14241.3 14629.2 14754.5 14783.3 14828.5 12509.8 12537.0 12580.4 12590.2 12612.7 691.7 812.1 828.1 836.6 840.1 825.3 826.7 827.9 836.1 837.8 879.0 881.0 883.6 884.4 885.5 878.3 881.0 882.9 882.9 883.3 647.5 648.0 648.5 649.6 650.2 12654.6 12792.7 12799.6 12805.6 12838.2 14519.2 14568.6 14573.9 14599.0 14698.8 11391.8 11540.7 11631.4 11650.5 11661.1 3830.9 3895.8 3905.1 3930.4 3971.9 55725.2 56202.4 58994.0 59629.0 59655.0 10545.9 10851.5 10905.3 11125.3 11194.2 19318.5 19580.8 21340.5 21466.4 21469.8 7817.9 7857.2 7868.9 7979.0 8159.2 4630.4 4635.0 4669.9 4674.3 4718.9 3437.7 3470.6 3477.7 3484.7 3484.9 546.3 551.6 604.9 632.3 634.6 678.8 696.7 747.9 776.7 806.4 8928.2 8958.8 9221.7 9334.8 9613.1 543.5 606.8 689.6 753.3 779.3 4346.1 4346.3 4518.5 4531.5 4547.5 69391.3 69396.7 70719.6 70836.0 71026.3 60127.0 60221.7 61408.7 61672.0 62656.7 55587.4 56150.2 56581.7 56665.7 56949.4 8466.0 8721.2 8765.4 8904.6 9178.7 29700.7 30644.5 30770.2 30879.0 31963.4 41773.0 41862.6 41894.8 42079.4 44085.3 11791.8 11926.1 11948.9 12027.9 12199.3 13210.5 13310.5 13451.3 13496.6 13865.7 12596.5 13024.8 13058.0 13186.5 13455.6 12099.9 12613.8 12767.9 12824.2 12882.4 10325.0 11275.2 11295.5 11314.6 12808.1 10661.6 10687.4 10789.1 10855.3 10862.3 10638.6 10675.2 10927.3 10977.9 11009.1 14:24:12 INFO - PROCESS | 3445 | -------- Summary: end -------- 14:24:12 INFO - PROCESS | 3445 | 14:24:13 INFO - TEST-INFO | 3445: exit 0 14:24:13 INFO - TEST-OK | dromaeo_css | took 1111660ms 14:24:13 INFO - TEST-START | kraken 14:24:13 INFO - operating with platform_type : linux_ 14:24:13 INFO - Initialising browser for kraken test... 14:24:13 INFO - TEST-INFO | started process 3682 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHpo3bB/profile http://localhost:44704/getInfo.html) 14:24:17 INFO - PROCESS | 3682 | 14:24:17 INFO - PROCESS | 3682 | (process:3750): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:24:18 INFO - PROCESS | 3682 | __metrics Screen width/height:1600/1200 14:24:18 INFO - PROCESS | 3682 | colorDepth:24 14:24:18 INFO - PROCESS | 3682 | Browser inner width/height: 1024/697 14:24:18 INFO - PROCESS | 3682 | __metrics 14:24:18 ERROR - PROCESS | 3682 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 14:24:19 INFO - TEST-INFO | 3682: exit 0 14:24:19 INFO - Browser initialized. 14:24:19 INFO - Running cycle 1/1 for kraken test... 14:24:19 INFO - TEST-INFO | started process 3801 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHpo3bB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:24:21 INFO - PROCESS | 3801 | 14:24:21 INFO - PROCESS | 3801 | (process:3861): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:24:31 INFO - PROCESS | 3801 | RSS: Main: 164552704 14:24:31 INFO - PROCESS | 3801 | 14:24:33 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44704/tests/kraken/driver.html?testName=audio-beat-detection) 14:24:34 INFO - PROCESS | 3801 | RSS: Main: 169504768 14:24:34 INFO - PROCESS | 3801 | 14:24:37 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44704/tests/kraken/driver.html?testName=audio-dft) 14:24:38 INFO - PROCESS | 3801 | RSS: Main: 172953600 14:24:38 INFO - PROCESS | 3801 | 14:24:40 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44704/tests/kraken/driver.html?testName=audio-fft) 14:24:41 INFO - PROCESS | 3801 | RSS: Main: 162516992 14:24:41 INFO - PROCESS | 3801 | 14:24:43 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44704/tests/kraken/driver.html?testName=audio-oscillator) 14:24:44 INFO - PROCESS | 3801 | RSS: Main: 163028992 14:24:44 INFO - PROCESS | 3801 | 14:24:46 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44704/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:24:47 INFO - PROCESS | 3801 | RSS: Main: 164835328 14:24:47 INFO - PROCESS | 3801 | 14:24:50 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44704/tests/kraken/driver.html?testName=imaging-darkroom) 14:24:50 INFO - PROCESS | 3801 | RSS: Main: 164655104 14:24:50 INFO - PROCESS | 3801 | 14:24:54 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44704/tests/kraken/driver.html?testName=imaging-desaturate) 14:24:55 INFO - PROCESS | 3801 | RSS: Main: 166989824 14:24:55 INFO - PROCESS | 3801 | 14:24:57 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44704/tests/kraken/driver.html?testName=json-parse-financial) 14:24:58 INFO - PROCESS | 3801 | RSS: Main: 166940672 14:24:58 INFO - PROCESS | 3801 | 14:25:00 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44704/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:25:00 INFO - PROCESS | 3801 | RSS: Main: 167047168 14:25:00 INFO - PROCESS | 3801 | 14:25:02 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44704/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:25:03 INFO - PROCESS | 3801 | RSS: Main: 166825984 14:25:03 INFO - PROCESS | 3801 | 14:25:05 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44704/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:25:06 INFO - PROCESS | 3801 | RSS: Main: 167317504 14:25:06 INFO - PROCESS | 3801 | 14:25:09 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44704/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:25:09 INFO - PROCESS | 3801 | RSS: Main: 167153664 14:25:09 INFO - PROCESS | 3801 | 14:25:12 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44704/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:25:13 INFO - PROCESS | 3801 | RSS: Main: 167215104 14:25:13 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:44704/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44704/tests/kraken/driver.html?testName=ai-astar) 14:25:15 INFO - PROCESS | 3801 | RSS: Main: 169529344 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | __start_tp_report 14:25:15 INFO - PROCESS | 3801 | _x_x_mozilla_page_load 14:25:15 INFO - PROCESS | 3801 | _x_x_mozilla_page_load_details 14:25:15 INFO - PROCESS | 3801 | |i|pagename|runs| 14:25:15 INFO - PROCESS | 3801 | |0;ai-astar;113;106;113;112;112;123;111;111;111;112 14:25:15 INFO - PROCESS | 3801 | |1;audio-beat-detection;151;124;154;183;136;153;160;129;186;136 14:25:15 INFO - PROCESS | 3801 | |2;audio-dft;161;162;162;161;165;164;174;165;165;167 14:25:15 INFO - PROCESS | 3801 | |3;audio-fft;76;77;77;159;77;77;74;77;162;74 14:25:15 INFO - PROCESS | 3801 | |4;audio-oscillator;93;93;157;92;93;93;160;93;94;151 14:25:15 INFO - PROCESS | 3801 | |5;imaging-gaussian-blur;118;118;117;118;117;118;117;120;121;123 14:25:15 INFO - PROCESS | 3801 | |6;imaging-darkroom;146;147;146;146;146;147;146;148;147;147 14:25:15 INFO - PROCESS | 3801 | |7;imaging-desaturate;95;95;95;95;96;95;100;96;95;95 14:25:15 INFO - PROCESS | 3801 | |8;json-parse-financial;59;59;69;59;59;65;65;73;58;57 14:25:15 INFO - PROCESS | 3801 | |9;json-stringify-tinderbox;63;61;60;61;61;62;61;60;61;61 14:25:15 INFO - PROCESS | 3801 | |10;stanford-crypto-aes;78;77;85;76;79;78;78;78;78;78 14:25:15 INFO - PROCESS | 3801 | |11;stanford-crypto-ccm;128;125;137;134;136;134;125;146;162;134 14:25:15 INFO - PROCESS | 3801 | |12;stanford-crypto-pbkdf2;182;172;175;173;181;170;169;169;173;170 14:25:15 INFO - PROCESS | 3801 | |13;stanford-crypto-sha256-iterative;73;70;70;74;71;69;85;69;70;73 14:25:15 INFO - PROCESS | 3801 | __end_tp_report 14:25:15 INFO - PROCESS | 3801 | __start_cc_report 14:25:15 INFO - PROCESS | 3801 | _x_x_mozilla_cycle_collect,848 14:25:15 INFO - PROCESS | 3801 | __end_cc_report 14:25:15 INFO - PROCESS | 3801 | __startTimestamp1461533115340__endTimestamp 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | ------- Summary: start ------- 14:25:15 INFO - PROCESS | 3801 | Number of tests: 14 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.00 stddev:4.22 (3.8%) stddev-sans-first:4.47 14:25:15 INFO - PROCESS | 3801 | Values: 113.0 106.0 113.0 112.0 112.0 123.0 111.0 111.0 111.0 112.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#1] audio-beat-detection Cycles:10 Average:151.20 Median:153.50 stddev:21.10 (13.7%) stddev-sans-first:22.38 14:25:15 INFO - PROCESS | 3801 | Values: 151.0 124.0 154.0 183.0 136.0 153.0 160.0 129.0 186.0 136.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#2] audio-dft Cycles:10 Average:164.60 Median:165.00 stddev:3.86 (2.3%) stddev-sans-first:3.87 14:25:15 INFO - PROCESS | 3801 | Values: 161.0 162.0 162.0 161.0 165.0 164.0 174.0 165.0 165.0 167.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#3] audio-fft Cycles:10 Average:93.00 Median:77.00 stddev:35.60 (46.2%) stddev-sans-first:37.23 14:25:15 INFO - PROCESS | 3801 | Values: 76.0 77.0 77.0 159.0 77.0 77.0 74.0 77.0 162.0 74.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#4] audio-oscillator Cycles:10 Average:111.90 Median:93.00 stddev:30.51 (32.8%) stddev-sans-first:31.59 14:25:15 INFO - PROCESS | 3801 | Values: 93.0 93.0 157.0 92.0 93.0 93.0 160.0 93.0 94.0 151.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#5] imaging-gaussian-blur Cycles:10 Average:118.70 Median:118.00 stddev:2.00 (1.7%) stddev-sans-first:2.11 14:25:15 INFO - PROCESS | 3801 | Values: 118.0 118.0 117.0 118.0 117.0 118.0 117.0 120.0 121.0 123.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 14:25:15 INFO - PROCESS | 3801 | Values: 146.0 147.0 146.0 146.0 146.0 147.0 146.0 148.0 147.0 147.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:95.00 stddev:1.57 (1.6%) stddev-sans-first:1.64 14:25:15 INFO - PROCESS | 3801 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 100.0 96.0 95.0 95.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:62.00 stddev:5.42 (8.7%) stddev-sans-first:5.61 14:25:15 INFO - PROCESS | 3801 | Values: 59.0 59.0 69.0 59.0 59.0 65.0 65.0 73.0 58.0 57.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:0.88 (1.4%) stddev-sans-first:0.60 14:25:15 INFO - PROCESS | 3801 | Values: 63.0 61.0 60.0 61.0 61.0 62.0 61.0 60.0 61.0 61.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#10] stanford-crypto-aes Cycles:10 Average:78.50 Median:78.00 stddev:2.42 (3.1%) stddev-sans-first:2.55 14:25:15 INFO - PROCESS | 3801 | Values: 78.0 77.0 85.0 76.0 79.0 78.0 78.0 78.0 78.0 78.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#11] stanford-crypto-ccm Cycles:10 Average:136.10 Median:135.00 stddev:11.03 (8.2%) stddev-sans-first:11.30 14:25:15 INFO - PROCESS | 3801 | Values: 128.0 125.0 137.0 134.0 136.0 134.0 125.0 146.0 162.0 134.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:173.00 stddev:4.70 (2.7%) stddev-sans-first:3.81 14:25:15 INFO - PROCESS | 3801 | Values: 182.0 172.0 175.0 173.0 181.0 170.0 169.0 169.0 173.0 170.0 14:25:15 INFO - PROCESS | 3801 | 14:25:15 INFO - PROCESS | 3801 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:72.00 stddev:4.77 (6.6%) stddev-sans-first:5.05 14:25:15 INFO - PROCESS | 3801 | Values: 73.0 70.0 70.0 74.0 71.0 69.0 85.0 69.0 70.0 73.0 14:25:15 INFO - PROCESS | 3801 | -------- Summary: end -------- 14:25:15 INFO - PROCESS | 3801 | 14:25:16 INFO - TEST-INFO | 3801: exit 0 14:25:16 INFO - TEST-OK | kraken | took 62719ms 14:25:16 INFO - SUITE-END | took 1174s 14:25:16 INFO - Completed test suite (00:19:34) 14:25:16 INFO - javascript benchmark 14:25:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3150.7951983121097, 3178.856951437185, 3183.7930534515253, 3210.0690799668096, 3235.91910202245, 5236.136037553799, 5273.024490469261, 5286.814961476349, 5341.973290133554, 5388.43421440749, 5124.461931497195, 5232.005918875411, 5316.149416093426, 5345.465453454653, 5375.650582712131, 4772.093302272575, 4826.32431459596, 4889.437714662806, 4890.853274401774, 4942.752862356886, 602.6460083505202, 4639.257718764999, 4770.0459908018365, 4794.2329227323635, 4841.225403935371, 4754.524547545252, 4768.046390721852, 4774.0, 4788.545088216614, 4816.686915350491, 4760.666753327268, 4796.736179510161, 4804.063207674505, 4826.227803551415, 4849.9030019399415, 4688.812447502096, 4800.279958006292, 4857.344258525124, 4873.731944743037, 4893.75531223441, 5190.974045129788, 5205.141151709963, 5217.3999990000875, 5219.269302297681, 5246.711430871301, 5448.346198456171, 5666.065099258617, 5703.201551782754, 5704.1443783432815, 5706.971465142689, 5777.393373695743, 5793.362730099686, 5813.47678708914, 5853.64878107315, 5869.706514674292, 5227.111391063529, 5229.686218826844, 5233.581313494911, 5235.188545775411, 5245.134552798783, 5579.218909352694, 5655.123455864347, 5663.490285874291, 5667.206591077253, 5710.628809127476, 5554.166874968787, 5558.360788509292, 5599.832005039855, 5611.523020543218, 5636.210930469738, 5667.206591077253, 5705.2012718219485, 5708.686022268732, 5710.7430165642645, 5712.65724056558, 5637.464440878192, 5649.802256921071, 5675.4324567543745, 5684.147377893267, 5723.141528770718, 5626.634268772516, 5633.6619802811965, 5657.2928383952, 5662.518685911744, 5673.035583950738, 4271.039016221387, 4301.0, 4307.978460107679, 4312.805923733376, 4348.412964249849, 3837.13664425502, 3874.8256328465286, 3893.0851249956236, 3903.121797595575, 3911.9608803911597, 5361.276227709287, 5400.324959380077, 5431.022415965085, 5437.32033495813, 5450.91823622646, 5604.271444712243, 5661.122526008476, 5729.54163666914, 5760.42395760429, 5767.452092051249, 5266.526012658879, 5366.7853285868905, 5384.084705599979, 5387.6228663993925, 5444.373897001817, 4858.6356023297685, 4915.901681966412, 4922.089413458521, 4932.383452068491, 4945.703257804544, 5677.460641239076, 5694.06048002075, 5902.439268269508, 5906.911396329058, 5921.3782552831335, 5682.772689092472, 5712.228849105484, 5726.942730572808, 5734.2258795062135, 5769.307683077974, 5799.797007104731, 5830.416958304136, 5832.912506312324, 5834.416558344132, 5847.356790752929, 5480.506754392124, 5579.246801681721, 5587.413321601173, 5667.291588551431, 5732.541396688338, 5612.7754889803955, 5654.15187721845, 5660.066089095253, 5674.687892165971, 5735.598508104393, 5464.153056276284, 5478.397376288684, 5490.450954904478, 5520.254765606592, 5529.972350138223, 5478.780848766005, 5491.75287112073, 5517.44825517445, 5528.530074943675, 5549.722513874371, 5358.437364076716, 5378.489043540857, 5415.106507426231, 5420.186971954081, 5455.1544510600925, 5504.642108415789, 5519.889602207853, 5534.667919924817, 5538.224648549287, 5542.972285138549, 5343.732813359395, 5394.379646340564, 5423.674579525241, 5434.402215756343, 5446.101393270066], "value": 5192.644885083962, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.9377415371385, 649.2760571962262, 659.5679829711538, 667.1094089390666, 691.5574032619121, 37352.626473735254, 38149.23701525968, 38424.0, 38485.80757096214, 38505.807470962674, 39397.60602393975, 39576.20847583046, 39658.0, 39893.0, 39911.80044099775, 8541.572921353938, 8682.435641683302, 8782.560871956408, 8788.51663158526, 8790.384673072887, 8721.476711397305, 8763.255123314526, 8819.95590022049, 8855.33584981129, 8860.556972151398, 8864.069272726367, 8874.2456891164, 8887.15572020657, 8887.511186884718, 8907.465552066864, 8803.691870784492, 8806.779830504256, 8874.778130546723, 8876.689315873913, 8894.066123057082, 8782.297416206751, 8870.955645221733, 8884.466931984101, 8896.733098007071, 8897.599608017656, 31354.686453135404, 40597.0, 40743.18513629711, 40873.79563102195, 41163.794181028905, 3120.687931206884, 3138.10563989262, 3152.3537674776617, 3220.6940340667597, 3241.33552621712, 28181.85909070462, 28363.432731345256, 28738.568921466194, 28770.0, 28953.276168095756, 4079.63283304504, 4085.0, 4104.938425923614, 4184.623383895434, 4268.0, 180645.0, 181235.0, 181863.0, 182700.0, 183617.0, 32370.0, 34690.47964280538, 35574.28851422957, 35671.82164089163, 35675.28649427049, 33313.66686333154, 33433.16417089592, 33469.330613387596, 33684.0, 33687.32625347479, 14921.850781492263, 15118.09291442517, 15119.319630616648, 15122.168280744665, 15134.243287835565, 12775.169613975062, 12904.032197585218, 12904.354782260849, 13065.804012939814, 13466.394012269275, 8359.456635318686, 8394.580270986426, 8441.113683063313, 8450.154984501623, 8510.106438823834, 3263.184203949013, 3267.281198136406, 3303.620083690358, 3324.0858763840138, 3363.2264579146445, 1039.4906495816995, 1082.5290998415558, 1133.9489722962487, 1165.0504838556549, 1165.5046605192758, 30924.917627882915, 31359.37281254408, 31723.0, 32148.678513215036, 32338.514922276183, 1112.7385064509833, 1127.3630398824637, 1157.7626586549736, 1176.4823477669797, 1223.7858374784378, 16566.17169141538, 16652.50042498727, 16818.57953551171, 16881.493555193363, 16918.407855725236, 450441.0, 455223.0, 455933.0, 457108.0, 458810.0, 56035.0, 57570.136447953315, 57688.71155644279, 57852.4214757847, 57898.7105064472, 1308.2918358996976, 1339.41065930992, 1340.7117469743857, 1341.4298922957703, 1483.4585376337775, 30296.394072117993, 31004.68995310018, 31828.204294892814, 31841.363172735953, 32528.186795330304, 49980.500194997585, 50473.49526504688, 50885.491145088075, 50899.236511451614, 50922.74538627283, 373442.0, 373804.0, 377258.0, 377934.0, 377950.0, 27709.58435623427, 28162.29594260078, 28305.71694283031, 44201.77899110484, 45354.54645453503, 42044.90348899454, 42127.36808947807, 42363.57636423596, 42375.788121059195, 46733.532664672915, 38917.61082389253, 39640.40539391854, 39763.80118099391, 40004.199916000936, 40471.59528404678, 38167.2366552673, 38201.23597528089, 39175.41236881392, 39584.40623390709, 40116.0, 36627.633723662424, 39753.0, 42041.36937945872, 42229.57770422257, 42867.0, 33152.50271245885, 34808.30383392267, 34881.65118348886, 35567.82216088903, 35680.0, 27553.17340479789, 27689.584656230574, 27748.0, 27893.60531973434, 27905.0], "value": 17880.435722560487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.8999260192349, 384.90762217067913, 394.5758309816947, 395.4503240495711, 399.7151906503964, 13846.446142154302, 17440.38958636448, 17674.73487897681, 18219.0, 18241.0, 17629.471115866512, 17791.288348466078, 17918.64162716745, 18462.0, 18511.814881851176, 5448.536874365664, 5520.641158324706, 5636.971815140919, 5662.094064949609, 5712.22884910536, 5652.06740887753, 5654.37801841797, 5665.0, 5676.943230567704, 5685.090385538315, 5446.537044351235, 5573.386927437979, 5723.055695810186, 5723.456271654186, 5734.397888221739, 5680.318361796591, 5698.202251684746, 5719.6854173020465, 5735.4551317624755, 5736.426357364272, 5022.246663000543, 5283.233931079972, 5319.414864364917, 5326.547243484309, 5329.973350133263, 11320.716982075432, 11430.828537571942, 11448.71378215543, 12264.080193985486, 12282.877171228352, 1980.742503474539, 2060.9690854637192, 2094.73268672454, 2098.31804663485, 2132.2217390652454, 9650.565724542412, 9736.610535578568, 10080.344777589433, 10976.670699879016, 14263.429462821474, 1853.369854249548, 1862.5995410986568, 1926.5857840564206, 1946.5036415714044, 1988.1848442138653, 80765.59617201876, 80812.59593701994, 81072.59463702644, 82702.17297827065, 82852.0, 10143.442110683984, 10144.087032166994, 10270.845937310947, 10329.793404131875, 10405.739856503496, 12828.615141545768, 13136.0, 14282.428702851978, 14282.500112496013, 14353.85646143546, 6110.26676798787, 6193.4425901669065, 7245.710171593183, 8113.675452981933, 8188.2630563249595, 7792.025996750406, 7798.0, 8054.5569993650915, 8062.193780621891, 8078.555679437687, 4481.0, 4485.820567177277, 4518.0, 4525.909481810345, 4619.8450387403145, 1823.9088045597666, 1900.268396667293, 1938.321587444383, 1938.6607343715075, 1977.2684106880547, 1001.4742260313249, 1031.896810318962, 1064.5369264370024, 1079.3469950679882, 1080.6109800471825, 17611.119444028005, 17655.73516397255, 17946.19242134081, 18411.907940460213, 19011.239550418104, 1004.6533945788691, 1014.051861509491, 1014.4521958142668, 1023.534291897185, 1067.6156583629886, 10152.695419137437, 10605.73485662849, 10701.571937122584, 10705.571777128827, 10705.83941240882, 465037.0, 465208.0, 471051.0, 493908.0, 500052.0, 91190.0, 94451.52774236085, 94557.0, 96835.0, 97920.0, 994.4928086675848, 1008.2690049714046, 1010.2170817616407, 1031.515187861704, 1039.740064983754, 16334.591635208739, 16480.505584832474, 16736.0, 16861.325546978474, 16913.66172676564, 29530.409391811612, 37316.0, 38885.80557097196, 39426.21147576975, 40433.39349909695, 49764.760585773, 52145.478545214064, 52263.0, 52831.0, 53003.20495192498, 5302.39022512416, 5458.918116228339, 7801.804954876174, 7887.329576986021, 8011.759647210593, 11418.714532136763, 11966.820497692368, 12361.64021957602, 12427.31649759272, 12627.621171364874, 11390.487428065922, 11416.31502109876, 11432.085433165494, 11486.712832179262, 11493.597724079616, 10981.3960232188, 10984.560617575207, 11218.270812397168, 11321.377324247245, 11575.594854180063, 6299.307076221704, 8275.50346979183, 11171.944140279571, 11732.472038758448, 11923.0, 7801.375889928679, 7822.72620458304, 7915.960420198093, 8067.193280671886, 8397.748067557983, 11426.485808138488, 11447.427628618369, 11745.47145378477, 11956.820647690118, 12080.21478603888], "value": 8668.797310650772, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.5139579636618, 1545.3277824146496, 1702.9489115326544, 1767.8497327727143, 1819.3086627081707, 7015.508914375983, 7410.814729631762, 7456.366208872246, 7692.076950765909, 7821.608919554021, 7398.704051837934, 7403.777886663409, 7403.888941665866, 7472.103347598282, 7480.4389670774635, 2045.0797141286444, 2059.0734169623656, 2062.8762274263518, 2071.6167509010806, 2082.5834833033377, 2059.979400206001, 2080.6046851098245, 2097.402240361497, 2100.33839340608, 2100.674395468705, 2070.33749200256, 2087.217293514932, 2092.163134746106, 2093.916243350272, 2095.706601075843, 2056.0233888902726, 2058.207590077823, 2064.0917996081675, 2065.8967051647433, 2068.9379318620468, 2007.65869802134, 2013.2047841102828, 2014.3151328548363, 2022.5651484930663, 2025.4430031741242, 7461.776146715607, 7536.547807131589, 7545.094588649343, 7553.018107645971, 7556.433267504959, 4694.835680751157, 4819.180739274332, 4842.322074909515, 4850.854474365775, 4860.8298709545, 3079.2609773654162, 3148.7481001520055, 3165.6517783043846, 3191.5850939377965, 3238.789478683878, 1011.3224139826334, 1043.2592859070069, 1043.3948309980194, 1049.6798476464676, 1063.558623171383, 10446.0, 10448.0, 10468.79062418755, 10478.63324783629, 10488.58045678172, 4085.632293093636, 4116.75299482032, 4233.407322974786, 4243.448351714257, 4309.676774241944, 8828.205461508494, 8909.777255568662, 8912.777180570538, 8913.5097569633, 8960.417572857743, 4839.975800120977, 4856.368672072608, 4858.416989961157, 4905.9754701226975, 4914.287428322873, 4038.434619153321, 4353.978230108829, 4426.1369033038245, 4510.368548403226, 4511.887202819956, 3631.782093074424, 3670.724695647837, 3715.368387374153, 3746.1758413149064, 3807.9048023799073, 1212.927224366541, 1241.0815996162773, 1242.9005679545614, 1248.5318005747845, 1277.2336598041102, 875.9868601970976, 1003.5233264199593, 1010.2019404670228, 1015.0864222200079, 1025.477006726575, 5428.4028756836715, 5645.858853528694, 5764.279465066867, 5768.480836724715, 5776.768929242783, 409.2653686632502, 413.1839616756941, 418.1073408273358, 418.54169684195756, 421.51736261979863, 3991.3214753491975, 4013.819378127991, 4026.275270451288, 4033.8991525212105, 4125.938110928339, 9200.171984521425, 9499.192568631745, 9583.90416095844, 9586.233101351874, 9605.855912161323, 9454.101860323259, 9517.428954262767, 9539.3322467428, 9543.141117299338, 9547.0, 7111.7866464006165, 7361.043064401593, 7423.183449820515, 7425.851482970418, 7459.70161193557, 5320.707361095177, 5347.304850369427, 5388.110961691277, 5479.6164268500825, 5598.440155984532, 8376.53929033909, 8647.437916535406, 8682.565871706263, 8742.169493898069, 8898.332625053017, 8433.957830211055, 8545.145485451405, 8679.956600216958, 8680.0, 8724.607392667218, 6367.331430199948, 6722.731090756315, 6880.346367095118, 6905.585664860125, 6971.930280697128, 7517.548947063194, 7517.887231691639, 7574.3183113520045, 7576.507527010726, 7614.885776713243, 6727.125473688389, 6930.7227710891, 6946.8610627788175, 6974.407175390149, 7038.436925046087, 6673.89880669684, 6736.157980252468, 6812.863742725019, 6819.522633415613, 6843.486738494732, 6211.81364559064, 6363.681815909279, 6411.71147298382, 6420.839479013062, 6438.6136831790245, 4866.975665121652, 4883.60931125502, 4955.900881982412, 4963.354763880672, 4987.476314986874, 4912.263160525807, 4938.000109955474, 4990.950090499194, 4992.15133427311, 5069.518395752398], "value": 4132.457004246155, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.52735478846097, 322.0355829440412, 331.09280571234825, 331.49281599153295, 334.1779223111146, 5808.941910580893, 5916.674582897945, 5929.762809487626, 6008.386351936911, 6055.152278680983, 5958.702064896759, 5964.314103878055, 5976.416249693834, 5976.52187824974, 5991.550633702468, 4538.432695913011, 4539.432570928634, 4562.783237817331, 4585.2663573828195, 4607.769611519428, 4449.84425545106, 4577.793999270041, 4578.267477203648, 4593.150267200562, 4593.701409408394, 4510.556621880999, 4510.954890451087, 4535.138323718499, 4536.319552067183, 4553.453585569736, 4527.864164075083, 4540.137373898949, 4540.636749060101, 4542.136993971135, 4546.659000574954, 3898.8440462381473, 3972.443857859902, 3994.1612261424843, 3995.820188091514, 3996.442276942759, 5659.320881494205, 5719.742611582489, 5722.914156287659, 5741.971290143564, 5775.711214439303, 5284.022455845642, 5309.840704778863, 5317.787288508455, 5323.22813192085, 5337.11937530307, 6041.426064523863, 6242.969909964896, 6300.842478938017, 6301.96849015752, 6303.9684801575695, 4352.042550638855, 4391.121775644884, 4391.98984233628, 4403.141387429452, 4426.790576831803, 6395.456386207178, 6570.671466426659, 6581.572197807127, 6589.901151482732, 6603.603783772989, 4486.506484286726, 4493.595576398075, 4505.977470112629, 4512.495564961412, 4521.1409832131785, 4511.872031992002, 4520.773961301922, 4529.275315949432, 4533.5013148553635, 4546.590806827401, 5136.845894623168, 5221.295125158055, 5258.447862974409, 5266.7366631668265, 5267.815626453055, 6174.9073763893575, 6333.20834895638, 6368.172137622079, 6373.649449280241, 6390.616563006234, 1558.3377064747438, 1570.225645021119, 1574.6457047164527, 1574.7795308656569, 1576.8107827060596, 1017.9134773544183, 1018.2414101494434, 1044.3942513342242, 1049.8057859295902, 1059.5443959097508, 6127.640858269509, 6129.264488261437, 6145.66198859067, 6157.384261573807, 6177.1969643946895, 6482.870342593122, 6521.67391630426, 6536.306986552582, 6547.377999090078, 6553.082568440422, 5955.702214889238, 6087.878242435215, 6117.908231376533, 6142.648617304186, 6169.475594574422, 3494.3011397720556, 3507.877224297137, 3523.2777280657915, 3535.8939231823088, 3540.46892966052, 6567.868642627216, 6722.6302553359055, 6736.225334086643, 6778.796636100925, 6780.525363224626, 6580.2103747549645, 6612.735490580323, 6643.933560664428, 6702.731890724316, 6717.361850624183, 6558.770443034471, 6707.295733947963, 6710.127683401127, 6809.625470599066, 6817.420519255819, 6465.866264132919, 6557.114789503356, 6562.212534495891, 6573.647439805638, 6596.934030659632, 6382.138411314599, 6414.807555773335, 6462.644554549545, 6475.838104047248, 6492.220933487823, 6716.731330746716, 6719.932800672126, 6721.966390168213, 6731.427828634425, 6748.0, 4355.455568053993, 4364.781760912006, 4377.956220437883, 4380.080183161572, 4391.407160033483, 6279.277883043508, 6293.276273228455, 6383.170187875547, 6527.869442611026, 6537.725143596953, 6387.808365749035, 6394.616323020634, 6395.456386207038, 6412.839679007876, 6416.743330266737, 6227.719752611234, 6325.387026308446, 6337.96831015842, 6348.841278968062, 6410.423061924449, 6288.874222515433, 6299.338569450142, 6303.936960630335, 6310.810675679737, 6324.683765811783, 6409.679516024087, 6454.290028097, 6455.806325810233, 6476.125723027331, 6490.545661803629, 5968.31364393083, 6288.434040936155, 6301.96849015752, 6318.494520438446, 6320.525960553069], "value": 4762.708884017466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.3428046197498, 766.4175226827616, 766.9271419215172, 788.2826627768729, 797.9082405523363, 14241.287935603217, 14629.195394253311, 14754.483593074245, 14783.334749936252, 14828.481003164892, 12509.812352814692, 12537.0, 12580.370981450913, 12590.244585324886, 12612.747745045117, 691.6887400669718, 812.0864027968536, 828.1345993436873, 836.5817091454273, 840.1136800675285, 825.2737590920012, 826.7023871406288, 827.9420440569132, 836.1429534726893, 837.8324335132997, 879.028673315983, 880.9911900880974, 883.5802993578031, 884.3676771108657, 885.4775682347414, 878.2534845381439, 881.0352663833124, 882.858742601184, 882.9293656507464, 883.2845395229884, 647.5078940005629, 648.0182523476936, 648.5298158834854, 649.5940037476577, 650.2327253840466, 12654.55709050188, 12792.680182995406, 12799.552015679406, 12805.615831525069, 12838.229706217657, 14519.20144392058, 14568.635784105376, 14573.92713036428, 14599.0, 14698.779518307234, 11391.829122563167, 11540.653780386601, 11631.418429078512, 11650.533978640844, 11661.12541559378, 3830.8561858907333, 3895.7662540247675, 3905.0627849316243, 3930.3711406174875, 3971.8808435746973, 55725.1641225382, 56202.43797562013, 58994.0, 59629.0, 59655.0, 10545.894541054644, 10851.457427128613, 10905.291156074829, 11125.276857004268, 11194.160437967023, 19318.517037073903, 19580.804191957897, 21340.466488337916, 21466.356009319745, 21469.785302146778, 7817.8827317590285, 7857.174996625272, 7868.921310786819, 7979.0, 8159.184081591912, 4630.4443466784205, 4634.95365046352, 4669.929951050737, 4674.252119660838, 4718.882027949328, 3437.7077948374167, 3470.583529976419, 3477.652234776502, 3484.7212223022098, 3484.8606055757487, 546.2516352596967, 551.6193826259868, 604.9243844519435, 632.3202557250974, 634.6255709131634, 678.7726111752618, 696.7213114754038, 747.9252074792478, 776.6699152860125, 806.4274365200656, 8928.15182557656, 8958.820823583492, 9221.723348299562, 9334.813303734023, 9613.134817866425, 543.5488544508049, 606.7906572232573, 689.6241548356157, 753.3182469864781, 779.3375630713848, 4346.087321662423, 4346.304591265382, 4518.502964673883, 4531.546845315508, 4547.522510136454, 69391.30608693848, 69396.65301673459, 70719.6464017687, 70836.0, 71026.289737103, 60127.0, 60221.698891505264, 61408.692956534935, 61672.0, 62656.686716566124, 55587.444125559035, 56150.15774763382, 56581.7170914151, 56665.716671417205, 56949.430505694414, 8466.0, 8721.215090641872, 8765.386422950332, 8904.599293031959, 9178.67874624385, 29700.70299297066, 30644.540331895485, 30770.230744231572, 30879.0, 31963.36073278475, 41773.0, 41862.581374185866, 41894.790526047174, 42079.36880946727, 44085.33871991858, 11791.76416471683, 11926.105542084551, 11948.880511194777, 12027.939860300643, 12199.268043917393, 13210.471581136648, 13310.46758129664, 13451.260180690157, 13496.595102146952, 13865.722685546434, 12596.496140154657, 13024.8046279304, 13058.0, 13186.538471153464, 13455.596332110052, 12099.939500302795, 12613.810792838298, 12767.93616031914, 12824.23054616726, 12882.35588220604, 10325.0, 11275.210735248782, 11295.491702873225, 11314.60398886035, 12808.10343275999, 10661.57353705874, 10687.358538644907, 10789.136869050613, 10855.294405863593, 10862.293950893167, 10638.635462118269, 10675.199360048182, 10927.344359338465, 10977.890221098007, 11009.119270458184], "value": 6775.167023598175, "unit": "score"}], "name": "dromaeo_css", "value": 6893.790167734608, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 113.0, 112.0, 112.0, 123.0, 111.0, 111.0, 111.0, 112.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 154.0, 183.0, 136.0, 153.0, 160.0, 129.0, 186.0, 136.0], "value": 151.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 165.0, 164.0, 174.0, 165.0, 165.0, 167.0], "value": 164.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 159.0, 77.0, 77.0, 74.0, 77.0, 162.0, 74.0], "value": 93.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 157.0, 92.0, 93.0, 93.0, 160.0, 93.0, 94.0, 151.0], "value": 111.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 117.0, 118.0, 117.0, 120.0, 121.0, 123.0], "value": 118.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 148.0, 147.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 100.0, 96.0, 95.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 69.0, 59.0, 59.0, 65.0, 65.0, 73.0, 58.0, 57.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 60.0, 61.0, 61.0, 62.0, 61.0, 60.0, 61.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 85.0, 76.0, 79.0, 78.0, 78.0, 78.0, 78.0, 78.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 125.0, 137.0, 134.0, 136.0, 134.0, 125.0, 146.0, 162.0, 134.0], "value": 136.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 172.0, 175.0, 173.0, 181.0, 170.0, 169.0, 169.0, 173.0, 170.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 70.0, 70.0, 74.0, 71.0, 69.0, 85.0, 69.0, 70.0, 73.0], "value": 72.4, "unit": "score"}], "name": "kraken", "value": 1577.9, "alertThreshold": 2.0}]} 14:25:16 INFO - Return code: 0 14:25:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:25:16 ERROR - # TBPL SUCCESS # 14:25:16 INFO - Running post-action listener: _resource_record_post_action 14:25:16 INFO - Running post-run listener: _resource_record_post_run 14:25:17 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 7811072; Write bytes: 418717696; Read time: 1968; Write time: 453032 14:25:17 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 1228800; Read time: 0; Write time: 212 14:25:17 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 6074368; Write bytes: 417488896; Read time: 1912; Write time: 452820 14:25:17 INFO - Running post-run listener: _upload_blobber_files 14:25:17 INFO - Blob upload gear active. 14:25:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:25:17 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 14:25:17 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:25:17 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:25:18 INFO - (blobuploader) - INFO - Open directory for files ... 14:25:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:25:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:25:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:25:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:25:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:25:18 INFO - (blobuploader) - INFO - Done attempting. 14:25:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:25:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:25:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:25:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:25:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:25:19 INFO - (blobuploader) - INFO - Done attempting. 14:25:19 INFO - (blobuploader) - INFO - Iteration through files over. 14:25:19 INFO - Return code: 0 14:25:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:25:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:25:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bdda14b7eb704dcb4422ff3f45d8f3840272f34daad316f98362a04ccff1571ecc47f3e7436aa599ed0fd1e27a40cfccc912c2ed34a0259363bc1312fa40c22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a033319ef41535e3450d861acbf3f9297145ea7041a81cafc2c592609d0e33f3dd19768a7ebc73cadc6ae645596c370b53ea298bd546405fcccc4a681879800"} 14:25:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:25:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:25:19 INFO - Contents: 14:25:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bdda14b7eb704dcb4422ff3f45d8f3840272f34daad316f98362a04ccff1571ecc47f3e7436aa599ed0fd1e27a40cfccc912c2ed34a0259363bc1312fa40c22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a033319ef41535e3450d861acbf3f9297145ea7041a81cafc2c592609d0e33f3dd19768a7ebc73cadc6ae645596c370b53ea298bd546405fcccc4a681879800"} 14:25:19 INFO - Running post-run listener: copy_logs_to_upload_dir 14:25:19 INFO - Copying logs to upload dir... 14:25:19 INFO - mkdir: /builds/slave/test/build/upload/logs 14:25:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.744659 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-04-24 14:25:19.405014) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 14:25:19.408110) ========= 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=6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bdda14b7eb704dcb4422ff3f45d8f3840272f34daad316f98362a04ccff1571ecc47f3e7436aa599ed0fd1e27a40cfccc912c2ed34a0259363bc1312fa40c22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a033319ef41535e3450d861acbf3f9297145ea7041a81cafc2c592609d0e33f3dd19768a7ebc73cadc6ae645596c370b53ea298bd546405fcccc4a681879800"} build_url:https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011047 build_url: 'https://queue.taskcluster.net/v1/task/UrI7cJ3eRg2foBzQMi49dg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bdda14b7eb704dcb4422ff3f45d8f3840272f34daad316f98362a04ccff1571ecc47f3e7436aa599ed0fd1e27a40cfccc912c2ed34a0259363bc1312fa40c22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a033319ef41535e3450d861acbf3f9297145ea7041a81cafc2c592609d0e33f3dd19768a7ebc73cadc6ae645596c370b53ea298bd546405fcccc4a681879800"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 14:25:19.433297) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:25:19.433645) ========= 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=6d15e502d8e29d5d2676d664000001c3-1461515724.403827-1009635162 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004348 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:25:19.516718) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 14:25:19.517053) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 14:25:19.517376) ========= ========= Total master_lag: 0.16 =========