builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1464993033.17 results: success (0) buildid: 20160603124626 builduid: 5422d12ecc704baa87f2d6e8f93b27c8 revision: d992dde457ca958041d6e10325dc15fa0edc2a41 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:33.170650) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:33.171463) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:33.171916) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:33.192383) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:33.192963) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-03 15:30:33-- 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% 46.6M=0s 2016-06-03 15:30:33 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.284963 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:33.494669) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:33.495152) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019635 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:33.546391) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:33.546840) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d992dde457ca958041d6e10325dc15fa0edc2a41 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d992dde457ca958041d6e10325dc15fa0edc2a41 --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=edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-03 15:30:33,541 truncating revision to first 12 chars 2016-06-03 15:30:33,541 Setting DEBUG logging. 2016-06-03 15:30:33,541 attempt 1/10 2016-06-03 15:30:33,541 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d992dde457ca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 15:30:33,919 unpacking tar archive at: mozilla-aurora-d992dde457ca/testing/mozharness/ program finished with exit code 0 elapsedTime=0.714748 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:34.275756) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:34.276111) ========= script_repo_revision: d992dde457ca958041d6e10325dc15fa0edc2a41 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:34.276557) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:34.276846) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 15:30:34.288096) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-06-03 15:30:34.288496) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:30:34 INFO - MultiFileLogger online at 20160603 15:30:34 in /builds/slave/test 15:30:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:30:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:30:34 INFO - {'append_to_log': False, 15:30:34 INFO - 'base_work_dir': '/builds/slave/test', 15:30:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:30:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:30:34 INFO - 'branch': 'Mozilla-Aurora', 15:30:34 INFO - 'buildbot_json_path': 'buildprops.json', 15:30:34 INFO - 'config_files': ('talos/linux_config.py',), 15:30:34 INFO - 'default_actions': ('clobber', 15:30:34 INFO - 'read-buildbot-config', 15:30:34 INFO - 'download-and-extract', 15:30:34 INFO - 'populate-webroot', 15:30:34 INFO - 'create-virtualenv', 15:30:34 INFO - 'install', 15:30:34 INFO - 'run-tests'), 15:30:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:30:34 INFO - 'download_minidump_stackwalk': True, 15:30:34 INFO - 'download_symbols': 'ondemand', 15:30:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:30:34 INFO - 'tooltool.py': '/tools/tooltool.py', 15:30:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:30:34 INFO - '/tools/misc-python/virtualenv.py')}, 15:30:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:30:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:30:34 INFO - 'installer_path': 'installer.exe', 15:30:34 INFO - 'log_level': 'info', 15:30:34 INFO - 'log_name': 'talos', 15:30:34 INFO - 'log_to_console': True, 15:30:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:30:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:30:34 INFO - 'opt_config_files': (), 15:30:34 INFO - 'pip_index': False, 15:30:34 INFO - 'sps_profile': False, 15:30:34 INFO - 'sps_profile_interval': 0, 15:30:34 INFO - 'suite': 'dromaeojs', 15:30:34 INFO - 'system_bits': '32', 15:30:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:30:34 INFO - 'title': 'talos-linux64-ix-033', 15:30:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:30:34 INFO - 'use_talos_json': True, 15:30:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:30:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:30:34 INFO - 'work_dir': 'build'} 15:30:34 INFO - ##### 15:30:34 INFO - ##### Running clobber step. 15:30:34 INFO - ##### 15:30:34 INFO - Running pre-action listener: _resource_record_pre_action 15:30:34 INFO - Running main action method: clobber 15:30:34 INFO - rmtree: /builds/slave/test/build 15:30:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:30:34 INFO - Running post-action listener: _resource_record_post_action 15:30:34 INFO - ##### 15:30:34 INFO - ##### Running read-buildbot-config step. 15:30:34 INFO - ##### 15:30:34 INFO - Running pre-action listener: _resource_record_pre_action 15:30:34 INFO - Running main action method: read_buildbot_config 15:30:34 INFO - Using buildbot properties: 15:30:34 INFO - { 15:30:34 INFO - "project": "", 15:30:34 INFO - "product": "firefox", 15:30:34 INFO - "script_repo_revision": "production", 15:30:34 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 15:30:34 INFO - "repository": "", 15:30:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 15:30:34 INFO - "buildid": "20160603124626", 15:30:34 INFO - "pgo_build": "True", 15:30:34 INFO - "basedir": "/builds/slave/test", 15:30:34 INFO - "buildnumber": 357, 15:30:34 INFO - "slavename": "talos-linux64-ix-033", 15:30:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:30:34 INFO - "platform": "ubuntu64_hw", 15:30:34 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 15:30:34 INFO - "branch": "mozilla-aurora", 15:30:34 INFO - "repo_path": "releases/mozilla-aurora", 15:30:34 INFO - "revision": "d992dde457ca958041d6e10325dc15fa0edc2a41", 15:30:34 INFO - "stage_platform": "linux64-pgo", 15:30:34 INFO - "builduid": "5422d12ecc704baa87f2d6e8f93b27c8", 15:30:34 INFO - "slavebuilddir": "test" 15:30:34 INFO - } 15:30:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 15:30:34 INFO - Running post-action listener: _resource_record_post_action 15:30:34 INFO - ##### 15:30:34 INFO - ##### Running download-and-extract step. 15:30:34 INFO - ##### 15:30:34 INFO - Running pre-action listener: _resource_record_pre_action 15:30:34 INFO - Running main action method: download_and_extract 15:30:34 INFO - mkdir: /builds/slave/test/build/tests 15:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:34 INFO - https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:30:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 15:30:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 15:30:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 15:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:30:39 INFO - Downloaded 1361 bytes. 15:30:39 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 15:30:39 INFO - Using the following test package requirements: 15:30:39 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 15:30:39 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:30:39 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:30:39 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:30:39 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:30:39 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:30:39 INFO - u'jsshell-linux-x86_64.zip'], 15:30:39 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:30:39 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:30:39 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 15:30:39 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:30:39 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:30:39 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:30:39 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:30:39 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:30:39 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:30:39 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:30:39 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:30:39 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:39 INFO - https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:30:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:30:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:30:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:30:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:30:42 INFO - Downloaded 24174242 bytes. 15:30:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:30:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:30:43 INFO - Return code: 0 15:30:43 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:43 INFO - https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:30:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:30:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:30:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:30:45 INFO - Downloaded 24174242 bytes. 15:30:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:30:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:30:47 INFO - Return code: 0 15:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:47 INFO - https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:30:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 15:30:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 15:30:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 15:30:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:30:48 INFO - Downloaded 10840145 bytes. 15:30:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:30:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:30:48 INFO - Return code: 0 15:30: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')]} 15:30:48 INFO - https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:30:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 15:30:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 15:30:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 15:30:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:30:50 INFO - Downloaded 62807674 bytes. 15:30:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 15:30:50 INFO - mkdir: /builds/slave/test/properties 15:30:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:30:50 INFO - Writing to file /builds/slave/test/properties/build_url 15:30:50 INFO - Contents: 15:30:50 INFO - build_url:https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 15:30:50 INFO - Running post-action listener: _resource_record_post_action 15:30:50 INFO - Running post-action listener: set_extra_try_arguments 15:30:51 INFO - ##### 15:30:51 INFO - ##### Running populate-webroot step. 15:30:51 INFO - ##### 15:30:51 INFO - Running pre-action listener: _resource_record_pre_action 15:30:51 INFO - Running main action method: populate_webroot 15:30:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:30:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:30:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:30:51 INFO - '--tppagecycles', 15:30:51 INFO - '7'], 15:30:51 INFO - 'tests': ['tsvgm']}}, 15:30:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:30:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:30:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:30:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:30:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:30:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:30:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:51 INFO - 'talos_options': ['--disable-e10s'], 15:30:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:30:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:30:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:51 INFO - 'talos_options': ['--disable-e10s'], 15:30:51 INFO - 'tests': ['damp', 'tps']}, 15:30:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:51 INFO - 'tests': ['damp', 'tps']}, 15:30:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:30:51 INFO - 'tests': ['dromaeo_dom']}, 15:30:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:30:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:30:51 INFO - 'tests': ['a11yr', 15:30:51 INFO - 'ts_paint', 15:30:51 INFO - 'tpaint', 15:30:51 INFO - 'sessionrestore', 15:30:51 INFO - 'sessionrestore_no_auto_restore', 15:30:51 INFO - 'tabpaint']}, 15:30:51 INFO - 'other-e10s': {'tests': ['a11yr', 15:30:51 INFO - 'ts_paint', 15:30:51 INFO - 'tpaint', 15:30:51 INFO - 'sessionrestore', 15:30:51 INFO - 'sessionrestore_no_auto_restore', 15:30:51 INFO - 'tabpaint']}, 15:30:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:30:51 INFO - 'ts_paint', 15:30:51 INFO - 'tpaint', 15:30:51 INFO - 'sessionrestore', 15:30:51 INFO - 'sessionrestore_no_auto_restore', 15:30:51 INFO - 'tabpaint']}, 15:30:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:30:51 INFO - 'ts_paint', 15:30:51 INFO - 'tpaint', 15:30:51 INFO - 'sessionrestore', 15:30:51 INFO - 'sessionrestore_no_auto_restore', 15:30:51 INFO - 'tabpaint']}, 15:30:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:30:51 INFO - 'tests': ['a11yr', 15:30:51 INFO - 'ts_paint', 15:30:51 INFO - 'tpaint', 15:30:51 INFO - 'sessionrestore', 15:30:51 INFO - 'sessionrestore_no_auto_restore', 15:30:51 INFO - 'tabpaint']}, 15:30:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:30:51 INFO - 'tests': ['a11yr', 15:30:51 INFO - 'ts_paint', 15:30:51 INFO - 'tpaint', 15:30:51 INFO - 'sessionrestore', 15:30:51 INFO - 'sessionrestore_no_auto_restore', 15:30:51 INFO - 'tabpaint']}, 15:30:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:30:51 INFO - 'tests': ['tsvgx', 15:30:51 INFO - 'tsvgr_opacity', 15:30:51 INFO - 'tart', 15:30:51 INFO - 'tscrollx', 15:30:51 INFO - 'cart']}, 15:30:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:30:51 INFO - 'tsvgr_opacity', 15:30:51 INFO - 'tart', 15:30:51 INFO - 'tscrollx', 15:30:51 INFO - 'cart']}, 15:30:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:51 INFO - 'talos_options': ['--disable-e10s'], 15:30:51 INFO - 'tests': ['tp5o']}, 15:30:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:51 INFO - 'tests': ['tp5o']}, 15:30:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:51 INFO - 'talos_options': ['--disable-e10s', 15:30:51 INFO - '--xperf_path', 15:30:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:30:51 INFO - 'tests': ['tp5n']}, 15:30:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:51 INFO - 'talos_options': ['--xperf_path', 15:30:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:30:51 INFO - 'tests': ['tp5n']}}, 15:30:51 INFO - 'talos.zip': {'path': '', 15:30:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:30:51 INFO - Running post-action listener: _resource_record_post_action 15:30:51 INFO - ##### 15:30:51 INFO - ##### Running create-virtualenv step. 15:30:51 INFO - ##### 15:30:51 INFO - Running pre-action listener: _resource_record_pre_action 15:30:51 INFO - Running main action method: create_virtualenv 15:30:51 INFO - Creating virtualenv /builds/slave/test/build/venv 15:30:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:30:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:30:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:30:51 INFO - Using real prefix '/usr' 15:30:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:30:51 INFO - Installing distribute.............................................................................................................................................................................................done. 15:30:53 INFO - Installing pip.................done. 15:30:53 INFO - Return code: 0 15:30:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:30: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')]} 15:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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')]} 15:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:53 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 0x7f12ec435f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xecf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029410>, '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 0x102a310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:30:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:30:53 INFO - Using env: {'DISPLAY': ':0', 15:30:53 INFO - 'HOME': '/home/cltbld', 15:30:53 INFO - 'LANG': 'en_US.UTF-8', 15:30:53 INFO - 'LANGUAGE': 'en_US:en', 15:30:53 INFO - 'LOGNAME': 'cltbld', 15:30:53 INFO - 'MAIL': '/var/mail/cltbld', 15:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:53 INFO - 'MOZ_NO_REMOTE': '1', 15:30:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:53 INFO - 'NO_EM_RESTART': '1', 15:30:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:53 INFO - 'PWD': '/builds/slave/test', 15:30:53 INFO - 'SHELL': '/bin/bash', 15:30:53 INFO - 'SHLVL': '1', 15:30:53 INFO - 'TERM': 'linux', 15:30:53 INFO - 'TMOUT': '86400', 15:30:53 INFO - 'USER': 'cltbld', 15:30:53 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 15:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:53 INFO - '_': '/tools/buildbot/bin/python'} 15:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:30:53 INFO - Cleaning up... 15:30:53 INFO - Return code: 0 15:30:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:30: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')]} 15:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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')]} 15:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:53 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 0x7f12ec435f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xecf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029410>, '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 0x102a310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:30:53 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 15:30:53 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 15:30:53 INFO - Using env: {'DISPLAY': ':0', 15:30:53 INFO - 'HOME': '/home/cltbld', 15:30:53 INFO - 'LANG': 'en_US.UTF-8', 15:30:53 INFO - 'LANGUAGE': 'en_US:en', 15:30:53 INFO - 'LOGNAME': 'cltbld', 15:30:53 INFO - 'MAIL': '/var/mail/cltbld', 15:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:53 INFO - 'MOZ_NO_REMOTE': '1', 15:30:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:53 INFO - 'NO_EM_RESTART': '1', 15:30:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:53 INFO - 'PWD': '/builds/slave/test', 15:30:53 INFO - 'SHELL': '/bin/bash', 15:30:53 INFO - 'SHLVL': '1', 15:30:53 INFO - 'TERM': 'linux', 15:30:53 INFO - 'TMOUT': '86400', 15:30:53 INFO - 'USER': 'cltbld', 15:30:53 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 15:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:53 INFO - '_': '/tools/buildbot/bin/python'} 15:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:54 INFO - Downloading/unpacking psutil>=0.7.1 15:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:30:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:30:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:30:56 INFO - Installing collected packages: psutil 15:30:56 INFO - Running setup.py install for psutil 15:30:56 INFO - building 'psutil._psutil_linux' extension 15:30:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:30:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:30:56 INFO - building 'psutil._psutil_posix' extension 15:30:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:30:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:30:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:30:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:30:56 INFO - Successfully installed psutil 15:30:56 INFO - Cleaning up... 15:30:56 INFO - Return code: 0 15:30:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f12ec435f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xecf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029410>, '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 0x102a310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:30:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:30:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:30:56 INFO - Using env: {'DISPLAY': ':0', 15:30:56 INFO - 'HOME': '/home/cltbld', 15:30:56 INFO - 'LANG': 'en_US.UTF-8', 15:30:56 INFO - 'LANGUAGE': 'en_US:en', 15:30:56 INFO - 'LOGNAME': 'cltbld', 15:30:56 INFO - 'MAIL': '/var/mail/cltbld', 15:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:56 INFO - 'MOZ_NO_REMOTE': '1', 15:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:56 INFO - 'NO_EM_RESTART': '1', 15:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:56 INFO - 'PWD': '/builds/slave/test', 15:30:56 INFO - 'SHELL': '/bin/bash', 15:30:56 INFO - 'SHLVL': '1', 15:30:56 INFO - 'TERM': 'linux', 15:30:56 INFO - 'TMOUT': '86400', 15:30:56 INFO - 'USER': 'cltbld', 15:30:56 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 15:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:56 INFO - '_': '/tools/buildbot/bin/python'} 15:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:30:58 INFO - Installing collected packages: mozsystemmonitor 15:30:58 INFO - Running setup.py install for mozsystemmonitor 15:30:59 INFO - Successfully installed mozsystemmonitor 15:30:59 INFO - Cleaning up... 15:31:00 INFO - Return code: 0 15:31:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:31: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')]} 15:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31: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')]} 15:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31: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', '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 0x7f12ec435f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xecf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029410>, '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 0x102a310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:31: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:31: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 blobuploader==1.2.4 15:31:00 INFO - Using env: {'DISPLAY': ':0', 15:31:00 INFO - 'HOME': '/home/cltbld', 15:31:00 INFO - 'LANG': 'en_US.UTF-8', 15:31:00 INFO - 'LANGUAGE': 'en_US:en', 15:31:00 INFO - 'LOGNAME': 'cltbld', 15:31:00 INFO - 'MAIL': '/var/mail/cltbld', 15:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:00 INFO - 'MOZ_NO_REMOTE': '1', 15:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:00 INFO - 'NO_EM_RESTART': '1', 15:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:00 INFO - 'PWD': '/builds/slave/test', 15:31:00 INFO - 'SHELL': '/bin/bash', 15:31:00 INFO - 'SHLVL': '1', 15:31:00 INFO - 'TERM': 'linux', 15:31:00 INFO - 'TMOUT': '86400', 15:31:00 INFO - 'USER': 'cltbld', 15:31:00 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 15:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:00 INFO - '_': '/tools/buildbot/bin/python'} 15:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:00 INFO - Downloading/unpacking blobuploader==1.2.4 15:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:02 INFO - Downloading blobuploader-1.2.4.tar.gz 15:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:31:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:31:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:03 INFO - Downloading docopt-0.6.1.tar.gz 15:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:31:03 INFO - Installing collected packages: blobuploader, requests, docopt 15:31:03 INFO - Running setup.py install for blobuploader 15:31:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:31:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:31:03 INFO - Running setup.py install for requests 15:31:03 INFO - Running setup.py install for docopt 15:31:03 INFO - Successfully installed blobuploader requests docopt 15:31:03 INFO - Cleaning up... 15:31:03 INFO - Return code: 0 15:31:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:31:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:03 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 0x7f12ec435f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xecf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029410>, '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 0x102a310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:31:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:31:03 INFO - Using env: {'DISPLAY': ':0', 15:31:03 INFO - 'HOME': '/home/cltbld', 15:31:03 INFO - 'LANG': 'en_US.UTF-8', 15:31:03 INFO - 'LANGUAGE': 'en_US:en', 15:31:03 INFO - 'LOGNAME': 'cltbld', 15:31:03 INFO - 'MAIL': '/var/mail/cltbld', 15:31:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:03 INFO - 'MOZ_NO_REMOTE': '1', 15:31:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:03 INFO - 'NO_EM_RESTART': '1', 15:31:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:03 INFO - 'PWD': '/builds/slave/test', 15:31:03 INFO - 'SHELL': '/bin/bash', 15:31:03 INFO - 'SHLVL': '1', 15:31:03 INFO - 'TERM': 'linux', 15:31:03 INFO - 'TMOUT': '86400', 15:31:03 INFO - 'USER': 'cltbld', 15:31:03 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 15:31:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:03 INFO - '_': '/tools/buildbot/bin/python'} 15:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:31:03 INFO - Running setup.py (path:/tmp/pip-4MIqEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:31:04 INFO - Running setup.py (path:/tmp/pip-UGuEZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:31:04 INFO - Running setup.py (path:/tmp/pip-9Twsqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:31:04 INFO - Running setup.py (path:/tmp/pip-JhZbiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:31:04 INFO - Running setup.py (path:/tmp/pip-HYKeDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:31:04 INFO - Running setup.py (path:/tmp/pip-kHJC0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:31:04 INFO - Running setup.py (path:/tmp/pip-Wtu8Yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:31:04 INFO - Running setup.py (path:/tmp/pip-pgbade-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:31:04 INFO - Running setup.py (path:/tmp/pip-1JWuff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:31:04 INFO - Running setup.py (path:/tmp/pip-_964WE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:31:04 INFO - Running setup.py (path:/tmp/pip-laH9Tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:31:04 INFO - Running setup.py (path:/tmp/pip-CHFuaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:31:04 INFO - Running setup.py (path:/tmp/pip-Ad9Iv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:31:04 INFO - Running setup.py (path:/tmp/pip-YXwL5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:31:04 INFO - Running setup.py (path:/tmp/pip-9SuQ_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:31:05 INFO - Running setup.py (path:/tmp/pip-wFJZje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:31:05 INFO - Running setup.py (path:/tmp/pip-ehLhTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:31:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:31:05 INFO - Running setup.py install for manifestparser 15:31:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:31:05 INFO - Running setup.py install for mozcrash 15:31:05 INFO - Running setup.py install for mozdebug 15:31:05 INFO - Running setup.py install for mozdevice 15:31:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:31:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:31:05 INFO - Running setup.py install for mozfile 15:31:05 INFO - Running setup.py install for mozhttpd 15:31:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:31:05 INFO - Running setup.py install for mozinfo 15:31:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:31:05 INFO - Running setup.py install for mozInstall 15:31:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:31:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:31:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:31:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:31:05 INFO - Running setup.py install for mozleak 15:31:06 INFO - Running setup.py install for mozlog 15:31:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:31:06 INFO - Running setup.py install for moznetwork 15:31:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:31:06 INFO - Running setup.py install for mozprocess 15:31:06 INFO - Running setup.py install for mozprofile 15:31:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:31:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:31:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:31:06 INFO - Running setup.py install for mozrunner 15:31:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:31:06 INFO - Running setup.py install for mozscreenshot 15:31:06 INFO - Running setup.py install for moztest 15:31:06 INFO - Running setup.py install for mozversion 15:31:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:31:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:31:06 INFO - Cleaning up... 15:31:06 INFO - Return code: 0 15:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:06 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 0x7f12ec435f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xecf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029410>, '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 0x102a310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:31:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:31:06 INFO - Using env: {'DISPLAY': ':0', 15:31:06 INFO - 'HOME': '/home/cltbld', 15:31:06 INFO - 'LANG': 'en_US.UTF-8', 15:31:06 INFO - 'LANGUAGE': 'en_US:en', 15:31:06 INFO - 'LOGNAME': 'cltbld', 15:31:06 INFO - 'MAIL': '/var/mail/cltbld', 15:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:06 INFO - 'MOZ_NO_REMOTE': '1', 15:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:06 INFO - 'NO_EM_RESTART': '1', 15:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:06 INFO - 'PWD': '/builds/slave/test', 15:31:06 INFO - 'SHELL': '/bin/bash', 15:31:06 INFO - 'SHLVL': '1', 15:31:06 INFO - 'TERM': 'linux', 15:31:06 INFO - 'TMOUT': '86400', 15:31:06 INFO - 'USER': 'cltbld', 15:31:06 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 15:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:06 INFO - '_': '/tools/buildbot/bin/python'} 15:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:31:07 INFO - Running setup.py (path:/tmp/pip-qdvdDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:31:07 INFO - Running setup.py (path:/tmp/pip-3300lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:31:07 INFO - Running setup.py (path:/tmp/pip-89d7p2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:31:07 INFO - Running setup.py (path:/tmp/pip-FQZIY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:31:07 INFO - Running setup.py (path:/tmp/pip-Ib6e0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:31:07 INFO - Running setup.py (path:/tmp/pip-N7EMbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:31:07 INFO - Running setup.py (path:/tmp/pip-GuKWJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:31:07 INFO - Running setup.py (path:/tmp/pip-KeZ_Gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:31:07 INFO - Running setup.py (path:/tmp/pip-UAusEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:31:07 INFO - Running setup.py (path:/tmp/pip-zYVuSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:31:07 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)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:31:07 INFO - Running setup.py (path:/tmp/pip-T5XIuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:31:07 INFO - Running setup.py (path:/tmp/pip-2SJbdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:31:08 INFO - Running setup.py (path:/tmp/pip-cpbavW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:31:08 INFO - Running setup.py (path:/tmp/pip-wvJuXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:31:08 INFO - Running setup.py (path:/tmp/pip-LC571S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:31:08 INFO - Running setup.py (path:/tmp/pip-M1m9T0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:31:08 INFO - Running setup.py (path:/tmp/pip-D3hRXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:31:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:10 INFO - Downloading blessings-1.6.tar.gz 15:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:31:10 INFO - Installing collected packages: blessings 15:31:10 INFO - Running setup.py install for blessings 15:31:10 INFO - Successfully installed blessings 15:31:10 INFO - Cleaning up... 15:31:10 INFO - Return code: 0 15:31:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:31:10 INFO - Reading from file tmpfile_stdout 15:31:10 INFO - Current package versions: 15:31:10 INFO - argparse == 1.2.1 15:31:10 INFO - blessings == 1.6 15:31:10 INFO - blobuploader == 1.2.4 15:31:10 INFO - docopt == 0.6.1 15:31:10 INFO - manifestparser == 1.1 15:31:10 INFO - mozInstall == 1.12 15:31:10 INFO - mozcrash == 0.17 15:31:10 INFO - mozdebug == 0.1 15:31:10 INFO - mozdevice == 0.48 15:31:10 INFO - mozfile == 1.2 15:31:10 INFO - mozhttpd == 0.7 15:31:10 INFO - mozinfo == 0.9 15:31:10 INFO - mozleak == 0.1 15:31:10 INFO - mozlog == 3.1 15:31:10 INFO - moznetwork == 0.27 15:31:10 INFO - mozprocess == 0.22 15:31:10 INFO - mozprofile == 0.28 15:31:10 INFO - mozrunner == 6.11 15:31:10 INFO - mozscreenshot == 0.1 15:31:10 INFO - mozsystemmonitor == 0.0 15:31:10 INFO - moztest == 0.7 15:31:10 INFO - mozversion == 1.4 15:31:10 INFO - psutil == 3.1.1 15:31:10 INFO - requests == 1.2.3 15:31:10 INFO - wsgiref == 0.1.2 15:31:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:10 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 0x7f12ec435f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xecf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029410>, '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 0x102a310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:31:10 INFO - Using env: {'DISPLAY': ':0', 15:31:10 INFO - 'HOME': '/home/cltbld', 15:31:10 INFO - 'LANG': 'en_US.UTF-8', 15:31:10 INFO - 'LANGUAGE': 'en_US:en', 15:31:10 INFO - 'LOGNAME': 'cltbld', 15:31:10 INFO - 'MAIL': '/var/mail/cltbld', 15:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:10 INFO - 'MOZ_NO_REMOTE': '1', 15:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:10 INFO - 'NO_EM_RESTART': '1', 15:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:10 INFO - 'PWD': '/builds/slave/test', 15:31:10 INFO - 'SHELL': '/bin/bash', 15:31:10 INFO - 'SHLVL': '1', 15:31:10 INFO - 'TERM': 'linux', 15:31:10 INFO - 'TMOUT': '86400', 15:31:10 INFO - 'USER': 'cltbld', 15:31:10 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 15:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:10 INFO - '_': '/tools/buildbot/bin/python'} 15:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:31:10 INFO - Cleaning up... 15:31:10 INFO - Return code: 0 15:31:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:10 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 0x7f12ec435f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xecf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029410>, '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 0x102a310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:31:10 INFO - Using env: {'DISPLAY': ':0', 15:31:10 INFO - 'HOME': '/home/cltbld', 15:31:10 INFO - 'LANG': 'en_US.UTF-8', 15:31:10 INFO - 'LANGUAGE': 'en_US:en', 15:31:10 INFO - 'LOGNAME': 'cltbld', 15:31:10 INFO - 'MAIL': '/var/mail/cltbld', 15:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:10 INFO - 'MOZ_NO_REMOTE': '1', 15:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:10 INFO - 'NO_EM_RESTART': '1', 15:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:10 INFO - 'PWD': '/builds/slave/test', 15:31:10 INFO - 'SHELL': '/bin/bash', 15:31:10 INFO - 'SHLVL': '1', 15:31:10 INFO - 'TERM': 'linux', 15:31:10 INFO - 'TMOUT': '86400', 15:31:10 INFO - 'USER': 'cltbld', 15:31:10 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 15:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:10 INFO - '_': '/tools/buildbot/bin/python'} 15:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:11 INFO - Downloading/unpacking jsonschema 15:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:31:13 INFO - Downloading/unpacking functools32 (from jsonschema) 15:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:13 INFO - Downloading functools32-3.2.3-2.tar.gz 15:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:31:13 INFO - warning: no files found matching '*.txt' 15:31:13 INFO - no previously-included directories found matching 'build' 15:31:13 INFO - no previously-included directories found matching 'dist' 15:31:13 INFO - no previously-included directories found matching '.git*' 15:31:13 INFO - Installing collected packages: jsonschema, functools32 15:31:13 INFO - Running setup.py install for functools32 15:31:13 INFO - warning: no files found matching '*.txt' 15:31:13 INFO - no previously-included directories found matching 'build' 15:31:13 INFO - no previously-included directories found matching 'dist' 15:31:13 INFO - no previously-included directories found matching '.git*' 15:31:13 INFO - Successfully installed jsonschema functools32 15:31:13 INFO - Cleaning up... 15:31:13 INFO - Return code: 0 15:31:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:31: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')]} 15:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31: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')]} 15:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:13 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 0x7f12ec435f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xecf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029410>, '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 0x102a310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:31:13 INFO - Using env: {'DISPLAY': ':0', 15:31:13 INFO - 'HOME': '/home/cltbld', 15:31:13 INFO - 'LANG': 'en_US.UTF-8', 15:31:13 INFO - 'LANGUAGE': 'en_US:en', 15:31:13 INFO - 'LOGNAME': 'cltbld', 15:31:13 INFO - 'MAIL': '/var/mail/cltbld', 15:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:13 INFO - 'MOZ_NO_REMOTE': '1', 15:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:13 INFO - 'NO_EM_RESTART': '1', 15:31:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:13 INFO - 'PWD': '/builds/slave/test', 15:31:13 INFO - 'SHELL': '/bin/bash', 15:31:13 INFO - 'SHLVL': '1', 15:31:13 INFO - 'TERM': 'linux', 15:31:13 INFO - 'TMOUT': '86400', 15:31:13 INFO - 'USER': 'cltbld', 15:31:13 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 15:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:13 INFO - '_': '/tools/buildbot/bin/python'} 15:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:13 INFO - Downloading/unpacking flake8 15:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:31:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:31:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31: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 15:31:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:31:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:31:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:31:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:31:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:31:16 INFO - no previously-included directories found matching 'docs/_build' 15:31:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:31:16 INFO - Running setup.py install for pep8 15:31:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:31:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:31:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:31:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:31:16 INFO - no previously-included directories found matching 'docs/_build' 15:31:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:31:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:31:16 INFO - Cleaning up... 15:31:16 INFO - Return code: 0 15:31:16 INFO - Running post-action listener: _resource_record_post_action 15:31:16 INFO - Running post-action listener: _start_resource_monitoring 15:31:16 INFO - Starting resource monitoring. 15:31:16 INFO - ##### 15:31:16 INFO - ##### Running install step. 15:31:16 INFO - ##### 15:31:16 INFO - Running pre-action listener: _resource_record_pre_action 15:31:16 INFO - Running main action method: install 15:31:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:31:16 INFO - Reading from file tmpfile_stdout 15:31:16 INFO - Detecting whether we're running mozinstall >=1.0... 15:31:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:31:16 INFO - Reading from file tmpfile_stdout 15:31:16 INFO - Output received: 15:31:16 INFO - Usage: mozinstall [options] installer 15:31:16 INFO - Options: 15:31:16 INFO - -h, --help show this help message and exit 15:31:16 INFO - -d DEST, --destination=DEST 15:31:16 INFO - Directory to install application into. [default: 15:31:16 INFO - "/builds/slave/test"] 15:31:16 INFO - --app=APP Application being installed. [default: firefox] 15:31:16 INFO - mkdir: /builds/slave/test/build/application 15:31:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:31:32 INFO - Reading from file tmpfile_stdout 15:31:32 INFO - Output received: 15:31:32 INFO - /builds/slave/test/build/application/firefox/firefox 15:31:32 INFO - Running post-action listener: _resource_record_post_action 15:31:32 INFO - ##### 15:31:32 INFO - ##### Running run-tests step. 15:31:32 INFO - ##### 15:31:32 INFO - Running pre-action listener: _resource_record_pre_action 15:31:32 INFO - Running main action method: run_tests 15:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:31:32 INFO - Python 2.7.3 15:31:32 INFO - Return code: 0 15:31:32 INFO - grabbing minidump binary from tooltool 15:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:32 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 0x1029410>, '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 0x102a310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:31:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:31:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:31:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:31:32 INFO - Return code: 0 15:31:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:31:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:31:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:31:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:31:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:31:33 INFO - Return code: 0 15:31:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:31:33 INFO - Using env: {'DISPLAY': ':0', 15:31:33 INFO - 'HOME': '/home/cltbld', 15:31:33 INFO - 'LANG': 'en_US.UTF-8', 15:31:33 INFO - 'LANGUAGE': 'en_US:en', 15:31:33 INFO - 'LOGNAME': 'cltbld', 15:31:33 INFO - 'MAIL': '/var/mail/cltbld', 15:31:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:31:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:33 INFO - 'MOZ_NO_REMOTE': '1', 15:31:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:33 INFO - 'NO_EM_RESTART': '1', 15:31:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:33 INFO - 'PWD': '/builds/slave/test', 15:31:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:31:33 INFO - 'SHELL': '/bin/bash', 15:31:33 INFO - 'SHLVL': '1', 15:31:33 INFO - 'TERM': 'linux', 15:31:33 INFO - 'TMOUT': '86400', 15:31:33 INFO - 'USER': 'cltbld', 15:31:33 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970', 15:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:33 INFO - '_': '/tools/buildbot/bin/python'} 15:31:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:31:33 INFO - mozversion application_buildid: 20160603124626 15:31:33 INFO - mozversion application_changeset: d992dde457ca958041d6e10325dc15fa0edc2a41 15:31:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:31:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:31:33 INFO - mozversion application_name: Firefox 15:31:33 INFO - mozversion application_remotingname: firefox-dev 15:31:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:31:33 INFO - mozversion application_vendor: Mozilla 15:31:33 INFO - mozversion application_version: 48.0a2 15:31:33 INFO - mozversion platform_buildid: 20160603124626 15:31:33 INFO - mozversion platform_changeset: d992dde457ca958041d6e10325dc15fa0edc2a41 15:31:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:31:33 INFO - mozversion platform_version: 48.0a2 15:31:33 INFO - using testdate: 1464993093 15:31:33 INFO - actual date: 1464993093 15:31:33 INFO - starting webserver on 'localhost:44211' 15:31:33 INFO - SUITE-START | Running 2 tests 15:31:33 INFO - TEST-START | dromaeo_css 15:31:33 INFO - operating with platform_type : linux_ 15:31:33 INFO - Initialising browser for dromaeo_css test... 15:31:33 INFO - TEST-INFO | started process 11947 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqGVnHN/profile http://localhost:44211/getInfo.html) 15:31:38 INFO - PROCESS | 11947 | __metrics Screen width/height:1600/1200 15:31:38 INFO - PROCESS | 11947 | colorDepth:24 15:31:38 INFO - PROCESS | 11947 | Browser inner width/height: 1024/697 15:31:38 INFO - PROCESS | 11947 | __metrics 15:31:40 ERROR - PROCESS | 11947 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:31:41 INFO - TEST-INFO | 11947: exit 0 15:31:41 INFO - Browser initialized. 15:31:41 INFO - Running cycle 1/1 for dromaeo_css test... 15:31:41 INFO - TEST-INFO | started process 12037 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqGVnHN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:31:52 INFO - PROCESS | 12037 | RSS: Main: 141959168 15:31:52 INFO - PROCESS | 12037 | 15:32:44 INFO - PROCESS | 12037 | 1464993164538 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:34:51 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44211/tests/dromaeo/cssquery-ext.html) 15:34:52 INFO - PROCESS | 12037 | RSS: Main: 247238656 15:34:52 INFO - PROCESS | 12037 | 15:37:55 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-ext.html (next: http://localhost:44211/tests/dromaeo/cssquery-jquery.html) 15:37:55 INFO - PROCESS | 12037 | RSS: Main: 226283520 15:37:55 INFO - PROCESS | 12037 | 15:40:57 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44211/tests/dromaeo/cssquery-mootools.html) 15:40:58 INFO - PROCESS | 12037 | RSS: Main: 217468928 15:40:58 INFO - PROCESS | 12037 | 15:44:00 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44211/tests/dromaeo/cssquery-prototype.html) 15:44:00 INFO - PROCESS | 12037 | RSS: Main: 212529152 15:44:00 INFO - PROCESS | 12037 | 15:47:03 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44211/tests/dromaeo/cssquery-yui.html) 15:47:03 INFO - PROCESS | 12037 | RSS: Main: 210665472 15:47:03 INFO - PROCESS | 12037 | 15:50:05 INFO - PROCESS | 12037 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-yui.html (next: http://localhost:44211/tests/dromaeo/cssquery-dojo.html) 15:50:06 INFO - PROCESS | 12037 | RSS: Main: 235982848 15:50:06 INFO - PROCESS | 12037 | 15:50:06 INFO - PROCESS | 12037 | __start_tp_report 15:50:06 INFO - PROCESS | 12037 | _x_x_mozilla_page_load 15:50:06 INFO - PROCESS | 12037 | _x_x_mozilla_page_load_details 15:50:06 INFO - PROCESS | 12037 | |i|pagename|runs| 15:50:06 INFO - PROCESS | 12037 | |0;dojo.html;3325.6009278886536;3392.474166504191;3395.7113645340137;3422.657734226576;3431.571053618298;5515.090010148326;5519.282493275873;5522.944770552293;5526.944730552693;5528.44715528447;5529.806456774014;5548.6393384430085;5557.5831812614115;5560.916586251209;5605.691686957216;4873.269009648563;4980.850574482763;4985.601151907857;5051.191809310511;5089.7455127243675;4888.951110488885;4941.530554597308;4987.326710894019;4993.251012348141;5012.749362531858;5036.244563315496;5051.898962020758;5061.44324124346;5063.772130254129;5099.15863882459;4971.975140124277;5009.5491405773655;5048.116579598555;5066.214736715815;5078.898422031557;4867.269909513566;4898.461169271377;4979.377577802775;4979.925301120486;4997.875053123701;5423.755930983115;5427.972860135714;5430.75561599725;5461.863453413656;5463.071277882769;5822.184894114827;5925.970370148165;5929.9703501482645;5933.673647949361;5939.168516407706;5936.881262374728;6028.0957856321465;6033.909491357633;6035.245594300713;6058.1215723719815;5511.641743286714;5511.917321240185;5512.91730624041;5513.338399392059;5514.476124768181;5864.912026319608;5947.37552556984;6012.488938440281;6013.9699301503215;6039.818805435844;5754.712264386764;5799.2750906136735;5800.042992906123;5830.446107619854;5835.270591176103;5889.852753681106;5900.527957763455;5902.144189092557;5902.852428689317;5907.556933229939;5720.2849643794525;5821.214136091573;5846.56150788684;5883.146943693226;5884.558658100659;5912.379200184005;5923.851903702356;5932.851678707981;5937.376575459514;5975.910361344583;4527.162474942146;4568.383268258809;4568.931466028012;4569.131864945716;4595.632349412039;3979.5821438749094;4043.757374557536;4079.8164082616354;4110.876673699794;4126.607972242632;5651.378348381675;5672.149177623307;5680.261565996477;5680.602357834912;5682.488576028095;5949.464548190684;5959.731812068468;5964.821055368346;5965.582409231399;5968.46283834457;5536.446355364512;5575.247341608912;5620.606557541015;5633.521150702236;5644.435556444405;5097.413797413345;5103.209002604603;5104.744762761847;5106.387233532;5141.331626888481;6165.445109940038;6201.472874805692;6205.348438413992;6218.875622487615;6225.501959843292;5816.970915145566;5930;5969.701514924323;5987.251593550806;6026.90959635597;6119.204503414528;6126.356732543019;6129.3564175762685;6130.6628135452975;6134.570580059353;5774.2782152230975;5774.595778295647;5777.335606405317;5779.97110014464;5787.9710601448405;5745.9425405745405;5894.823155305347;5925.85185370352;5935.732892019956;5940.79207227745;5728.226689396878;5779.653220806596;5787.218725472176;5788.507976822018;5794;5580.497755202051;5704.11586204139;5749.137629355463;5769.740361683818;5773.191753154478;5614.410486898817;5691.203231547669;5700.401457846866;5702.116171993348;5714.514266287246;5805.448482394166;5807.912881306699;5811.767529298781;5820.417958204146;5820.447057529528;5605;5628.690422026664;5701.344345400166;5711.228984087095;5760.769569217185 15:50:06 INFO - PROCESS | 12037 | |1;ext.html;674.1472037872089;680.442037529226;694.4964900447179;737.4432303610773;743.5315751076822;33223.83388083059;33809.66190338096;38789.80605096974;39654.206915861665;39794.204115917666;43486.34770478446;44045.77977110118;44132.3380149298;44227.77886110573;44350;8318.500889946623;8979.236764874977;9153.633854645806;9155.221806146452;10474.738131546696;10500.789984200312;10643.14854811617;10644.733881652983;10649.627263045795;10663.733406664818;10610.522526486287;10636.414997175152;10652.840207396895;10663.733406664856;10665.413402262873;8584.356173287028;10564;10635.627753028646;10819.83770243447;11084.168687348401;10510.842337364946;10610.62862799806;10621.84067238992;10624.256302058859;10629.096526795232;40577.391339129936;41132.38301425481;41137.794311028556;41206.38190427146;41327.58672413268;3650.8722194723323;3665.963340366589;4167.937480937789;4319.373690814846;4349.499807522112;34019.82990085034;34113.82943085294;34309.828450857836;35809;35860.64139358599;4503.144402563536;4517.728936263835;4595.1686218463965;4606.26299792035;4638;342394;343988.9999999975;344190;344414;344767;39572;40188.59811401907;40435.59564404377;40577;59837.40162598405;38215.426768598496;38262;38354.42468362977;38369.80815095906;38382.61617383846;16064.9557778744;16161.4343497977;16186.838131618768;16204.513864584082;16235.59411014734;12595.622131336075;13141.934290328487;13201.141925774795;13586;14023.1586104832;9101.544922753836;9115.90884091164;9152.725418237464;9169.816603667889;9257.953710231406;3481.0949153220345;3526.594441639193;3562.4300111981956;3565.982170089185;3596.0650230940146;1100.5567639839544;1123.393367581513;1158.2124155574122;1167;1201.2492192379764;30212.84893575518;31022.22444438862;31535.84232078871;32067;32198.034058978268;1197.2696655040418;1200.6758175292623;1216.8296438498617;1221.5358163897663;1232.1621297517595;15711.528654140395;16539.751903721455;16959.321627135025;16996.32014719422;17260.827391726172;531614;559009;565119;569338;571307;57912.4208757907;58291.417085829446;58666.41333586695;58687.41312586905;58759.70620146872;1404.6137312239216;1447.2185020089244;1494.9626259343604;1518.7949626800462;1594.6970075685583;32289.35421291608;33285.66714332923;33457.66542334643;33578.328433431685;33678.49482257817;53193.73403133114;53902.460975389746;54008.72995634997;54092.72953635207;54108.72945635246;448718;462645;465379;465688;465854;41972;46716.299255510516;49167.26249106195;49653.25520117129;49887.50112498829;51945;53208.4679153219;53526.73236633948;54124;54410;44470.55777408151;45078.32382514331;48916.75541622269;49519.00961980668;50115.74942125266;42231.7888410556;42963.3555496674;43230.783846081824;44446.55553444424;44785.55214447814;45752.313715294964;46728;46738.532614674776;47579.76210118927;47678.28482572695;38359.2328153441;39651.80174099111;40116;40119.3982090263;40312.69996378969;30364.696353036186;30595.847020764755;30769;30842.84578577093;31211.219719507193 15:50:06 INFO - PROCESS | 12037 | |2;jquery.html;434.2595874034771;436.02480762147485;439.4024127187025;446.89274574102217;453.4494904894675;18512.722309165372;18887.338943136976;19321.806781932177;19352.419427417182;19380.224791008342;19607.21571137153;19632.901835490804;19898.60202795943;19916.302929397472;19995.700064499044;5565.6104072714925;5651.32184137904;5824.359320474744;5834.329052158992;5844.736986835603;4256.702030857841;5719;5761.884762304751;5792.6524408535415;6052.969735151318;6284.717187726564;6307.716152773136;6311.242650881901;6312.621242725451;6324.3043265240785;5516.668999860001;5802.738876750556;5808.3901190375245;6234.5324100692405;6266.436020758131;3947.624975627311;5517.475839795213;5639;5666.320041595035;5678.858028549319;13871.237081960488;14102.92948535261;14109.64725881851;14113.082649627844;17353.7396939046;2348.7533808949984;2447.730749617541;2510.623406489023;2530.544501989641;2537.3656585853537;9980;10107;10283.845742313872;10284.640037598649;10381.480925953672;2058.763242227148;2122.36329101269;2130.243763463979;2143.4105620954206;2162.664786958024;73528.63235683841;77691;78595.6070219651;80448.19551804524;82650.76023859707;10654.414007229521;11123.55505779776;11144.219904606762;11155.442227888572;11452.885471145348;15755.660768834778;15764.211789410483;15841.445549405715;15858.603534911488;15880.52358429226;8392.706255281037;8403.957980210182;8455.915440845512;8527.317814574819;8588.441751286146;8193.95903020493;8223.629936652866;8245.093039765621;8286.70996515131;8309.750707478785;5029.84910452687;5093.0577843099145;5128.538431541179;5131.102057139986;5136.845894623168;1881.8776779509562;2083.374987503743;2152.515683971094;2184.2682701295244;2224.8108910742767;1047.643801107627;1064.8881867403811;1161.79668558002;1181.1141643767176;1210.5352523446552;18901.7164742529;18976.146073426728;19134.425967221006;19336.4199074028;19396.515087122654;1108.5842808946645;1109.1348747976592;1122.3041714137287;1149.5574203931546;1159.976800463986;10449.68650940473;10676.199285053652;10762.838557421645;10910.5090270938;10999.560017599206;493780;506765;513741;522447;534043;98932.50533747284;100084;100950.30549087485;101345;101679.49160254152;1065.5524679634573;1094.7099018760034;1097.7200813792272;1105.4804242006244;1111.6942840718866;13374;16961.151942403078;17447.56381090483;17461.12694365251;17616.823831761518;40926;41151.794241028605;41532.37701434542;42133.36799947942;42252;52121;56326.718366407906;56358.7182064087;56382.71808641095;57044.429555703915;9002.279817614706;9078.727638170865;9085.31929031182;9136.285588787276;9205.58574864146;9946.751331216778;13214.603561893158;13375.665608359479;13405.530806422119;13652.795208072086;11259.662210133709;11512;11767.941160294144;11954.820677689668;12177;11456.885431145914;11653.53385864556;11843.170978031456;11921.165518413974;12121.75756484883;6445.4199122079235;8448.493090414595;11138.777224455627;12246.387680616112;12569.434375452944;8585.613647385757;8909.55452227399;9140.725778226664;9285.675001374919;9291.442513449214;11672.883271167519;11793.351365674971;11829.704257393634;11946.34295113777;12011 15:50:06 INFO - PROCESS | 12037 | |3;mootools.html;1415.6107070555602;1493.6041948883546;1499.8950073494852;1723.8017627972788;1890.4801179675587;7688.692452301908;8330.083690794016;8477.957610211955;8483.830323393528;8485.321174306056;7683.769486915401;7818.061832580084;7833.647485863135;7836.138024817265;7836.529808211511;2146.51703366742;2351.694279743631;2360.091364824538;2376.3465047112095;2405.230326295586;2385.85684858909;2391.5814732421845;2394.6288325309606;2396.6524853896176;2401.4476670365825;2027.7161197432372;2094.6648536234206;2095.7904209579074;2098.9685154722692;2102.6951092091635;2122.8089471947524;2355.034435881289;2403.531311394279;2405.506871091422;2407.843490173142;2369.0642196332524;2369.680093187415;2375.762423757628;2380.4524959259466;2425.2966639674473;8158.061822890325;8168.632411541495;8222.794430139234;8224.671013159466;8236.094029656733;5108.208227724672;5245.527902488794;5252.71110088945;5255.290535777659;5324.733763311819;3472.7742696724886;3518.542589463379;3561.8753343632848;3596.9820150899077;3634.30948119859;1117.4692021289904;1160.970975725605;1179.598936361641;1181.1554738362113;1214.2714371377192;11689.941550292195;11740.530378784839;11755.882441175565;11757.882421175764;11758.647240582795;4591.655625828076;4668.673192876466;4669.929951050737;4811.350467686818;4817.229243321051;9613.855792163124;9675.03249675027;9680;9689.660861869801;9690.079442452956;4909.484504127086;5346.4653534647005;5350.197470379397;5372.086745253317;5384;4902.338184345139;4969.3539839821315;4973.452920178777;4990.750462476862;4997.450280469146;4169.187008533368;4195;4227.133437645275;4258;4338.848140315137;1317.6545748567564;1381.2334154544124;1388.6042477893955;1399.7130588229388;1406.4585134723;1030.4384110659707;1035.4460363705382;1046.733083063829;1056.271172890703;1057.836035414512;5872.338301006067;5883.882322353529;5926.555508336892;6073.635581865102;6118.3269840317525;472.1784095673572;486.5815398757066;1110.161827820007;1126.6874091683262;1141.3437273567731;4012.598740125964;4110.7328023678365;4138.751674899456;4178.958210417918;4226.746395216297;9888.653897113567;10027.097561219525;10047.547860346302;10088.899111008943;10151;10118.898811011943;10169.491525423698;10171.033751793568;10256.43589602579;10303.53634086453;7672.194419585975;8158.184181581913;8262.462939908886;8276.29651479619;8288.751337459766;5538.723063846872;5550.75021624036;5667.773289068391;5680.772769089355;5733.254676891977;8898.911010889808;8911.821763564822;8914.86627700598;9060.637574496946;9166.08339166078;8971.372003959661;9130.543472826464;9250.814983700422;9278.95360523193;9291.76770580741;7007.299270072952;7514.286142816423;7671.769846904602;7729.458937874295;7778.222177782177;8301.045379781403;8308.833823323379;8333.208345207195;8344.916550834656;8355.080941096354;7416.8887466686965;7647.349975252166;7694.923050769421;7758.88361674564;7900.091489478554;7453.664585093792;7497.175310715707;7593.278638529331;7618.771436856903;7685.269899359552;7286.089238845145;7291.4166866649475;7322.926770732224;7328.083989501312;7331.450141239535;5142;5205.401378841481;5228.660137091229;5258.158694608845;5321.414644389037;5376.220448034935;5385.2191432241325;5385.434529374516;5444.945550544602;5467.781288748405 15:50:06 INFO - PROCESS | 12037 | |4;prototype.html;362.3568166504455;364.49699414807577;365.9487671725958;372.7409450431951;379.9373103437933;6151.046587778896;6176.444120029202;6259.155014073099;6267.874642507147;6274.090256912745;6191.814245572628;6195.845103872405;6199.845003874905;6206.100115483252;6211.254649442062;4555.772211389434;4640.721556706591;4727.739974301412;4735.573798358147;4735.73953432561;4708.223143181371;4740.71555706657;4741.193997020515;4764.880877978044;4779.6176305895615;4496.415465989417;4742.834000809972;4743.667943243974;4753.976230118845;4754.714717116966;4600.585947264729;4610.469795973472;4614.284785858197;4724.338592597039;4747.45404278509;4164.91670166598;4175.812088456027;4181.393697913785;4183.163367326516;4187.91624167515;5915.645061296336;5917.940820591782;5935.881282374372;5955.225820643332;5962.284525856881;5502.697351645658;5518.19986102017;5520.530754885839;5524.779008839641;5530.225768392428;6356.2054743157105;6503.967480162616;6519.380658837403;6520.347965203489;6542.247641521239;4634.328022436762;4640.953590464086;4643.141018911479;4649.628029757645;4657.3246879202325;6781.796546103625;6795.7961261162245;6819.544963813023;6829.795106146824;6892.966055091726;4737.3841400618385;4741.620670346364;4742.5257474252985;4747.501512341223;4754.144254034307;4816.373871396696;4816.638752093607;4818.349522814446;4818.855434336975;4824.204006338985;5501.339839219319;5501.642393244426;5501.889962200734;5505.449455054462;5513.062779327524;6350.523710721714;6550;6665.567397680585;6680.899786503206;6684.097646817715;1643.8849280550246;1650.3728583138331;1657.2625181793985;1662.9334826607042;1663.4344322930262;1031.3811712972156;1089.0634054712941;1097.226455348977;1107.457345900503;1108.5676586131415;6270.244331587163;6460.644664543402;6482.060101285288;6483.643399613066;6485.7405703772265;6823;6834.829129271808;6841.350071743176;6843.213030501456;6846.623435711083;6441.1626488556185;6450.451711604473;6451.1936007999;6461.386168314003;6462.256840463313;3627.220147668211;3659.7255205859665;3662.1943172502006;3672.467492213614;3672.6694597486353;6920.653967301615;7157.606331651809;7166.53417527859;7191.928080719231;7207.13514378278;6931.896021559681;6956.2000369958105;6957.478189135733;6968.3728464438445;6974.755883544051;7118.750843720549;7159.284071592903;7165.462590305748;7168.713251469987;7184.640767961581;6833.41915937131;6834.658267086726;6834.794956151323;6844.28135045813;6844.726210951506;6492.350764923659;6707.765228216989;6717.731290748511;6738.6967586459705;6790.5925644461495;7147.928520714726;7152.427805775629;7158.498905076803;7167.426605871622;7188.28117188277;4669.4396672399525;4703.623710103252;4708.105459962596;4710.26990816424;4711.905761884812;6778.288279730557;6811.421029212573;6833.974903764276;6849.1096157499205;6872.965635171792;6340.809775706736;6561.245456772342;6572.211334639874;6602.933970660232;6626.56927299724;6520.706568204348;6586.538942274186;6590.538662293593;6593.571417857824;6596.868062638816;6492.935070649422;6493.642849643126;6494.318096599789;6497.057926600522;6499.2200935887995;6631.568948018364;6650.334966503505;6650.866982660416;6652.135222421054;6659.300773418915;6372.139761132374;6457.128287681291;6480.222373315232;6485.513586480938;6488.383603557654 15:50:06 INFO - PROCESS | 12037 | |5;yui.html;838.1576515601819;842.3008902610834;908.6592527802075;912.662314943471;924.7734305095248;14226.217558034305;14638.853611463883;14661.92669036656;14686.41254349826;14696.779548306784;13866.861331386683;14484.05853619514;14539.563813085599;14559.1992440416;14568.344424500896;759.0284435922001;868.9695860644863;877.1754550722301;886.1006078829993;909.7043460875209;870.3820287595814;882.6513527156769;882.9249513791335;884.3897710579679;886.2998231397188;936.8735220745181;937.2174234514189;939.1735272959786;940.407543247753;941.138857944983;786.850498405304;858.8239410920778;877.9122087791234;878.89892662344;925.8472352061935;646.2793984707065;647.1813156357211;647.4529022975578;647.4690753582065;647.5467172978933;14379.209143497104;14476.27618619065;14547.199904005276;14568.927155364156;14662.340194691264;14312.42750289987;15124.016938898936;15196.924015379853;15230.238488075664;15287.159206243652;11775.528978840835;11964.880351196463;11978.341191234478;12039.699007524881;12160.209586376857;3952.407138929155;3973.543042550115;3993.301172294866;4019.417184508259;4027.476428064362;54379.45620543783;54416;54513;54657.72671136619;54689.453105468834;10389.896101038892;10760.3543787373;10765.78468430627;10956.835647465294;11029.83455248172;18974.14616342268;19105.90447047756;19165;19192.520186995436;19201.423957281302;8086.150954149847;8195.71315003972;8578.571071446528;8586.184312490303;8807.823843523094;4842.564169224786;4848.248521479177;4945.530174633404;5003.099442100384;5024.673396229234;3461.44616861301;3486.1284678830293;3521.9823900880333;3575.7675751076617;3587.820608969541;601.7794338889571;615.6552330694735;665.171861033017;668.9331066893271;670.51722759613;748.5134662469329;774.2953911940108;813.4021494201756;822.7531740477833;835.7618078847499;10038.447885366375;10293.43386113771;10335.22485813552;10363.378197308186;10421.739456513647;647.2394935950334;657.3984803904469;678.7827895073627;787.696736756353;790.3005839831791;4631.467381251199;4819.59033482151;4839;4868.391451068616;4888.095702295087;59188;60930.39069609247;61157;68554.31445685479;68956.31043689599;54211.18683219755;56146.43853561412;56705;58715;59152;52992;53021.73489132529;53088.469115308355;53660;54003.459965399845;10077.344972576759;10116.747081323027;10256.58973641046;10405.115565176742;10549.472526373804;38673.80663096667;39422.21155576815;40559.594404055584;40728.1854362917;40812.1837563253;45747.542524575656;51334.743326284624;51607;51753.482465174864;52179.217311741115;12847.229166250056;12885.355732213538;12915;13155.60533184006;13212.933935330262;13628.523001694894;13789.103708259332;14027.789583156056;14365;14417.9279103608;10767.3539587625;12566.937165314115;12971.805422918475;13047.543335983195;13255.602331930058;12450.875491244971;12608.810867836806;12859.29273889945;12874.67813304675;13326.200427974352;12982.935085324512;13160.80258796138;13501.527446539323;13637.590872273848;13821.447142114203;10573.312734672221;10732.785344292914;10740.624078157227;10836.512356943798;11152.052075573667;10724.830270118937;10725.624603138853;11014;11083.833742493707;11147.498362573542 15:50:06 INFO - PROCESS | 12037 | __end_tp_report 15:50:06 INFO - PROCESS | 12037 | __start_cc_report 15:50:06 INFO - PROCESS | 12037 | _x_x_mozilla_cycle_collect,804 15:50:06 INFO - PROCESS | 12037 | __end_cc_report 15:50:06 INFO - PROCESS | 12037 | __startTimestamp1464994206236__endTimestamp 15:50:06 INFO - PROCESS | 12037 | 15:50:06 INFO - PROCESS | 12037 | ------- Summary: start ------- 15:50:06 INFO - PROCESS | 12037 | Number of tests: 6 15:50:06 INFO - PROCESS | 12037 | 15:50:06 INFO - PROCESS | 12037 | [#0] dojo.html Cycles:165 Average:5497.18 Median:5702.12 stddev:602.42 (10.6%) stddev-sans-first:579.68 15:50:06 INFO - PROCESS | 12037 | Values: 3325.6 3392.5 3395.7 3422.7 3431.6 5515.1 5519.3 5522.9 5526.9 5528.4 5529.8 5548.6 5557.6 5560.9 5605.7 4873.3 4980.9 4985.6 5051.2 5089.7 4889.0 4941.5 4987.3 4993.3 5012.7 5036.2 5051.9 5061.4 5063.8 5099.2 4972.0 5009.5 5048.1 5066.2 5078.9 4867.3 4898.5 4979.4 4979.9 4997.9 5423.8 5428.0 5430.8 5461.9 5463.1 5822.2 5926.0 5930.0 5933.7 5939.2 5936.9 6028.1 6033.9 6035.2 6058.1 5511.6 5511.9 5512.9 5513.3 5514.5 5864.9 5947.4 6012.5 6014.0 6039.8 5754.7 5799.3 5800.0 5830.4 5835.3 5889.9 5900.5 5902.1 5902.9 5907.6 5720.3 5821.2 5846.6 5883.1 5884.6 5912.4 5923.9 5932.9 5937.4 5975.9 4527.2 4568.4 4568.9 4569.1 4595.6 3979.6 4043.8 4079.8 4110.9 4126.6 5651.4 5672.1 5680.3 5680.6 5682.5 5949.5 5959.7 5964.8 5965.6 5968.5 5536.4 5575.2 5620.6 5633.5 5644.4 5097.4 5103.2 5104.7 5106.4 5141.3 6165.4 6201.5 6205.3 6218.9 6225.5 5817.0 5930.0 5969.7 5987.3 6026.9 6119.2 6126.4 6129.4 6130.7 6134.6 5774.3 5774.6 5777.3 5780.0 5788.0 5745.9 5894.8 5925.9 5935.7 5940.8 5728.2 5779.7 5787.2 5788.5 5794.0 5580.5 5704.1 5749.1 5769.7 5773.2 5614.4 5691.2 5700.4 5702.1 5714.5 5805.4 5807.9 5811.8 5820.4 5820.4 5605.0 5628.7 5701.3 5711.2 5760.8 15:50:06 INFO - PROCESS | 12037 | 15:50:06 INFO - PROCESS | 12037 | [#1] ext.html Cycles:180 Average:61506.03 Median:39180.90 stddev:123020.48 (314.0%) stddev-sans-first:123280.80 15:50:06 INFO - PROCESS | 12037 | Values: 674.1 680.4 694.5 737.4 743.5 33223.8 33809.7 38789.8 39654.2 39794.2 43486.3 44045.8 44132.3 44227.8 44350.0 8318.5 8979.2 9153.6 9155.2 10474.7 10500.8 10643.1 10644.7 10649.6 10663.7 10610.5 10636.4 10652.8 10663.7 10665.4 8584.4 10564.0 10635.6 10819.8 11084.2 10510.8 10610.6 10621.8 10624.3 10629.1 40577.4 41132.4 41137.8 41206.4 41327.6 3650.9 3666.0 4167.9 4319.4 4349.5 34019.8 34113.8 34309.8 35809.0 35860.6 4503.1 4517.7 4595.2 4606.3 4638.0 342394.0 343989.0 344190.0 344414.0 344767.0 39572.0 40188.6 40435.6 40577.0 59837.4 38215.4 38262.0 38354.4 38369.8 38382.6 16065.0 16161.4 16186.8 16204.5 16235.6 12595.6 13141.9 13201.1 13586.0 14023.2 9101.5 9115.9 9152.7 9169.8 9258.0 3481.1 3526.6 3562.4 3566.0 3596.1 1100.6 1123.4 1158.2 1167.0 1201.2 30212.8 31022.2 31535.8 32067.0 32198.0 1197.3 1200.7 1216.8 1221.5 1232.2 15711.5 16539.8 16959.3 16996.3 17260.8 531614.0 559009.0 565119.0 569338.0 571307.0 57912.4 58291.4 58666.4 58687.4 58759.7 1404.6 1447.2 1495.0 1518.8 1594.7 32289.4 33285.7 33457.7 33578.3 33678.5 53193.7 53902.5 54008.7 54092.7 54108.7 448718.0 462645.0 465379.0 465688.0 465854.0 41972.0 46716.3 49167.3 49653.3 49887.5 51945.0 53208.5 53526.7 54124.0 54410.0 44470.6 45078.3 48916.8 49519.0 50115.7 42231.8 42963.4 43230.8 44446.6 44785.6 45752.3 46728.0 46738.5 47579.8 47678.3 38359.2 39651.8 40116.0 40119.4 40312.7 30364.7 30595.8 30769.0 30842.8 31211.2 15:50:06 INFO - PROCESS | 12037 | 15:50:06 INFO - PROCESS | 12037 | [#2] jquery.html Cycles:180 Average:29592.86 Median:2147.96 stddev:84821.86 (3948.9%) stddev-sans-first:85031.55 15:50:06 INFO - PROCESS | 12037 | Values: 434.3 436.0 439.4 446.9 453.4 18512.7 18887.3 19321.8 19352.4 19380.2 19607.2 19632.9 19898.6 19916.3 19995.7 5565.6 5651.3 5824.4 5834.3 5844.7 4256.7 5719.0 5761.9 5792.7 6053.0 6284.7 6307.7 6311.2 6312.6 6324.3 5516.7 5802.7 5808.4 6234.5 6266.4 3947.6 5517.5 5639.0 5666.3 5678.9 13871.2 14102.9 14109.6 14113.1 17353.7 2348.8 2447.7 2510.6 2530.5 2537.4 9980.0 10107.0 10283.8 10284.6 10381.5 2058.8 2122.4 2130.2 2143.4 2162.7 73528.6 77691.0 78595.6 80448.2 82650.8 10654.4 11123.6 11144.2 11155.4 11452.9 15755.7 15764.2 15841.4 15858.6 15880.5 8392.7 8404.0 8455.9 8527.3 8588.4 8194.0 8223.6 8245.1 8286.7 8309.8 5029.8 5093.1 5128.5 5131.1 5136.8 1881.9 2083.4 2152.5 2184.3 2224.8 1047.6 1064.9 1161.8 1181.1 1210.5 18901.7 18976.1 19134.4 19336.4 19396.5 1108.6 1109.1 1122.3 1149.6 1160.0 10449.7 10676.2 10762.8 10910.5 10999.6 493780.0 506765.0 513741.0 522447.0 534043.0 98932.5 100084.0 100950.3 101345.0 101679.5 1065.6 1094.7 1097.7 1105.5 1111.7 13374.0 16961.2 17447.6 17461.1 17616.8 40926.0 41151.8 41532.4 42133.4 42252.0 52121.0 56326.7 56358.7 56382.7 57044.4 9002.3 9078.7 9085.3 9136.3 9205.6 9946.8 13214.6 13375.7 13405.5 13652.8 11259.7 11512.0 11767.9 11954.8 12177.0 11456.9 11653.5 11843.2 11921.2 12121.8 6445.4 8448.5 11138.8 12246.4 12569.4 8585.6 8909.6 9140.7 9285.7 9291.4 11672.9 11793.4 11829.7 11946.3 12011.0 15:50:06 INFO - PROCESS | 12037 | 15:50:06 INFO - PROCESS | 12037 | [#3] mootools.html Cycles:180 Average:5629.36 Median:5125.10 stddev:3029.05 (59.1%) stddev-sans-first:3020.99 15:50:06 INFO - PROCESS | 12037 | Values: 1415.6 1493.6 1499.9 1723.8 1890.5 7688.7 8330.1 8478.0 8483.8 8485.3 7683.8 7818.1 7833.6 7836.1 7836.5 2146.5 2351.7 2360.1 2376.3 2405.2 2385.9 2391.6 2394.6 2396.7 2401.4 2027.7 2094.7 2095.8 2099.0 2102.7 2122.8 2355.0 2403.5 2405.5 2407.8 2369.1 2369.7 2375.8 2380.5 2425.3 8158.1 8168.6 8222.8 8224.7 8236.1 5108.2 5245.5 5252.7 5255.3 5324.7 3472.8 3518.5 3561.9 3597.0 3634.3 1117.5 1161.0 1179.6 1181.2 1214.3 11689.9 11740.5 11755.9 11757.9 11758.6 4591.7 4668.7 4669.9 4811.4 4817.2 9613.9 9675.0 9680.0 9689.7 9690.1 4909.5 5346.5 5350.2 5372.1 5384.0 4902.3 4969.4 4973.5 4990.8 4997.5 4169.2 4195.0 4227.1 4258.0 4338.8 1317.7 1381.2 1388.6 1399.7 1406.5 1030.4 1035.4 1046.7 1056.3 1057.8 5872.3 5883.9 5926.6 6073.6 6118.3 472.2 486.6 1110.2 1126.7 1141.3 4012.6 4110.7 4138.8 4179.0 4226.7 9888.7 10027.1 10047.5 10088.9 10151.0 10118.9 10169.5 10171.0 10256.4 10303.5 7672.2 8158.2 8262.5 8276.3 8288.8 5538.7 5550.8 5667.8 5680.8 5733.3 8898.9 8911.8 8914.9 9060.6 9166.1 8971.4 9130.5 9250.8 9279.0 9291.8 7007.3 7514.3 7671.8 7729.5 7778.2 8301.0 8308.8 8333.2 8344.9 8355.1 7416.9 7647.3 7694.9 7758.9 7900.1 7453.7 7497.2 7593.3 7618.8 7685.3 7286.1 7291.4 7322.9 7328.1 7331.5 5142.0 5205.4 5228.7 5258.2 5321.4 5376.2 5385.2 5385.4 5444.9 5467.8 15:50:06 INFO - PROCESS | 12037 | 15:50:06 INFO - PROCESS | 12037 | [#4] prototype.html Cycles:180 Average:5531.70 Median:6307.45 stddev:1673.01 (26.5%) stddev-sans-first:1632.09 15:50:06 INFO - PROCESS | 12037 | Values: 362.4 364.5 365.9 372.7 379.9 6151.0 6176.4 6259.2 6267.9 6274.1 6191.8 6195.8 6199.8 6206.1 6211.3 4555.8 4640.7 4727.7 4735.6 4735.7 4708.2 4740.7 4741.2 4764.9 4779.6 4496.4 4742.8 4743.7 4754.0 4754.7 4600.6 4610.5 4614.3 4724.3 4747.5 4164.9 4175.8 4181.4 4183.2 4187.9 5915.6 5917.9 5935.9 5955.2 5962.3 5502.7 5518.2 5520.5 5524.8 5530.2 6356.2 6504.0 6519.4 6520.3 6542.2 4634.3 4641.0 4643.1 4649.6 4657.3 6781.8 6795.8 6819.5 6829.8 6893.0 4737.4 4741.6 4742.5 4747.5 4754.1 4816.4 4816.6 4818.3 4818.9 4824.2 5501.3 5501.6 5501.9 5505.4 5513.1 6350.5 6550.0 6665.6 6680.9 6684.1 1643.9 1650.4 1657.3 1662.9 1663.4 1031.4 1089.1 1097.2 1107.5 1108.6 6270.2 6460.6 6482.1 6483.6 6485.7 6823.0 6834.8 6841.4 6843.2 6846.6 6441.2 6450.5 6451.2 6461.4 6462.3 3627.2 3659.7 3662.2 3672.5 3672.7 6920.7 7157.6 7166.5 7191.9 7207.1 6931.9 6956.2 6957.5 6968.4 6974.8 7118.8 7159.3 7165.5 7168.7 7184.6 6833.4 6834.7 6834.8 6844.3 6844.7 6492.4 6707.8 6717.7 6738.7 6790.6 7147.9 7152.4 7158.5 7167.4 7188.3 4669.4 4703.6 4708.1 4710.3 4711.9 6778.3 6811.4 6834.0 6849.1 6873.0 6340.8 6561.2 6572.2 6602.9 6626.6 6520.7 6586.5 6590.5 6593.6 6596.9 6492.9 6493.6 6494.3 6497.1 6499.2 6631.6 6650.3 6650.9 6652.1 6659.3 6372.1 6457.1 6480.2 6485.5 6488.4 15:50:06 INFO - PROCESS | 12037 | 15:50:06 INFO - PROCESS | 12037 | [#5] yui.html Cycles:180 Average:15575.17 Median:4023.45 stddev:17950.69 (446.2%) stddev-sans-first:17966.93 15:50:06 INFO - PROCESS | 12037 | Values: 838.2 842.3 908.7 912.7 924.8 14226.2 14638.9 14661.9 14686.4 14696.8 13866.9 14484.1 14539.6 14559.2 14568.3 759.0 869.0 877.2 886.1 909.7 870.4 882.7 882.9 884.4 886.3 936.9 937.2 939.2 940.4 941.1 786.9 858.8 877.9 878.9 925.8 646.3 647.2 647.5 647.5 647.5 14379.2 14476.3 14547.2 14568.9 14662.3 14312.4 15124.0 15196.9 15230.2 15287.2 11775.5 11964.9 11978.3 12039.7 12160.2 3952.4 3973.5 3993.3 4019.4 4027.5 54379.5 54416.0 54513.0 54657.7 54689.5 10389.9 10760.4 10765.8 10956.8 11029.8 18974.1 19105.9 19165.0 19192.5 19201.4 8086.2 8195.7 8578.6 8586.2 8807.8 4842.6 4848.2 4945.5 5003.1 5024.7 3461.4 3486.1 3522.0 3575.8 3587.8 601.8 615.7 665.2 668.9 670.5 748.5 774.3 813.4 822.8 835.8 10038.4 10293.4 10335.2 10363.4 10421.7 647.2 657.4 678.8 787.7 790.3 4631.5 4819.6 4839.0 4868.4 4888.1 59188.0 60930.4 61157.0 68554.3 68956.3 54211.2 56146.4 56705.0 58715.0 59152.0 52992.0 53021.7 53088.5 53660.0 54003.5 10077.3 10116.7 10256.6 10405.1 10549.5 38673.8 39422.2 40559.6 40728.2 40812.2 45747.5 51334.7 51607.0 51753.5 52179.2 12847.2 12885.4 12915.0 13155.6 13212.9 13628.5 13789.1 14027.8 14365.0 14417.9 10767.4 12566.9 12971.8 13047.5 13255.6 12450.9 12608.8 12859.3 12874.7 13326.2 12982.9 13160.8 13501.5 13637.6 13821.4 10573.3 10732.8 10740.6 10836.5 11152.1 10724.8 10725.6 11014.0 11083.8 11147.5 15:50:06 INFO - PROCESS | 12037 | -------- Summary: end -------- 15:50:06 INFO - PROCESS | 12037 | 15:50:06 INFO - TEST-INFO | 12037: exit 0 15:50:06 INFO - TEST-OK | dromaeo_css | took 1113149ms 15:50:06 INFO - TEST-START | kraken 15:50:06 INFO - operating with platform_type : linux_ 15:50:06 INFO - Initialising browser for kraken test... 15:50:06 INFO - TEST-INFO | started process 12229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaYlOGe/profile http://localhost:44211/getInfo.html) 15:50:11 INFO - PROCESS | 12229 | __metrics Screen width/height:1600/1200 15:50:11 INFO - PROCESS | 12229 | colorDepth:24 15:50:11 INFO - PROCESS | 12229 | Browser inner width/height: 1024/697 15:50:11 INFO - PROCESS | 12229 | __metrics 15:50:12 INFO - PROCESS | 12229 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:50:12 ERROR - PROCESS | 12229 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:50:13 INFO - TEST-INFO | 12229: exit 0 15:50:13 INFO - Browser initialized. 15:50:13 INFO - Running cycle 1/1 for kraken test... 15:50:13 INFO - TEST-INFO | started process 12323 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaYlOGe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:50:25 INFO - PROCESS | 12323 | RSS: Main: 142700544 15:50:25 INFO - PROCESS | 12323 | 15:50:27 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44211/tests/kraken/driver.html?testName=audio-beat-detection) 15:50:28 INFO - PROCESS | 12323 | RSS: Main: 197050368 15:50:28 INFO - PROCESS | 12323 | 15:50:31 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44211/tests/kraken/driver.html?testName=audio-dft) 15:50:31 INFO - PROCESS | 12323 | RSS: Main: 181841920 15:50:31 INFO - PROCESS | 12323 | 15:50:34 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44211/tests/kraken/driver.html?testName=audio-fft) 15:50:35 INFO - PROCESS | 12323 | RSS: Main: 272924672 15:50:35 INFO - PROCESS | 12323 | 15:50:37 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44211/tests/kraken/driver.html?testName=audio-oscillator) 15:50:38 INFO - PROCESS | 12323 | RSS: Main: 178556928 15:50:38 INFO - PROCESS | 12323 | 15:50:40 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44211/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:50:40 INFO - PROCESS | 12323 | RSS: Main: 176791552 15:50:40 INFO - PROCESS | 12323 | 15:50:43 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44211/tests/kraken/driver.html?testName=imaging-darkroom) 15:50:44 INFO - PROCESS | 12323 | RSS: Main: 211795968 15:50:44 INFO - PROCESS | 12323 | 15:50:47 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44211/tests/kraken/driver.html?testName=imaging-desaturate) 15:50:48 INFO - PROCESS | 12323 | RSS: Main: 211693568 15:50:48 INFO - PROCESS | 12323 | 15:50:51 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44211/tests/kraken/driver.html?testName=json-parse-financial) 15:50:51 INFO - PROCESS | 12323 | RSS: Main: 211320832 15:50:51 INFO - PROCESS | 12323 | 15:50:53 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44211/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:50:53 INFO - PROCESS | 12323 | RSS: Main: 190464000 15:50:53 INFO - PROCESS | 12323 | 15:50:55 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:50:56 INFO - PROCESS | 12323 | RSS: Main: 196907008 15:50:56 INFO - PROCESS | 12323 | 15:50:57 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:50:58 INFO - PROCESS | 12323 | RSS: Main: 207572992 15:50:58 INFO - PROCESS | 12323 | 15:51:01 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:51:01 INFO - PROCESS | 12323 | RSS: Main: 212463616 15:51:01 INFO - PROCESS | 12323 | 15:51:04 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:51:04 INFO - PROCESS | 12323 | RSS: Main: 209543168 15:51:04 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44211/tests/kraken/driver.html?testName=ai-astar) 15:51:06 INFO - PROCESS | 12323 | RSS: Main: 273346560 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | __start_tp_report 15:51:06 INFO - PROCESS | 12323 | _x_x_mozilla_page_load 15:51:06 INFO - PROCESS | 12323 | _x_x_mozilla_page_load_details 15:51:06 INFO - PROCESS | 12323 | |i|pagename|runs| 15:51:06 INFO - PROCESS | 12323 | |0;ai-astar;109;110;110;110;111;110;105;111;110;110 15:51:06 INFO - PROCESS | 12323 | |1;audio-beat-detection;155;124;163;171;131;149;147;126;186;126 15:51:06 INFO - PROCESS | 12323 | |2;audio-dft;161;160;160;159;160;159;161;162;162;159 15:51:06 INFO - PROCESS | 12323 | |3;audio-fft;73;75;74;178;74;74;76;77;154;77 15:51:06 INFO - PROCESS | 12323 | |4;audio-oscillator;93;91;152;92;92;92;143;92;92;140 15:51:06 INFO - PROCESS | 12323 | |5;imaging-gaussian-blur;117;118;116;116;117;116;116;116;116;117 15:51:06 INFO - PROCESS | 12323 | |6;imaging-darkroom;145;146;145;146;146;145;151;144;145;146 15:51:06 INFO - PROCESS | 12323 | |7;imaging-desaturate;95;94;95;94;95;94;95;94;94;94 15:51:06 INFO - PROCESS | 12323 | |8;json-parse-financial;55;53;54;54;53;53;55;74;54;51 15:51:06 INFO - PROCESS | 12323 | |9;json-stringify-tinderbox;58;58;57;57;58;58;57;57;57;57 15:51:06 INFO - PROCESS | 12323 | |10;stanford-crypto-aes;72;72;72;73;74;71;73;73;72;72 15:51:06 INFO - PROCESS | 12323 | |11;stanford-crypto-ccm;118;120;110;113;111;114;111;113;126;130 15:51:06 INFO - PROCESS | 12323 | |12;stanford-crypto-pbkdf2;161;157;161;156;156;153;156;155;157;153 15:51:06 INFO - PROCESS | 12323 | |13;stanford-crypto-sha256-iterative;67;66;65;66;64;64;63;65;65;63 15:51:06 INFO - PROCESS | 12323 | __end_tp_report 15:51:06 INFO - PROCESS | 12323 | __start_cc_report 15:51:06 INFO - PROCESS | 12323 | _x_x_mozilla_cycle_collect,1583 15:51:06 INFO - PROCESS | 12323 | __end_cc_report 15:51:06 INFO - PROCESS | 12323 | __startTimestamp1464994266785__endTimestamp 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | ------- Summary: start ------- 15:51:06 INFO - PROCESS | 12323 | Number of tests: 14 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:1.71 (1.6%) stddev-sans-first:1.80 15:51:06 INFO - PROCESS | 12323 | Values: 109.0 110.0 110.0 110.0 111.0 110.0 105.0 111.0 110.0 110.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#1] audio-beat-detection Cycles:10 Average:147.80 Median:152.00 stddev:21.30 (14.0%) stddev-sans-first:22.43 15:51:06 INFO - PROCESS | 12323 | Values: 155.0 124.0 163.0 171.0 131.0 149.0 147.0 126.0 186.0 126.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:1.16 (0.7%) stddev-sans-first:1.20 15:51:06 INFO - PROCESS | 12323 | Values: 161.0 160.0 160.0 159.0 160.0 159.0 161.0 162.0 162.0 159.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#3] audio-fft Cycles:10 Average:93.20 Median:74.50 stddev:38.81 (52.1%) stddev-sans-first:40.47 15:51:06 INFO - PROCESS | 12323 | Values: 73.0 75.0 74.0 178.0 74.0 74.0 76.0 77.0 154.0 77.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#4] audio-oscillator Cycles:10 Average:107.90 Median:92.00 stddev:25.77 (28.0%) stddev-sans-first:26.77 15:51:06 INFO - PROCESS | 12323 | Values: 93.0 91.0 152.0 92.0 92.0 92.0 143.0 92.0 92.0 140.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:116.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 15:51:06 INFO - PROCESS | 12323 | Values: 117.0 118.0 116.0 116.0 117.0 116.0 116.0 116.0 116.0 117.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:1.91 (1.3%) stddev-sans-first:2.00 15:51:06 INFO - PROCESS | 12323 | Values: 145.0 146.0 145.0 146.0 146.0 145.0 151.0 144.0 145.0 146.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 15:51:06 INFO - PROCESS | 12323 | Values: 95.0 94.0 95.0 94.0 95.0 94.0 95.0 94.0 94.0 94.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:54.00 stddev:6.57 (12.2%) stddev-sans-first:6.96 15:51:06 INFO - PROCESS | 12323 | Values: 55.0 53.0 54.0 54.0 53.0 53.0 55.0 74.0 54.0 51.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#9] json-stringify-tinderbox Cycles:10 Average:57.40 Median:57.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 15:51:06 INFO - PROCESS | 12323 | Values: 58.0 58.0 57.0 57.0 58.0 58.0 57.0 57.0 57.0 57.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#10] stanford-crypto-aes Cycles:10 Average:72.40 Median:72.50 stddev:0.84 (1.2%) stddev-sans-first:0.88 15:51:06 INFO - PROCESS | 12323 | Values: 72.0 72.0 72.0 73.0 74.0 71.0 73.0 73.0 72.0 72.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#11] stanford-crypto-ccm Cycles:10 Average:116.60 Median:116.00 stddev:6.83 (5.9%) stddev-sans-first:7.23 15:51:06 INFO - PROCESS | 12323 | Values: 118.0 120.0 110.0 113.0 111.0 114.0 111.0 113.0 126.0 130.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.50 Median:156.50 stddev:2.76 (1.8%) stddev-sans-first:2.40 15:51:06 INFO - PROCESS | 12323 | Values: 161.0 157.0 161.0 156.0 156.0 153.0 156.0 155.0 157.0 153.0 15:51:06 INFO - PROCESS | 12323 | 15:51:06 INFO - PROCESS | 12323 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.32 (2.0%) stddev-sans-first:1.13 15:51:06 INFO - PROCESS | 12323 | Values: 67.0 66.0 65.0 66.0 64.0 64.0 63.0 65.0 65.0 63.0 15:51:06 INFO - PROCESS | 12323 | -------- Summary: end -------- 15:51:06 INFO - PROCESS | 12323 | 15:51:07 INFO - TEST-INFO | 12323: exit 0 15:51:07 INFO - TEST-OK | kraken | took 60801ms 15:51:07 INFO - SUITE-END | took 1173s 15:51:07 INFO - Completed test suite (00:19:34) 15:51:07 INFO - javascript benchmark 15:51:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.6009278886536, 3392.474166504191, 3395.7113645340137, 3422.657734226576, 3431.571053618298, 5515.090010148326, 5519.282493275873, 5522.944770552293, 5526.944730552693, 5528.44715528447, 5529.806456774014, 5548.6393384430085, 5557.5831812614115, 5560.916586251209, 5605.691686957216, 4873.269009648563, 4980.850574482763, 4985.601151907857, 5051.191809310511, 5089.7455127243675, 4888.951110488885, 4941.530554597308, 4987.326710894019, 4993.251012348141, 5012.749362531858, 5036.244563315496, 5051.898962020758, 5061.44324124346, 5063.772130254129, 5099.15863882459, 4971.975140124277, 5009.5491405773655, 5048.116579598555, 5066.214736715815, 5078.898422031557, 4867.269909513566, 4898.461169271377, 4979.377577802775, 4979.925301120486, 4997.875053123701, 5423.755930983115, 5427.972860135714, 5430.75561599725, 5461.863453413656, 5463.071277882769, 5822.184894114827, 5925.970370148165, 5929.9703501482645, 5933.673647949361, 5939.168516407706, 5936.881262374728, 6028.0957856321465, 6033.909491357633, 6035.245594300713, 6058.1215723719815, 5511.641743286714, 5511.917321240185, 5512.91730624041, 5513.338399392059, 5514.476124768181, 5864.912026319608, 5947.37552556984, 6012.488938440281, 6013.9699301503215, 6039.818805435844, 5754.712264386764, 5799.2750906136735, 5800.042992906123, 5830.446107619854, 5835.270591176103, 5889.852753681106, 5900.527957763455, 5902.144189092557, 5902.852428689317, 5907.556933229939, 5720.2849643794525, 5821.214136091573, 5846.56150788684, 5883.146943693226, 5884.558658100659, 5912.379200184005, 5923.851903702356, 5932.851678707981, 5937.376575459514, 5975.910361344583, 4527.162474942146, 4568.383268258809, 4568.931466028012, 4569.131864945716, 4595.632349412039, 3979.5821438749094, 4043.757374557536, 4079.8164082616354, 4110.876673699794, 4126.607972242632, 5651.378348381675, 5672.149177623307, 5680.261565996477, 5680.602357834912, 5682.488576028095, 5949.464548190684, 5959.731812068468, 5964.821055368346, 5965.582409231399, 5968.46283834457, 5536.446355364512, 5575.247341608912, 5620.606557541015, 5633.521150702236, 5644.435556444405, 5097.413797413345, 5103.209002604603, 5104.744762761847, 5106.387233532, 5141.331626888481, 6165.445109940038, 6201.472874805692, 6205.348438413992, 6218.875622487615, 6225.501959843292, 5816.970915145566, 5930.0, 5969.701514924323, 5987.251593550806, 6026.90959635597, 6119.204503414528, 6126.356732543019, 6129.3564175762685, 6130.6628135452975, 6134.570580059353, 5774.2782152230975, 5774.595778295647, 5777.335606405317, 5779.97110014464, 5787.9710601448405, 5745.9425405745405, 5894.823155305347, 5925.85185370352, 5935.732892019956, 5940.79207227745, 5728.226689396878, 5779.653220806596, 5787.218725472176, 5788.507976822018, 5794.0, 5580.497755202051, 5704.11586204139, 5749.137629355463, 5769.740361683818, 5773.191753154478, 5614.410486898817, 5691.203231547669, 5700.401457846866, 5702.116171993348, 5714.514266287246, 5805.448482394166, 5807.912881306699, 5811.767529298781, 5820.417958204146, 5820.447057529528, 5605.0, 5628.690422026664, 5701.344345400166, 5711.228984087095, 5760.769569217185], "value": 5458.95342892257, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.1472037872089, 680.442037529226, 694.4964900447179, 737.4432303610773, 743.5315751076822, 33223.83388083059, 33809.66190338096, 38789.80605096974, 39654.206915861665, 39794.204115917666, 43486.34770478446, 44045.77977110118, 44132.3380149298, 44227.77886110573, 44350.0, 8318.500889946623, 8979.236764874977, 9153.633854645806, 9155.221806146452, 10474.738131546696, 10500.789984200312, 10643.14854811617, 10644.733881652983, 10649.627263045795, 10663.733406664818, 10610.522526486287, 10636.414997175152, 10652.840207396895, 10663.733406664856, 10665.413402262873, 8584.356173287028, 10564.0, 10635.627753028646, 10819.83770243447, 11084.168687348401, 10510.842337364946, 10610.62862799806, 10621.84067238992, 10624.256302058859, 10629.096526795232, 40577.391339129936, 41132.38301425481, 41137.794311028556, 41206.38190427146, 41327.58672413268, 3650.8722194723323, 3665.963340366589, 4167.937480937789, 4319.373690814846, 4349.499807522112, 34019.82990085034, 34113.82943085294, 34309.828450857836, 35809.0, 35860.64139358599, 4503.144402563536, 4517.728936263835, 4595.1686218463965, 4606.26299792035, 4638.0, 342394.0, 343988.9999999975, 344190.0, 344414.0, 344767.0, 39572.0, 40188.59811401907, 40435.59564404377, 40577.0, 59837.40162598405, 38215.426768598496, 38262.0, 38354.42468362977, 38369.80815095906, 38382.61617383846, 16064.9557778744, 16161.4343497977, 16186.838131618768, 16204.513864584082, 16235.59411014734, 12595.622131336075, 13141.934290328487, 13201.141925774795, 13586.0, 14023.1586104832, 9101.544922753836, 9115.90884091164, 9152.725418237464, 9169.816603667889, 9257.953710231406, 3481.0949153220345, 3526.594441639193, 3562.4300111981956, 3565.982170089185, 3596.0650230940146, 1100.5567639839544, 1123.393367581513, 1158.2124155574122, 1167.0, 1201.2492192379764, 30212.84893575518, 31022.22444438862, 31535.84232078871, 32067.0, 32198.034058978268, 1197.2696655040418, 1200.6758175292623, 1216.8296438498617, 1221.5358163897663, 1232.1621297517595, 15711.528654140395, 16539.751903721455, 16959.321627135025, 16996.32014719422, 17260.827391726172, 531614.0, 559009.0, 565119.0, 569338.0, 571307.0, 57912.4208757907, 58291.417085829446, 58666.41333586695, 58687.41312586905, 58759.70620146872, 1404.6137312239216, 1447.2185020089244, 1494.9626259343604, 1518.7949626800462, 1594.6970075685583, 32289.35421291608, 33285.66714332923, 33457.66542334643, 33578.328433431685, 33678.49482257817, 53193.73403133114, 53902.460975389746, 54008.72995634997, 54092.72953635207, 54108.72945635246, 448718.0, 462645.0, 465379.0, 465688.0, 465854.0, 41972.0, 46716.299255510516, 49167.26249106195, 49653.25520117129, 49887.50112498829, 51945.0, 53208.4679153219, 53526.73236633948, 54124.0, 54410.0, 44470.55777408151, 45078.32382514331, 48916.75541622269, 49519.00961980668, 50115.74942125266, 42231.7888410556, 42963.3555496674, 43230.783846081824, 44446.55553444424, 44785.55214447814, 45752.313715294964, 46728.0, 46738.532614674776, 47579.76210118927, 47678.28482572695, 38359.2328153441, 39651.80174099111, 40116.0, 40119.3982090263, 40312.69996378969, 30364.696353036186, 30595.847020764755, 30769.0, 30842.84578577093, 31211.219719507193], "value": 20339.319729356685, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.2595874034771, 436.02480762147485, 439.4024127187025, 446.89274574102217, 453.4494904894675, 18512.722309165372, 18887.338943136976, 19321.806781932177, 19352.419427417182, 19380.224791008342, 19607.21571137153, 19632.901835490804, 19898.60202795943, 19916.302929397472, 19995.700064499044, 5565.6104072714925, 5651.32184137904, 5824.359320474744, 5834.329052158992, 5844.736986835603, 4256.702030857841, 5719.0, 5761.884762304751, 5792.6524408535415, 6052.969735151318, 6284.717187726564, 6307.716152773136, 6311.242650881901, 6312.621242725451, 6324.3043265240785, 5516.668999860001, 5802.738876750556, 5808.3901190375245, 6234.5324100692405, 6266.436020758131, 3947.624975627311, 5517.475839795213, 5639.0, 5666.320041595035, 5678.858028549319, 13871.237081960488, 14102.92948535261, 14109.64725881851, 14113.082649627844, 17353.7396939046, 2348.7533808949984, 2447.730749617541, 2510.623406489023, 2530.544501989641, 2537.3656585853537, 9980.0, 10107.0, 10283.845742313872, 10284.640037598649, 10381.480925953672, 2058.763242227148, 2122.36329101269, 2130.243763463979, 2143.4105620954206, 2162.664786958024, 73528.63235683841, 77691.0, 78595.6070219651, 80448.19551804524, 82650.76023859707, 10654.414007229521, 11123.55505779776, 11144.219904606762, 11155.442227888572, 11452.885471145348, 15755.660768834778, 15764.211789410483, 15841.445549405715, 15858.603534911488, 15880.52358429226, 8392.706255281037, 8403.957980210182, 8455.915440845512, 8527.317814574819, 8588.441751286146, 8193.95903020493, 8223.629936652866, 8245.093039765621, 8286.70996515131, 8309.750707478785, 5029.84910452687, 5093.0577843099145, 5128.538431541179, 5131.102057139986, 5136.845894623168, 1881.8776779509562, 2083.374987503743, 2152.515683971094, 2184.2682701295244, 2224.8108910742767, 1047.643801107627, 1064.8881867403811, 1161.79668558002, 1181.1141643767176, 1210.5352523446552, 18901.7164742529, 18976.146073426728, 19134.425967221006, 19336.4199074028, 19396.515087122654, 1108.5842808946645, 1109.1348747976592, 1122.3041714137287, 1149.5574203931546, 1159.976800463986, 10449.68650940473, 10676.199285053652, 10762.838557421645, 10910.5090270938, 10999.560017599206, 493780.0, 506765.0, 513741.0, 522447.0, 534043.0, 98932.50533747284, 100084.0, 100950.30549087485, 101345.0, 101679.49160254152, 1065.5524679634573, 1094.7099018760034, 1097.7200813792272, 1105.4804242006244, 1111.6942840718866, 13374.0, 16961.151942403078, 17447.56381090483, 17461.12694365251, 17616.823831761518, 40926.0, 41151.794241028605, 41532.37701434542, 42133.36799947942, 42252.0, 52121.0, 56326.718366407906, 56358.7182064087, 56382.71808641095, 57044.429555703915, 9002.279817614706, 9078.727638170865, 9085.31929031182, 9136.285588787276, 9205.58574864146, 9946.751331216778, 13214.603561893158, 13375.665608359479, 13405.530806422119, 13652.795208072086, 11259.662210133709, 11512.0, 11767.941160294144, 11954.820677689668, 12177.0, 11456.885431145914, 11653.53385864556, 11843.170978031456, 11921.165518413974, 12121.75756484883, 6445.4199122079235, 8448.493090414595, 11138.777224455627, 12246.387680616112, 12569.434375452944, 8585.613647385757, 8909.55452227399, 9140.725778226664, 9285.675001374919, 9291.442513449214, 11672.883271167519, 11793.351365674971, 11829.704257393634, 11946.34295113777, 12011.0], "value": 9342.0889444052, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.6107070555602, 1493.6041948883546, 1499.8950073494852, 1723.8017627972788, 1890.4801179675587, 7688.692452301908, 8330.083690794016, 8477.957610211955, 8483.830323393528, 8485.321174306056, 7683.769486915401, 7818.061832580084, 7833.647485863135, 7836.138024817265, 7836.529808211511, 2146.51703366742, 2351.694279743631, 2360.091364824538, 2376.3465047112095, 2405.230326295586, 2385.85684858909, 2391.5814732421845, 2394.6288325309606, 2396.6524853896176, 2401.4476670365825, 2027.7161197432372, 2094.6648536234206, 2095.7904209579074, 2098.9685154722692, 2102.6951092091635, 2122.8089471947524, 2355.034435881289, 2403.531311394279, 2405.506871091422, 2407.843490173142, 2369.0642196332524, 2369.680093187415, 2375.762423757628, 2380.4524959259466, 2425.2966639674473, 8158.061822890325, 8168.632411541495, 8222.794430139234, 8224.671013159466, 8236.094029656733, 5108.208227724672, 5245.527902488794, 5252.71110088945, 5255.290535777659, 5324.733763311819, 3472.7742696724886, 3518.542589463379, 3561.8753343632848, 3596.9820150899077, 3634.30948119859, 1117.4692021289904, 1160.970975725605, 1179.598936361641, 1181.1554738362113, 1214.2714371377192, 11689.941550292195, 11740.530378784839, 11755.882441175565, 11757.882421175764, 11758.647240582795, 4591.655625828076, 4668.673192876466, 4669.929951050737, 4811.350467686818, 4817.229243321051, 9613.855792163124, 9675.03249675027, 9680.0, 9689.660861869801, 9690.079442452956, 4909.484504127086, 5346.4653534647005, 5350.197470379397, 5372.086745253317, 5384.0, 4902.338184345139, 4969.3539839821315, 4973.452920178777, 4990.750462476862, 4997.450280469146, 4169.187008533368, 4195.0, 4227.133437645275, 4258.0, 4338.848140315137, 1317.6545748567564, 1381.2334154544124, 1388.6042477893955, 1399.7130588229388, 1406.4585134723, 1030.4384110659707, 1035.4460363705382, 1046.733083063829, 1056.271172890703, 1057.836035414512, 5872.338301006067, 5883.882322353529, 5926.555508336892, 6073.635581865102, 6118.3269840317525, 472.1784095673572, 486.5815398757066, 1110.161827820007, 1126.6874091683262, 1141.3437273567731, 4012.598740125964, 4110.7328023678365, 4138.751674899456, 4178.958210417918, 4226.746395216297, 9888.653897113567, 10027.097561219525, 10047.547860346302, 10088.899111008943, 10151.0, 10118.898811011943, 10169.491525423698, 10171.033751793568, 10256.43589602579, 10303.53634086453, 7672.194419585975, 8158.184181581913, 8262.462939908886, 8276.29651479619, 8288.751337459766, 5538.723063846872, 5550.75021624036, 5667.773289068391, 5680.772769089355, 5733.254676891977, 8898.911010889808, 8911.821763564822, 8914.86627700598, 9060.637574496946, 9166.08339166078, 8971.372003959661, 9130.543472826464, 9250.814983700422, 9278.95360523193, 9291.76770580741, 7007.299270072952, 7514.286142816423, 7671.769846904602, 7729.458937874295, 7778.222177782177, 8301.045379781403, 8308.833823323379, 8333.208345207195, 8344.916550834656, 8355.080941096354, 7416.8887466686965, 7647.349975252166, 7694.923050769421, 7758.88361674564, 7900.091489478554, 7453.664585093792, 7497.175310715707, 7593.278638529331, 7618.771436856903, 7685.269899359552, 7286.089238845145, 7291.4166866649475, 7322.926770732224, 7328.083989501312, 7331.450141239535, 5142.0, 5205.401378841481, 5228.660137091229, 5258.158694608845, 5321.414644389037, 5376.220448034935, 5385.2191432241325, 5385.434529374516, 5444.945550544602, 5467.781288748405], "value": 4585.931857360247, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.3568166504455, 364.49699414807577, 365.9487671725958, 372.7409450431951, 379.9373103437933, 6151.046587778896, 6176.444120029202, 6259.155014073099, 6267.874642507147, 6274.090256912745, 6191.814245572628, 6195.845103872405, 6199.845003874905, 6206.100115483252, 6211.254649442062, 4555.772211389434, 4640.721556706591, 4727.739974301412, 4735.573798358147, 4735.73953432561, 4708.223143181371, 4740.71555706657, 4741.193997020515, 4764.880877978044, 4779.6176305895615, 4496.415465989417, 4742.834000809972, 4743.667943243974, 4753.976230118845, 4754.714717116966, 4600.585947264729, 4610.469795973472, 4614.284785858197, 4724.338592597039, 4747.45404278509, 4164.91670166598, 4175.812088456027, 4181.393697913785, 4183.163367326516, 4187.91624167515, 5915.645061296336, 5917.940820591782, 5935.881282374372, 5955.225820643332, 5962.284525856881, 5502.697351645658, 5518.19986102017, 5520.530754885839, 5524.779008839641, 5530.225768392428, 6356.2054743157105, 6503.967480162616, 6519.380658837403, 6520.347965203489, 6542.247641521239, 4634.328022436762, 4640.953590464086, 4643.141018911479, 4649.628029757645, 4657.3246879202325, 6781.796546103625, 6795.7961261162245, 6819.544963813023, 6829.795106146824, 6892.966055091726, 4737.3841400618385, 4741.620670346364, 4742.5257474252985, 4747.501512341223, 4754.144254034307, 4816.373871396696, 4816.638752093607, 4818.349522814446, 4818.855434336975, 4824.204006338985, 5501.339839219319, 5501.642393244426, 5501.889962200734, 5505.449455054462, 5513.062779327524, 6350.523710721714, 6550.0, 6665.567397680585, 6680.899786503206, 6684.097646817715, 1643.8849280550246, 1650.3728583138331, 1657.2625181793985, 1662.9334826607042, 1663.4344322930262, 1031.3811712972156, 1089.0634054712941, 1097.226455348977, 1107.457345900503, 1108.5676586131415, 6270.244331587163, 6460.644664543402, 6482.060101285288, 6483.643399613066, 6485.7405703772265, 6823.0, 6834.829129271808, 6841.350071743176, 6843.213030501456, 6846.623435711083, 6441.1626488556185, 6450.451711604473, 6451.1936007999, 6461.386168314003, 6462.256840463313, 3627.220147668211, 3659.7255205859665, 3662.1943172502006, 3672.467492213614, 3672.6694597486353, 6920.653967301615, 7157.606331651809, 7166.53417527859, 7191.928080719231, 7207.13514378278, 6931.896021559681, 6956.2000369958105, 6957.478189135733, 6968.3728464438445, 6974.755883544051, 7118.750843720549, 7159.284071592903, 7165.462590305748, 7168.713251469987, 7184.640767961581, 6833.41915937131, 6834.658267086726, 6834.794956151323, 6844.28135045813, 6844.726210951506, 6492.350764923659, 6707.765228216989, 6717.731290748511, 6738.6967586459705, 6790.5925644461495, 7147.928520714726, 7152.427805775629, 7158.498905076803, 7167.426605871622, 7188.28117188277, 4669.4396672399525, 4703.623710103252, 4708.105459962596, 4710.26990816424, 4711.905761884812, 6778.288279730557, 6811.421029212573, 6833.974903764276, 6849.1096157499205, 6872.965635171792, 6340.809775706736, 6561.245456772342, 6572.211334639874, 6602.933970660232, 6626.56927299724, 6520.706568204348, 6586.538942274186, 6590.538662293593, 6593.571417857824, 6596.868062638816, 6492.935070649422, 6493.642849643126, 6494.318096599789, 6497.057926600522, 6499.2200935887995, 6631.568948018364, 6650.334966503505, 6650.866982660416, 6652.135222421054, 6659.300773418915, 6372.139761132374, 6457.128287681291, 6480.222373315232, 6485.513586480938, 6488.383603557654], "value": 4990.498620068578, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [838.1576515601819, 842.3008902610834, 908.6592527802075, 912.662314943471, 924.7734305095248, 14226.217558034305, 14638.853611463883, 14661.92669036656, 14686.41254349826, 14696.779548306784, 13866.861331386683, 14484.05853619514, 14539.563813085599, 14559.1992440416, 14568.344424500896, 759.0284435922001, 868.9695860644863, 877.1754550722301, 886.1006078829993, 909.7043460875209, 870.3820287595814, 882.6513527156769, 882.9249513791335, 884.3897710579679, 886.2998231397188, 936.8735220745181, 937.2174234514189, 939.1735272959786, 940.407543247753, 941.138857944983, 786.850498405304, 858.8239410920778, 877.9122087791234, 878.89892662344, 925.8472352061935, 646.2793984707065, 647.1813156357211, 647.4529022975578, 647.4690753582065, 647.5467172978933, 14379.209143497104, 14476.27618619065, 14547.199904005276, 14568.927155364156, 14662.340194691264, 14312.42750289987, 15124.016938898936, 15196.924015379853, 15230.238488075664, 15287.159206243652, 11775.528978840835, 11964.880351196463, 11978.341191234478, 12039.699007524881, 12160.209586376857, 3952.407138929155, 3973.543042550115, 3993.301172294866, 4019.417184508259, 4027.476428064362, 54379.45620543783, 54416.0, 54513.0, 54657.72671136619, 54689.453105468834, 10389.896101038892, 10760.3543787373, 10765.78468430627, 10956.835647465294, 11029.83455248172, 18974.14616342268, 19105.90447047756, 19165.0, 19192.520186995436, 19201.423957281302, 8086.150954149847, 8195.71315003972, 8578.571071446528, 8586.184312490303, 8807.823843523094, 4842.564169224786, 4848.248521479177, 4945.530174633404, 5003.099442100384, 5024.673396229234, 3461.44616861301, 3486.1284678830293, 3521.9823900880333, 3575.7675751076617, 3587.820608969541, 601.7794338889571, 615.6552330694735, 665.171861033017, 668.9331066893271, 670.51722759613, 748.5134662469329, 774.2953911940108, 813.4021494201756, 822.7531740477833, 835.7618078847499, 10038.447885366375, 10293.43386113771, 10335.22485813552, 10363.378197308186, 10421.739456513647, 647.2394935950334, 657.3984803904469, 678.7827895073627, 787.696736756353, 790.3005839831791, 4631.467381251199, 4819.59033482151, 4839.0, 4868.391451068616, 4888.095702295087, 59188.0, 60930.39069609247, 61157.0, 68554.31445685479, 68956.31043689599, 54211.18683219755, 56146.43853561412, 56705.0, 58715.0, 59152.0, 52992.0, 53021.73489132529, 53088.469115308355, 53660.0, 54003.459965399845, 10077.344972576759, 10116.747081323027, 10256.58973641046, 10405.115565176742, 10549.472526373804, 38673.80663096667, 39422.21155576815, 40559.594404055584, 40728.1854362917, 40812.1837563253, 45747.542524575656, 51334.743326284624, 51607.0, 51753.482465174864, 52179.217311741115, 12847.229166250056, 12885.355732213538, 12915.0, 13155.60533184006, 13212.933935330262, 13628.523001694894, 13789.103708259332, 14027.789583156056, 14365.0, 14417.9279103608, 10767.3539587625, 12566.937165314115, 12971.805422918475, 13047.543335983195, 13255.602331930058, 12450.875491244971, 12608.810867836806, 12859.29273889945, 12874.67813304675, 13326.200427974352, 12982.935085324512, 13160.80258796138, 13501.527446539323, 13637.590872273848, 13821.447142114203, 10573.312734672221, 10732.785344292914, 10740.624078157227, 10836.512356943798, 11152.052075573667, 10724.830270118937, 10725.624603138853, 11014.0, 11083.833742493707, 11147.498362573542], "value": 7099.135672830511, "unit": "score"}], "name": "dromaeo_css", "value": 7432.206215485958, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 110.0, 110.0, 111.0, 110.0, 105.0, 111.0, 110.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 124.0, 163.0, 171.0, 131.0, 149.0, 147.0, 126.0, 186.0, 126.0], "value": 147.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 159.0, 160.0, 159.0, 161.0, 162.0, 162.0, 159.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 178.0, 74.0, 74.0, 76.0, 77.0, 154.0, 77.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 152.0, 92.0, 92.0, 92.0, 143.0, 92.0, 92.0, 140.0], "value": 107.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 151.0, 144.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 54.0, 53.0, 53.0, 55.0, 74.0, 54.0, 51.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 57.0, 58.0, 58.0, 57.0, 57.0, 57.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 73.0, 74.0, 71.0, 73.0, 73.0, 72.0, 72.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 120.0, 110.0, 113.0, 111.0, 114.0, 111.0, 113.0, 126.0, 130.0], "value": 116.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 157.0, 161.0, 156.0, 156.0, 153.0, 156.0, 155.0, 157.0, 153.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 66.0, 64.0, 64.0, 63.0, 65.0, 65.0, 63.0], "value": 64.8, "unit": "score"}], "name": "kraken", "value": 1498.8999999999999, "alertThreshold": 2.0}]} 15:51:08 INFO - Return code: 0 15:51:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:51:08 ERROR - # TBPL SUCCESS # 15:51:08 INFO - Running post-action listener: _resource_record_post_action 15:51:08 INFO - Running post-run listener: _resource_record_post_run 15:51:09 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 483328; Write bytes: 245727232; Read time: 156; Write time: 229740 15:51:09 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 1298432; Read time: 0; Write time: 60 15:51:09 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 483328; Write bytes: 244428800; Read time: 156; Write time: 229680 15:51:09 INFO - Running post-run listener: _upload_blobber_files 15:51:09 INFO - Blob upload gear active. 15:51:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:51:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:51:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:51:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:51:09 INFO - (blobuploader) - INFO - Open directory for files ... 15:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:51:10 INFO - (blobuploader) - INFO - Done attempting. 15:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:51:11 INFO - (blobuploader) - INFO - Done attempting. 15:51:11 INFO - (blobuploader) - INFO - Iteration through files over. 15:51:11 INFO - Return code: 0 15:51:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:51:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:51:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a12f4a7ee0b36b16a6fa7c41ec5826513ca114bcb77b4ab11007aae738cd1b16659b50301e49f07eb15e3c80f0104171beec847a9775594cc44dc1f4eb0a2b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dedae7f58c364e35f1ee83ba01ffa99667605bcf9ebd15ad797817a2feb4e8479ce6912566c54d0273d306540aa377a39ca032ffbef9aa518e7fb6d3f77b1b4a"} 15:51:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:51:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:51:11 INFO - Contents: 15:51:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a12f4a7ee0b36b16a6fa7c41ec5826513ca114bcb77b4ab11007aae738cd1b16659b50301e49f07eb15e3c80f0104171beec847a9775594cc44dc1f4eb0a2b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dedae7f58c364e35f1ee83ba01ffa99667605bcf9ebd15ad797817a2feb4e8479ce6912566c54d0273d306540aa377a39ca032ffbef9aa518e7fb6d3f77b1b4a"} 15:51:11 INFO - Running post-run listener: copy_logs_to_upload_dir 15:51:11 INFO - Copying logs to upload dir... 15:51:11 INFO - mkdir: /builds/slave/test/build/upload/logs 15:51:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.026436 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-06-03 15:51:11.517136) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 15:51:11.521861) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a12f4a7ee0b36b16a6fa7c41ec5826513ca114bcb77b4ab11007aae738cd1b16659b50301e49f07eb15e3c80f0104171beec847a9775594cc44dc1f4eb0a2b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dedae7f58c364e35f1ee83ba01ffa99667605bcf9ebd15ad797817a2feb4e8479ce6912566c54d0273d306540aa377a39ca032ffbef9aa518e7fb6d3f77b1b4a"} build_url:https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/G3x_ZfDbR4ihP07aZ6qrRg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a12f4a7ee0b36b16a6fa7c41ec5826513ca114bcb77b4ab11007aae738cd1b16659b50301e49f07eb15e3c80f0104171beec847a9775594cc44dc1f4eb0a2b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dedae7f58c364e35f1ee83ba01ffa99667605bcf9ebd15ad797817a2feb4e8479ce6912566c54d0273d306540aa377a39ca032ffbef9aa518e7fb6d3f77b1b4a"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 15:51:11.576402) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:51:11.579799) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1464992667.626747-1738502970 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004693 ========= master_lag: 0.16 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:51:11.743187) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 15:51:11.743545) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 15:51:11.827992) ========= ========= Total master_lag: 0.47 =========