builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-040 starttime: 1467606600.7 results: success (0) buildid: 20160703183526 builduid: 4afe51364a1a41f58a25f952e1314257 revision: 344a25a1f2697c963321fe204c688d1e76a7cc01 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:00.700962) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:00.701354) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:00.701644) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:00.716743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:00.717007) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-03 21:30:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2016-07-03 21:30:01 (43.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125649 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:00.857317) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:00.857605) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018081 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:00.907894) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-03 21:30:00.908189) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 344a25a1f2697c963321fe204c688d1e76a7cc01 --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-beta --rev 344a25a1f2697c963321fe204c688d1e76a7cc01 --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=9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-03 21:30:01,985 truncating revision to first 12 chars 2016-07-03 21:30:01,985 Setting DEBUG logging. 2016-07-03 21:30:01,985 attempt 1/10 2016-07-03 21:30:01,985 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/344a25a1f269?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-03 21:30:02,868 unpacking tar archive at: mozilla-beta-344a25a1f269/testing/mozharness/ program finished with exit code 0 elapsedTime=1.218526 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-03 21:30:02.147482) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:02.147821) ========= script_repo_revision: 344a25a1f2697c963321fe204c688d1e76a7cc01 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:02.148239) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:02.151525) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-03 21:30:02.164980) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-03 21:30:02.165268) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:30:03 INFO - MultiFileLogger online at 20160703 21:30:03 in /builds/slave/test 21:30:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 21:30:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:30:03 INFO - {'append_to_log': False, 21:30:03 INFO - 'base_work_dir': '/builds/slave/test', 21:30:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 21:30:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:30:03 INFO - 'branch': 'Mozilla-Beta', 21:30:03 INFO - 'buildbot_json_path': 'buildprops.json', 21:30:03 INFO - 'config_files': ('talos/linux_config.py',), 21:30:03 INFO - 'default_actions': ('clobber', 21:30:03 INFO - 'read-buildbot-config', 21:30:03 INFO - 'download-and-extract', 21:30:03 INFO - 'populate-webroot', 21:30:03 INFO - 'create-virtualenv', 21:30:03 INFO - 'install', 21:30:03 INFO - 'run-tests'), 21:30:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:30:03 INFO - 'download_minidump_stackwalk': True, 21:30:03 INFO - 'download_symbols': 'ondemand', 21:30:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:30:03 INFO - 'tooltool.py': '/tools/tooltool.py', 21:30:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:30:03 INFO - '/tools/misc-python/virtualenv.py')}, 21:30:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:30:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:30:03 INFO - 'installer_path': 'installer.exe', 21:30:03 INFO - 'log_level': 'info', 21:30:03 INFO - 'log_name': 'talos', 21:30:03 INFO - 'log_to_console': True, 21:30:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:30:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:30:03 INFO - 'opt_config_files': (), 21:30:03 INFO - 'pip_index': False, 21:30:03 INFO - 'sps_profile': False, 21:30:03 INFO - 'sps_profile_interval': 0, 21:30:03 INFO - 'suite': 'dromaeojs-e10s', 21:30:03 INFO - 'system_bits': '32', 21:30:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:30:03 INFO - 'title': 'talos-linux64-ix-040', 21:30:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:30:03 INFO - 'use_talos_json': True, 21:30:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:30:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:30:03 INFO - 'work_dir': 'build'} 21:30:03 INFO - ##### 21:30:03 INFO - ##### Running clobber step. 21:30:03 INFO - ##### 21:30:03 INFO - Running pre-action listener: _resource_record_pre_action 21:30:03 INFO - Running main action method: clobber 21:30:03 INFO - rmtree: /builds/slave/test/build 21:30:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:30:03 INFO - Running post-action listener: _resource_record_post_action 21:30:03 INFO - ##### 21:30:03 INFO - ##### Running read-buildbot-config step. 21:30:03 INFO - ##### 21:30:03 INFO - Running pre-action listener: _resource_record_pre_action 21:30:03 INFO - Running main action method: read_buildbot_config 21:30:03 INFO - Using buildbot properties: 21:30:03 INFO - { 21:30:03 INFO - "project": "", 21:30:03 INFO - "product": "firefox", 21:30:03 INFO - "script_repo_revision": "production", 21:30:03 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 21:30:03 INFO - "repository": "", 21:30:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 21:30:03 INFO - "buildid": "20160703183526", 21:30:03 INFO - "pgo_build": "True", 21:30:03 INFO - "basedir": "/builds/slave/test", 21:30:03 INFO - "buildnumber": 83, 21:30:03 INFO - "slavename": "talos-linux64-ix-040", 21:30:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:30:03 INFO - "platform": "ubuntu64_hw", 21:30:03 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 21:30:03 INFO - "branch": "mozilla-beta", 21:30:03 INFO - "repo_path": "releases/mozilla-beta", 21:30:03 INFO - "revision": "344a25a1f2697c963321fe204c688d1e76a7cc01", 21:30:03 INFO - "stage_platform": "linux64-pgo", 21:30:03 INFO - "builduid": "4afe51364a1a41f58a25f952e1314257", 21:30:03 INFO - "slavebuilddir": "test" 21:30:03 INFO - } 21:30:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2. 21:30:03 INFO - Running post-action listener: _resource_record_post_action 21:30:03 INFO - ##### 21:30:03 INFO - ##### Running download-and-extract step. 21:30:03 INFO - ##### 21:30:03 INFO - Running pre-action listener: _resource_record_pre_action 21:30:03 INFO - Running main action method: download_and_extract 21:30:03 INFO - mkdir: /builds/slave/test/build/tests 21:30: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')]} 21:30:03 INFO - https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:30:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 21:30:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 21:30:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 21:30:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:30:11 INFO - Downloaded 1327 bytes. 21:30:11 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 21:30:11 INFO - Using the following test package requirements: 21:30:11 INFO - {u'common': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 21:30:11 INFO - u'cppunittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 21:30:11 INFO - u'firefox-48.0.en-US.linux-x86_64.cppunittest.tests.zip'], 21:30:11 INFO - u'gtest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 21:30:11 INFO - u'firefox-48.0.en-US.linux-x86_64.gtest.tests.zip'], 21:30:11 INFO - u'jittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 21:30:11 INFO - u'jsshell-linux-x86_64.zip'], 21:30:11 INFO - u'mochitest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 21:30:11 INFO - u'firefox-48.0.en-US.linux-x86_64.mochitest.tests.zip'], 21:30:11 INFO - u'mozbase': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 21:30:11 INFO - u'reftest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 21:30:11 INFO - u'firefox-48.0.en-US.linux-x86_64.reftest.tests.zip'], 21:30:11 INFO - u'talos': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 21:30:11 INFO - u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'], 21:30:11 INFO - u'web-platform': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 21:30:11 INFO - u'firefox-48.0.en-US.linux-x86_64.web-platform.tests.zip'], 21:30:11 INFO - u'xpcshell': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 21:30:11 INFO - u'firefox-48.0.en-US.linux-x86_64.xpcshell.tests.zip']} 21:30:11 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:11 INFO - https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:30:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 21:30:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 21:30:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 21:30:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:30:21 INFO - Downloaded 24237155 bytes. 21:30:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:30:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:30:22 INFO - Return code: 0 21:30:22 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:30:22 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')]} 21:30:22 INFO - https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:30:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 21:30:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 21:30:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 21:30:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:30:23 INFO - Downloaded 24237155 bytes. 21:30:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:30:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:30:25 INFO - Return code: 0 21:30:25 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')]} 21:30:25 INFO - https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:30:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 21:30:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 21:30:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 21:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:30:26 INFO - Downloaded 10840145 bytes. 21:30:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:30:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:30:26 INFO - Return code: 0 21:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:26 INFO - https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:30:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 21:30:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 21:30:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 21:30:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:30:28 INFO - Downloaded 56015949 bytes. 21:30:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 21:30:28 INFO - mkdir: /builds/slave/test/properties 21:30:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:30:28 INFO - Writing to file /builds/slave/test/properties/build_url 21:30:28 INFO - Contents: 21:30:28 INFO - build_url:https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 21:30:29 INFO - Running post-action listener: _resource_record_post_action 21:30:29 INFO - Running post-action listener: set_extra_try_arguments 21:30:29 INFO - ##### 21:30:29 INFO - ##### Running populate-webroot step. 21:30:29 INFO - ##### 21:30:29 INFO - Running pre-action listener: _resource_record_pre_action 21:30:29 INFO - Running main action method: populate_webroot 21:30:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:30:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:30:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:30:29 INFO - '--tppagecycles', 21:30:29 INFO - '7'], 21:30:29 INFO - 'tests': ['tsvgm']}}, 21:30:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:30:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:30:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:30:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:30:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:30:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:30:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:30:29 INFO - 'talos_options': ['--disable-e10s'], 21:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:30:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:30:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:30:29 INFO - 'talos_options': ['--disable-e10s'], 21:30:29 INFO - 'tests': ['damp', 'tps']}, 21:30:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:30:29 INFO - 'tests': ['damp', 'tps']}, 21:30:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:30:29 INFO - 'tests': ['dromaeo_dom']}, 21:30:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:30:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:30:29 INFO - 'tests': ['a11yr', 21:30:29 INFO - 'ts_paint', 21:30:29 INFO - 'tpaint', 21:30:29 INFO - 'sessionrestore', 21:30:29 INFO - 'sessionrestore_no_auto_restore', 21:30:29 INFO - 'tabpaint']}, 21:30:29 INFO - 'other-e10s': {'tests': ['a11yr', 21:30:29 INFO - 'ts_paint', 21:30:29 INFO - 'tpaint', 21:30:29 INFO - 'sessionrestore', 21:30:29 INFO - 'sessionrestore_no_auto_restore', 21:30:29 INFO - 'tabpaint']}, 21:30:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:30:29 INFO - 'ts_paint', 21:30:29 INFO - 'tpaint', 21:30:29 INFO - 'sessionrestore', 21:30:29 INFO - 'sessionrestore_no_auto_restore', 21:30:29 INFO - 'tabpaint']}, 21:30:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:30:29 INFO - 'ts_paint', 21:30:29 INFO - 'tpaint', 21:30:29 INFO - 'sessionrestore', 21:30:29 INFO - 'sessionrestore_no_auto_restore', 21:30:29 INFO - 'tabpaint']}, 21:30:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:30:29 INFO - 'tests': ['a11yr', 21:30:29 INFO - 'ts_paint', 21:30:29 INFO - 'tpaint', 21:30:29 INFO - 'sessionrestore', 21:30:29 INFO - 'sessionrestore_no_auto_restore', 21:30:29 INFO - 'tabpaint']}, 21:30:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:30:29 INFO - 'tests': ['a11yr', 21:30:29 INFO - 'ts_paint', 21:30:29 INFO - 'tpaint', 21:30:29 INFO - 'sessionrestore', 21:30:29 INFO - 'sessionrestore_no_auto_restore', 21:30:29 INFO - 'tabpaint']}, 21:30:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:30:29 INFO - 'tests': ['tsvgx', 21:30:29 INFO - 'tsvgr_opacity', 21:30:29 INFO - 'tart', 21:30:29 INFO - 'tscrollx', 21:30:29 INFO - 'cart']}, 21:30:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:30:29 INFO - 'tsvgr_opacity', 21:30:29 INFO - 'tart', 21:30:29 INFO - 'tscrollx', 21:30:29 INFO - 'cart']}, 21:30:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:30:29 INFO - 'talos_options': ['--disable-e10s'], 21:30:29 INFO - 'tests': ['tp5o']}, 21:30:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:30:29 INFO - 'tests': ['tp5o']}, 21:30:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:30:29 INFO - 'talos_options': ['--disable-e10s', 21:30:29 INFO - '--xperf_path', 21:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:30:29 INFO - 'tests': ['tp5n']}, 21:30:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:30:29 INFO - 'talos_options': ['--xperf_path', 21:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:30:29 INFO - 'tests': ['tp5n']}}, 21:30:29 INFO - 'talos.zip': {'path': '', 21:30:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:30:29 INFO - Running post-action listener: _resource_record_post_action 21:30:29 INFO - ##### 21:30:29 INFO - ##### Running create-virtualenv step. 21:30:29 INFO - ##### 21:30:29 INFO - Running pre-action listener: _resource_record_pre_action 21:30:29 INFO - Running main action method: create_virtualenv 21:30:29 INFO - Creating virtualenv /builds/slave/test/build/venv 21:30:29 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 21:30:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:30:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:30:29 INFO - Using real prefix '/usr' 21:30:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:30:29 INFO - Installing distribute.............................................................................................................................................................................................done. 21:30:31 INFO - Installing pip.................done. 21:30:31 INFO - Return code: 0 21:30:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:30:31 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')]} 21:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:31 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')]} 21:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:31 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 0x7f1638a23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1270410>, '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 0x1271310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12717a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', '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 21:30:31 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 21:30:31 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 21:30:31 INFO - Using env: {'DISPLAY': ':0', 21:30:31 INFO - 'HOME': '/home/cltbld', 21:30:31 INFO - 'LANG': 'en_US.UTF-8', 21:30:31 INFO - 'LANGUAGE': 'en_US:en', 21:30:31 INFO - 'LOGNAME': 'cltbld', 21:30:31 INFO - 'MAIL': '/var/mail/cltbld', 21:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:31 INFO - 'MOZ_NO_REMOTE': '1', 21:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:31 INFO - 'NO_EM_RESTART': '1', 21:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:31 INFO - 'PWD': '/builds/slave/test', 21:30:31 INFO - 'SHELL': '/bin/bash', 21:30:31 INFO - 'SHLVL': '1', 21:30:31 INFO - 'TERM': 'linux', 21:30:31 INFO - 'TMOUT': '86400', 21:30:31 INFO - 'USER': 'cltbld', 21:30:31 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', 21:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:31 INFO - '_': '/tools/buildbot/bin/python'} 21:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:31 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 21:30:31 INFO - Cleaning up... 21:30:31 INFO - Return code: 0 21:30:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:30:31 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')]} 21:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:31 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')]} 21:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:31 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 0x7f1638a23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1270410>, '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 0x1271310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12717a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', '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 21:30:31 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 21:30:31 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 21:30:31 INFO - Using env: {'DISPLAY': ':0', 21:30:31 INFO - 'HOME': '/home/cltbld', 21:30:31 INFO - 'LANG': 'en_US.UTF-8', 21:30:31 INFO - 'LANGUAGE': 'en_US:en', 21:30:31 INFO - 'LOGNAME': 'cltbld', 21:30:31 INFO - 'MAIL': '/var/mail/cltbld', 21:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:31 INFO - 'MOZ_NO_REMOTE': '1', 21:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:31 INFO - 'NO_EM_RESTART': '1', 21:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:31 INFO - 'PWD': '/builds/slave/test', 21:30:31 INFO - 'SHELL': '/bin/bash', 21:30:31 INFO - 'SHLVL': '1', 21:30:31 INFO - 'TERM': 'linux', 21:30:31 INFO - 'TMOUT': '86400', 21:30:31 INFO - 'USER': 'cltbld', 21:30:31 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', 21:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:31 INFO - '_': '/tools/buildbot/bin/python'} 21:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:32 INFO - Downloading/unpacking psutil>=0.7.1 21:30:32 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 21:30:32 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 21:30:32 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 21:30:32 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 21:30:32 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 21:30:32 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 21:30:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:30:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:30:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:30:34 INFO - Installing collected packages: psutil 21:30:34 INFO - Running setup.py install for psutil 21:30:34 INFO - building 'psutil._psutil_linux' extension 21:30:34 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 21:30:34 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 21:30:34 INFO - building 'psutil._psutil_posix' extension 21:30:34 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 21:30:34 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 21:30:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:30:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:30:34 INFO - Successfully installed psutil 21:30:34 INFO - Cleaning up... 21:30:34 INFO - Return code: 0 21:30:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21: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')]} 21:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21: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')]} 21:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:34 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 0x7f1638a23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1270410>, '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 0x1271310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12717a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', '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 21:30:34 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 21:30:34 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 21:30:34 INFO - Using env: {'DISPLAY': ':0', 21:30:34 INFO - 'HOME': '/home/cltbld', 21:30:34 INFO - 'LANG': 'en_US.UTF-8', 21:30:34 INFO - 'LANGUAGE': 'en_US:en', 21:30:34 INFO - 'LOGNAME': 'cltbld', 21:30:34 INFO - 'MAIL': '/var/mail/cltbld', 21:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:34 INFO - 'MOZ_NO_REMOTE': '1', 21:30:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:34 INFO - 'NO_EM_RESTART': '1', 21:30:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:34 INFO - 'PWD': '/builds/slave/test', 21:30:34 INFO - 'SHELL': '/bin/bash', 21:30:34 INFO - 'SHLVL': '1', 21:30:34 INFO - 'TERM': 'linux', 21:30:34 INFO - 'TMOUT': '86400', 21:30:34 INFO - 'USER': 'cltbld', 21:30:34 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', 21:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:34 INFO - '_': '/tools/buildbot/bin/python'} 21:30:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:30:34 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 21:30:34 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 21:30:34 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 21:30:34 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 21:30:34 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 21:30:34 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 21:30:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:30:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:30:36 INFO - Installing collected packages: mozsystemmonitor 21:30:36 INFO - Running setup.py install for mozsystemmonitor 21:30:36 INFO - Successfully installed mozsystemmonitor 21:30:36 INFO - Cleaning up... 21:30:36 INFO - Return code: 0 21:30:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:30:36 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')]} 21:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:36 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')]} 21:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:36 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 0x7f1638a23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1270410>, '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 0x1271310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12717a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', '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 21:30:36 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 21:30:36 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 21:30:36 INFO - Using env: {'DISPLAY': ':0', 21:30:36 INFO - 'HOME': '/home/cltbld', 21:30:36 INFO - 'LANG': 'en_US.UTF-8', 21:30:36 INFO - 'LANGUAGE': 'en_US:en', 21:30:36 INFO - 'LOGNAME': 'cltbld', 21:30:36 INFO - 'MAIL': '/var/mail/cltbld', 21:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:36 INFO - 'MOZ_NO_REMOTE': '1', 21:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:36 INFO - 'NO_EM_RESTART': '1', 21:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:36 INFO - 'PWD': '/builds/slave/test', 21:30:36 INFO - 'SHELL': '/bin/bash', 21:30:36 INFO - 'SHLVL': '1', 21:30:36 INFO - 'TERM': 'linux', 21:30:36 INFO - 'TMOUT': '86400', 21:30:36 INFO - 'USER': 'cltbld', 21:30:36 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', 21:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:36 INFO - '_': '/tools/buildbot/bin/python'} 21:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:37 INFO - Downloading/unpacking blobuploader==1.2.4 21:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:39 INFO - Downloading blobuploader-1.2.4.tar.gz 21:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:30:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:30:39 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 21:30:39 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 21:30:39 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 21:30:39 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 21:30:39 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 21:30:39 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 21:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:30:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:30:39 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 21:30:39 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 21:30:39 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 21:30:39 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 21:30:39 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 21:30:39 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 21:30:39 INFO - Downloading docopt-0.6.1.tar.gz 21:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:30:39 INFO - Installing collected packages: blobuploader, requests, docopt 21:30:39 INFO - Running setup.py install for blobuploader 21:30:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:30:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:30:39 INFO - Running setup.py install for requests 21:30:40 INFO - Running setup.py install for docopt 21:30:40 INFO - Successfully installed blobuploader requests docopt 21:30:40 INFO - Cleaning up... 21:30:40 INFO - Return code: 0 21:30:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:40 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 0x7f1638a23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1270410>, '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 0x1271310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12717a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', '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 21:30:40 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 21:30:40 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 21:30:40 INFO - Using env: {'DISPLAY': ':0', 21:30:40 INFO - 'HOME': '/home/cltbld', 21:30:40 INFO - 'LANG': 'en_US.UTF-8', 21:30:40 INFO - 'LANGUAGE': 'en_US:en', 21:30:40 INFO - 'LOGNAME': 'cltbld', 21:30:40 INFO - 'MAIL': '/var/mail/cltbld', 21:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:40 INFO - 'MOZ_NO_REMOTE': '1', 21:30:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:40 INFO - 'NO_EM_RESTART': '1', 21:30:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:40 INFO - 'PWD': '/builds/slave/test', 21:30:40 INFO - 'SHELL': '/bin/bash', 21:30:40 INFO - 'SHLVL': '1', 21:30:40 INFO - 'TERM': 'linux', 21:30:40 INFO - 'TMOUT': '86400', 21:30:40 INFO - 'USER': 'cltbld', 21:30:40 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', 21:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:40 INFO - '_': '/tools/buildbot/bin/python'} 21:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:30:40 INFO - Running setup.py (path:/tmp/pip-IE1cZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:30:40 INFO - Running setup.py (path:/tmp/pip-E0xn2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:30:40 INFO - Running setup.py (path:/tmp/pip-KndafF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:30:40 INFO - Running setup.py (path:/tmp/pip-HMuBst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:30:40 INFO - Running setup.py (path:/tmp/pip-ekjhav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:30:41 INFO - Running setup.py (path:/tmp/pip-kSnbkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:30:41 INFO - Running setup.py (path:/tmp/pip-g3GOCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:30:41 INFO - Running setup.py (path:/tmp/pip-JYPYoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:30:41 INFO - Running setup.py (path:/tmp/pip-8j7yIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:30:41 INFO - Running setup.py (path:/tmp/pip-_snCmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:30:41 INFO - Running setup.py (path:/tmp/pip-r1GIHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:30:41 INFO - Running setup.py (path:/tmp/pip-OnWFC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:30:41 INFO - Running setup.py (path:/tmp/pip-8xX97Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:30:41 INFO - Running setup.py (path:/tmp/pip-N7azK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:30:41 INFO - Running setup.py (path:/tmp/pip-LYD3wJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:30:41 INFO - Running setup.py (path:/tmp/pip-9FP97N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:30:42 INFO - Running setup.py (path:/tmp/pip-Iyae_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:30:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:30:42 INFO - Running setup.py install for manifestparser 21:30:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:30:42 INFO - Running setup.py install for mozcrash 21:30:42 INFO - Running setup.py install for mozdebug 21:30:42 INFO - Running setup.py install for mozdevice 21:30:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:30:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:30:42 INFO - Running setup.py install for mozfile 21:30:42 INFO - Running setup.py install for mozhttpd 21:30:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:30:42 INFO - Running setup.py install for mozinfo 21:30:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:30:42 INFO - Running setup.py install for mozInstall 21:30:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:30:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:30:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:30:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:30:42 INFO - Running setup.py install for mozleak 21:30:42 INFO - Running setup.py install for mozlog 21:30:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:30:43 INFO - Running setup.py install for moznetwork 21:30:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:30:43 INFO - Running setup.py install for mozprocess 21:30:43 INFO - Running setup.py install for mozprofile 21:30:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:30:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:30:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:30:43 INFO - Running setup.py install for mozrunner 21:30:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:30:43 INFO - Running setup.py install for mozscreenshot 21:30:43 INFO - Running setup.py install for moztest 21:30:43 INFO - Running setup.py install for mozversion 21:30:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:30:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:30:43 INFO - Cleaning up... 21:30:43 INFO - Return code: 0 21:30:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21: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')]} 21:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21: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')]} 21:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:43 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 0x7f1638a23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1270410>, '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 0x1271310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12717a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', '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 21:30:43 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 21:30:43 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 21:30:43 INFO - Using env: {'DISPLAY': ':0', 21:30:43 INFO - 'HOME': '/home/cltbld', 21:30:43 INFO - 'LANG': 'en_US.UTF-8', 21:30:43 INFO - 'LANGUAGE': 'en_US:en', 21:30:43 INFO - 'LOGNAME': 'cltbld', 21:30:43 INFO - 'MAIL': '/var/mail/cltbld', 21:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:43 INFO - 'MOZ_NO_REMOTE': '1', 21:30:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:43 INFO - 'NO_EM_RESTART': '1', 21:30:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:43 INFO - 'PWD': '/builds/slave/test', 21:30:43 INFO - 'SHELL': '/bin/bash', 21:30:43 INFO - 'SHLVL': '1', 21:30:43 INFO - 'TERM': 'linux', 21:30:43 INFO - 'TMOUT': '86400', 21:30:43 INFO - 'USER': 'cltbld', 21:30:43 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', 21:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:43 INFO - '_': '/tools/buildbot/bin/python'} 21:30:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:30:44 INFO - Running setup.py (path:/tmp/pip-rALvUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:30:44 INFO - Running setup.py (path:/tmp/pip-VZbWDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:30:44 INFO - Running setup.py (path:/tmp/pip-8URXUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:30:44 INFO - Running setup.py (path:/tmp/pip-j2Vyjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:30:44 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)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:30:44 INFO - Running setup.py (path:/tmp/pip-XI_nk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:30:44 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)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:30:44 INFO - Running setup.py (path:/tmp/pip-TdWCDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:30:44 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)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:30:44 INFO - Running setup.py (path:/tmp/pip-U4Ul_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:30:44 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)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:30:44 INFO - Running setup.py (path:/tmp/pip-itXWkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:30:44 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)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:30:44 INFO - Running setup.py (path:/tmp/pip-xu3asS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:30:44 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)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:30:44 INFO - Running setup.py (path:/tmp/pip-sGI9t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:30:44 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)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:30:44 INFO - Running setup.py (path:/tmp/pip-6hBL13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:30:44 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)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:30:44 INFO - Running setup.py (path:/tmp/pip-YT_zcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:30:44 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)) 21:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:30:44 INFO - Running setup.py (path:/tmp/pip-SiBml_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:30:45 INFO - Running setup.py (path:/tmp/pip-anJBWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:30:45 INFO - Running setup.py (path:/tmp/pip-SdakAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:30:45 INFO - Running setup.py (path:/tmp/pip-4zs5Du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:30:45 INFO - Running setup.py (path:/tmp/pip-j6ccub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:30:45 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)) 21:30:45 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)) 21:30:45 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)) 21:30:45 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)) 21:30:45 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)) 21:30:45 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)) 21:30:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:30:45 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 21:30:45 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 21:30:45 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 21:30:45 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 21:30:45 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 21:30:45 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 21:30:47 INFO - Downloading blessings-1.6.tar.gz 21:30:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:30:47 INFO - Installing collected packages: blessings 21:30:47 INFO - Running setup.py install for blessings 21:30:47 INFO - Successfully installed blessings 21:30:47 INFO - Cleaning up... 21:30:47 INFO - Return code: 0 21:30:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:30:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:30:47 INFO - Reading from file tmpfile_stdout 21:30:47 INFO - Current package versions: 21:30:47 INFO - argparse == 1.2.1 21:30:47 INFO - blessings == 1.6 21:30:47 INFO - blobuploader == 1.2.4 21:30:47 INFO - docopt == 0.6.1 21:30:47 INFO - manifestparser == 1.1 21:30:47 INFO - mozInstall == 1.12 21:30:47 INFO - mozcrash == 0.17 21:30:47 INFO - mozdebug == 0.1 21:30:47 INFO - mozdevice == 0.48 21:30:47 INFO - mozfile == 1.2 21:30:47 INFO - mozhttpd == 0.7 21:30:47 INFO - mozinfo == 0.9 21:30:47 INFO - mozleak == 0.1 21:30:47 INFO - mozlog == 3.1 21:30:47 INFO - moznetwork == 0.27 21:30:47 INFO - mozprocess == 0.22 21:30:47 INFO - mozprofile == 0.28 21:30:47 INFO - mozrunner == 6.11 21:30:47 INFO - mozscreenshot == 0.1 21:30:47 INFO - mozsystemmonitor == 0.0 21:30:47 INFO - moztest == 0.7 21:30:47 INFO - mozversion == 1.4 21:30:47 INFO - psutil == 3.1.1 21:30:47 INFO - requests == 1.2.3 21:30:47 INFO - wsgiref == 0.1.2 21:30:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21: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')]} 21:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21: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')]} 21:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:47 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 0x7f1638a23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1270410>, '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 0x1271310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12717a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', '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 21:30:47 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 21:30:47 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 21:30:47 INFO - Using env: {'DISPLAY': ':0', 21:30:47 INFO - 'HOME': '/home/cltbld', 21:30:47 INFO - 'LANG': 'en_US.UTF-8', 21:30:47 INFO - 'LANGUAGE': 'en_US:en', 21:30:47 INFO - 'LOGNAME': 'cltbld', 21:30:47 INFO - 'MAIL': '/var/mail/cltbld', 21:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:47 INFO - 'MOZ_NO_REMOTE': '1', 21:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:47 INFO - 'NO_EM_RESTART': '1', 21:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:47 INFO - 'PWD': '/builds/slave/test', 21:30:47 INFO - 'SHELL': '/bin/bash', 21:30:47 INFO - 'SHLVL': '1', 21:30:47 INFO - 'TERM': 'linux', 21:30:47 INFO - 'TMOUT': '86400', 21:30:47 INFO - 'USER': 'cltbld', 21:30:47 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', 21:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:47 INFO - '_': '/tools/buildbot/bin/python'} 21:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:47 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)) 21:30:47 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)) 21:30:47 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)) 21:30:47 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)) 21:30:47 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)) 21:30:47 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)) 21:30:47 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)) 21:30:47 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)) 21:30:47 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)) 21:30:47 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)) 21:30:47 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)) 21:30:47 INFO - Cleaning up... 21:30:47 INFO - Return code: 0 21:30:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21: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')]} 21:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21: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')]} 21:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:47 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 0x7f1638a23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1270410>, '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 0x1271310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12717a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', '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 21:30:47 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 21:30:47 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 21:30:47 INFO - Using env: {'DISPLAY': ':0', 21:30:47 INFO - 'HOME': '/home/cltbld', 21:30:47 INFO - 'LANG': 'en_US.UTF-8', 21:30:47 INFO - 'LANGUAGE': 'en_US:en', 21:30:47 INFO - 'LOGNAME': 'cltbld', 21:30:47 INFO - 'MAIL': '/var/mail/cltbld', 21:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:47 INFO - 'MOZ_NO_REMOTE': '1', 21:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:47 INFO - 'NO_EM_RESTART': '1', 21:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:47 INFO - 'PWD': '/builds/slave/test', 21:30:47 INFO - 'SHELL': '/bin/bash', 21:30:47 INFO - 'SHLVL': '1', 21:30:47 INFO - 'TERM': 'linux', 21:30:47 INFO - 'TMOUT': '86400', 21:30:47 INFO - 'USER': 'cltbld', 21:30:47 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', 21:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:47 INFO - '_': '/tools/buildbot/bin/python'} 21:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:48 INFO - Downloading/unpacking jsonschema 21:30:48 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 21:30:48 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 21:30:48 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 21:30:48 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 21:30:48 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 21:30:48 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 21:30:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:30:50 INFO - Downloading/unpacking functools32 (from jsonschema) 21:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:50 INFO - Downloading functools32-3.2.3-2.tar.gz 21:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:30:50 INFO - warning: no files found matching '*.txt' 21:30:50 INFO - no previously-included directories found matching 'build' 21:30:50 INFO - no previously-included directories found matching 'dist' 21:30:50 INFO - no previously-included directories found matching '.git*' 21:30:50 INFO - Installing collected packages: jsonschema, functools32 21:30:50 INFO - Running setup.py install for functools32 21:30:50 INFO - warning: no files found matching '*.txt' 21:30:50 INFO - no previously-included directories found matching 'build' 21:30:50 INFO - no previously-included directories found matching 'dist' 21:30:50 INFO - no previously-included directories found matching '.git*' 21:30:50 INFO - Successfully installed jsonschema functools32 21:30:50 INFO - Cleaning up... 21:30:50 INFO - Return code: 0 21:30:50 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:50 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 0x7f1638a23f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1270410>, '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 0x1271310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12717a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', '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 21:30:50 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 21:30:50 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 21:30:50 INFO - Using env: {'DISPLAY': ':0', 21:30:50 INFO - 'HOME': '/home/cltbld', 21:30:50 INFO - 'LANG': 'en_US.UTF-8', 21:30:50 INFO - 'LANGUAGE': 'en_US:en', 21:30:50 INFO - 'LOGNAME': 'cltbld', 21:30:50 INFO - 'MAIL': '/var/mail/cltbld', 21:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:50 INFO - 'MOZ_NO_REMOTE': '1', 21:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:50 INFO - 'NO_EM_RESTART': '1', 21:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:50 INFO - 'PWD': '/builds/slave/test', 21:30:50 INFO - 'SHELL': '/bin/bash', 21:30:50 INFO - 'SHLVL': '1', 21:30:50 INFO - 'TERM': 'linux', 21:30:50 INFO - 'TMOUT': '86400', 21:30:50 INFO - 'USER': 'cltbld', 21:30:50 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', 21:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:50 INFO - '_': '/tools/buildbot/bin/python'} 21:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:50 INFO - Downloading/unpacking flake8 21:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:30:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:30:52 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 21:30:52 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 21:30:52 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 21:30:52 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 21:30:52 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 21:30:52 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 21:30:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:30:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:30:52 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 21:30:52 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 21:30:52 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 21:30:52 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 21:30:52 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 21:30:52 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 21:30:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:30:53 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 21:30:53 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 21:30:53 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 21:30:53 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 21:30:53 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 21:30:53 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 21:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:30:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:30:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:30:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:30:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:30:53 INFO - no previously-included directories found matching 'docs/_build' 21:30:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:30:53 INFO - Running setup.py install for pep8 21:30:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:30:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:30:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:30:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:30:53 INFO - no previously-included directories found matching 'docs/_build' 21:30:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:30:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:30:53 INFO - Cleaning up... 21:30:53 INFO - Return code: 0 21:30:53 INFO - Running post-action listener: _resource_record_post_action 21:30:53 INFO - Running post-action listener: _start_resource_monitoring 21:30:53 INFO - Starting resource monitoring. 21:30:53 INFO - ##### 21:30:53 INFO - ##### Running install step. 21:30:53 INFO - ##### 21:30:53 INFO - Running pre-action listener: _resource_record_pre_action 21:30:53 INFO - Running main action method: install 21:30:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:30:53 INFO - Reading from file tmpfile_stdout 21:30:53 INFO - Detecting whether we're running mozinstall >=1.0... 21:30:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:30:53 INFO - Reading from file tmpfile_stdout 21:30:53 INFO - Output received: 21:30:53 INFO - Usage: mozinstall [options] installer 21:30:53 INFO - Options: 21:30:53 INFO - -h, --help show this help message and exit 21:30:53 INFO - -d DEST, --destination=DEST 21:30:53 INFO - Directory to install application into. [default: 21:30:53 INFO - "/builds/slave/test"] 21:30:53 INFO - --app=APP Application being installed. [default: firefox] 21:30:53 INFO - mkdir: /builds/slave/test/build/application 21:30:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:31:07 INFO - Reading from file tmpfile_stdout 21:31:07 INFO - Output received: 21:31:07 INFO - /builds/slave/test/build/application/firefox/firefox 21:31:07 INFO - Running post-action listener: _resource_record_post_action 21:31:07 INFO - ##### 21:31:07 INFO - ##### Running run-tests step. 21:31:07 INFO - ##### 21:31:07 INFO - Running pre-action listener: _resource_record_pre_action 21:31:07 INFO - Running main action method: run_tests 21:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:31:07 INFO - Python 2.7.3 21:31:07 INFO - Return code: 0 21:31:07 INFO - grabbing minidump binary from tooltool 21:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:07 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 0x1270410>, '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 0x1271310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12717a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:31:07 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 21:31:07 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 21:31:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:31:07 INFO - Return code: 0 21:31:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:31:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:31:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:31:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:31:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:31:08 INFO - Return code: 0 21:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:31:08 INFO - Using env: {'DISPLAY': ':0', 21:31:08 INFO - 'HOME': '/home/cltbld', 21:31:08 INFO - 'LANG': 'en_US.UTF-8', 21:31:08 INFO - 'LANGUAGE': 'en_US:en', 21:31:08 INFO - 'LOGNAME': 'cltbld', 21:31:08 INFO - 'MAIL': '/var/mail/cltbld', 21:31:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:31:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:08 INFO - 'MOZ_NO_REMOTE': '1', 21:31:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:08 INFO - 'NO_EM_RESTART': '1', 21:31:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:08 INFO - 'PWD': '/builds/slave/test', 21:31:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:31:08 INFO - 'SHELL': '/bin/bash', 21:31:08 INFO - 'SHLVL': '1', 21:31:08 INFO - 'TERM': 'linux', 21:31:08 INFO - 'TMOUT': '86400', 21:31:08 INFO - 'USER': 'cltbld', 21:31:08 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809', 21:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:08 INFO - '_': '/tools/buildbot/bin/python'} 21:31:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:31:08 INFO - mozversion application_buildid: 20160703183526 21:31:08 INFO - mozversion application_changeset: 344a25a1f2697c963321fe204c688d1e76a7cc01 21:31:08 INFO - mozversion application_display_name: Firefox 21:31:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:31:08 INFO - mozversion application_name: Firefox 21:31:08 INFO - mozversion application_remotingname: firefox 21:31:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:31:08 INFO - mozversion application_vendor: Mozilla 21:31:08 INFO - mozversion application_version: 48.0 21:31:08 INFO - mozversion platform_buildid: 20160703183526 21:31:08 INFO - mozversion platform_changeset: 344a25a1f2697c963321fe204c688d1e76a7cc01 21:31:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:31:08 INFO - mozversion platform_version: 48.0 21:31:08 INFO - using testdate: 1467606668 21:31:08 INFO - actual date: 1467606668 21:31:08 INFO - starting webserver on 'localhost:40995' 21:31:08 INFO - SUITE-START | Running 2 tests 21:31:08 INFO - TEST-START | dromaeo_css 21:31:08 INFO - operating with platform_type : linux_ 21:31:08 INFO - Initialising browser for dromaeo_css test... 21:31:08 INFO - TEST-INFO | started process 29336 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvoZhd7/profile http://localhost:40995/getInfo.html) 21:31:12 INFO - PROCESS | 29336 | 21:31:12 INFO - PROCESS | 29336 | (process:29414): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:31:12 INFO - PROCESS | 29336 | __metrics Screen width/height:1600/1200 21:31:12 INFO - PROCESS | 29336 | colorDepth:24 21:31:12 INFO - PROCESS | 29336 | Browser inner width/height: 1024/697 21:31:12 INFO - PROCESS | 29336 | __metrics 21:31:13 ERROR - PROCESS | 29336 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 21:31:14 INFO - TEST-INFO | 29336: exit 0 21:31:14 INFO - Browser initialized. 21:31:14 INFO - Running cycle 1/1 for dromaeo_css test... 21:31:14 INFO - TEST-INFO | started process 29464 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvoZhd7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:31:16 INFO - PROCESS | 29464 | 21:31:16 INFO - PROCESS | 29464 | (process:29531): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:31:26 INFO - PROCESS | 29464 | RSS: Main: 150843392 21:31:26 INFO - PROCESS | 29464 | 21:32:16 INFO - PROCESS | 29464 | 1467606736095 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] 21:34:23 INFO - PROCESS | 29464 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40995/tests/dromaeo/cssquery-ext.html) 21:34:24 INFO - PROCESS | 29464 | RSS: Main: 155045888 21:34:24 INFO - PROCESS | 29464 | 21:37:26 INFO - PROCESS | 29464 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-ext.html (next: http://localhost:40995/tests/dromaeo/cssquery-jquery.html) 21:37:26 INFO - PROCESS | 29464 | RSS: Main: 153255936 21:37:26 INFO - PROCESS | 29464 | 21:40:28 INFO - PROCESS | 29464 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40995/tests/dromaeo/cssquery-mootools.html) 21:40:29 INFO - PROCESS | 29464 | RSS: Main: 152711168 21:40:29 INFO - PROCESS | 29464 | 21:43:30 INFO - PROCESS | 29464 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40995/tests/dromaeo/cssquery-prototype.html) 21:43:31 INFO - PROCESS | 29464 | RSS: Main: 154206208 21:43:31 INFO - PROCESS | 29464 | 21:46:33 INFO - PROCESS | 29464 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40995/tests/dromaeo/cssquery-yui.html) 21:46:34 INFO - PROCESS | 29464 | RSS: Main: 152801280 21:46:34 INFO - PROCESS | 29464 | 21:49:36 INFO - PROCESS | 29464 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-yui.html (next: http://localhost:40995/tests/dromaeo/cssquery-dojo.html) 21:49:36 INFO - PROCESS | 29464 | RSS: Main: 156581888 21:49:36 INFO - PROCESS | 29464 | 21:49:36 INFO - PROCESS | 29464 | __start_tp_report 21:49:36 INFO - PROCESS | 29464 | _x_x_mozilla_page_load 21:49:36 INFO - PROCESS | 29464 | _x_x_mozilla_page_load_details 21:49:36 INFO - PROCESS | 29464 | |i|pagename|runs| 21:49:36 INFO - PROCESS | 29464 | |0;dojo.html;3296.274819539701;3348.5479460272854;3352.195473086459;3377.864885404584;3389.9322013559713;5544.708708048913;5561.33264008319;5578.497935185833;5599.776008959647;5607.7476513556885;5502.614816962814;5571.192177134323;5572.0527510323245;5577.8605534861645;5581;4982.676126051804;5053.77258023388;5094.7197904115255;5097.770600322994;5104.872378190556;4904.705717656952;5050.7222102784335;5136.357955255593;5182.663126896739;5206.375234971809;5019.247112933052;5048.141815891289;5085.745712714368;5112.156494178456;5151.253068305094;5061.6962982221;5074.720890351029;5077.669951453144;5091.083604951107;5109.718965456898;4876.853694389139;4996.975015124937;5010.173321401964;5040.6723562968655;5065.11360511909;5253.789848406059;5309.203619457074;5331.680099194022;5335.733213339357;5395.7032363220005;5917.556183286228;5932.9703351483395;5940.227770389822;5954.2259506264345;5971.044632858721;5807;5957.165996760457;5973.8207853764025;5991.161237426763;5997.610155339932;5479.835604931858;5527.2538207341895;5533.335999680024;5540;5566.610337276352;6002.069679199731;6007.639541627516;6009.098635204754;6029.517638588903;6030.547708921848;5695.316562012585;5784.566157538202;5795.82612521625;5795.8551036224435;5873.177755114288;5886.941130588725;5888.028475301613;5891.49922256613;5894.233749612608;5902.852428689317;5826.533877289807;5834.883302333929;5863.413658634103;5865.178874957509;5865.794697185578;5794.304683438015;5866.2960444746905;5929.970350148222;5998.100284957204;6011.248593925759;4461.754603496839;4470.061287129739;4471.709338892962;4471.977640111779;4501.234790085693;4028.798560072043;4030.234255491447;4034.9394759078637;4047.0287131088326;4061.918761624751;5646.2942132233475;5695.601307908407;5704.258446402024;5735.139729040594;5759.136129580596;5878.617889837147;5989.31122920861;5998.25021872266;6015;6038;5651.2935883014625;5679;5679.119736440858;5705.286839145107;5709.65742055478;5036.093503169392;5055;5139.871503212449;5141.871453213699;5157.484251574812;6114.9694251529345;6119.785807496716;6132.264128304632;6143.170671959318;6153.446189842935;5756.683382414008;5880.735366908585;5919.31927828288;5929.347771745019;5933.910991335133;6174.753009879554;6177.444030037139;6182.938170618237;6193.876122477435;6207.720652570735;5729.255196824386;5735.8566035849435;5737.885242295048;5747.994101032386;5768.596198266249;5793.449622285876;5832.825015249549;5879.911801323069;5892.145638882424;6022.879542409215;5727.742251598771;5769.249997500466;5769.74036168365;5772.480476757112;5805.651660900359;5702.37273899879;5717.656940583578;5755.884882302415;5757.136429535436;5758.337791153904;5555.055640541037;5601.747921343468;5634.859128521656;5658.349289831722;5680.40355762639;5765.682887441231;5769.913451298318;5781.624194427349;5813.418658134153;5820.272465941757;5655.547556195576;5657.85855353603;5660.15097735343;5663.5469162467725;5674.233978413026 21:49:36 INFO - PROCESS | 29464 | |1;ext.html;597.9342272350041;663.9170103737033;665.2615596687677;667.9198496180456;675.1560549313357;40412.393814092815;41584.16831663365;41688.95777605558;41813.581864181346;41948.58051419485;44443.77778111113;44857.55142448583;45103.548964510344;45382.319265211045;45435.99999999992;8849.115088491148;9003.684871029514;9014.2788576914;9032.638694452247;9061.546922653873;8828.823423531525;8875.156860098281;8885.422447540921;8905.421147625384;8933.106689331047;9099.181073703365;9099.818003639923;9180.219681327095;9202.309826762987;9210.953945230298;8991.640334386617;8994.190522852941;9083.45499270046;9085.909140908572;9114.45313281202;9095.045020272843;9102.590383432762;9171.266298696088;9173.724788256362;9188.632454701803;44896;45155.32267015997;45250.321245181345;45289.320660190126;45298.320525192146;3872;3927.9214415711526;4036.1120553478186;4097.201045796071;4098.303288440972;33815.32369352624;34233.828830855935;34513.13717157066;34577.13557161091;34868;4578.473475550321;4634.930476042862;4638.814447422099;4656.837010704643;4672.976635116803;335990;339264;340093;341923;342586;39870;65251.67374163099;66097.66951165213;66187.66906165438;66242;40122.19755604872;40261;40347.59652403497;40455.1908961825;40473;17149.228284727218;17227.052512111703;17263.827361726475;17331.56671083233;17413.390531331344;14149.434022639185;14179.220142892034;14226.928865355814;14232.644183895485;14264.572062838131;9376.578053987587;9386.15524602789;9465.763355916157;9466;9490.098440648266;3556.270964452281;3666.1934374437583;3667.94498082529;3686.8340924658455;3688.7233457490797;1148.3569201247162;1149.1036991146918;1175.1186610042469;1186.4957393107895;1200.5677955935855;33682.20174732383;34076.31847363088;34179.82910085434;34391;34837.65162348395;1193.5524178433088;1196.7845787758113;1197.1560050164612;1273.8662440443804;1282.352412031918;16467.25897334599;17221.39725109615;17287.481375558753;17793.555161120818;17906.28374865017;516407;557164;565640;565968;568453;59287;59696.701516493005;60005.39994599998;60094.69952650209;60113.39886601165;1537.2313843078462;1537.9923100384651;1568.7882135911734;1595.7367034439187;1664.3592216996544;33880.66119338775;34568.48147277843;34705.82647086748;34829.65170348264;35136.82431587826;56734.14898776604;57093;57602.13596795968;57610;57650.4234957645;340713;344693;345168;346021;347138;31286.843565782026;31925.840370797996;31926.52110218395;31957.68042319547;51076;49778.502214978835;50874;51771.2234316478;52062.47937520577;55028.449715502335;43704.56295437005;45938;46042;46969;46994.53005469902;43983.560164397946;45007;45129.32306015347;45781.54218457906;45785.77107114443;45691.31463027991;45945.54054459413;46869.53130468788;47089;47411.288830666876;40103.59896400999;40235.3964690524;40239.19521609493;40553.59446405498;40767.1846563073;30621.846890765402;31029.379412411174;31272.5927333266;31568.422578726415;31995 21:49:36 INFO - PROCESS | 29464 | |2;jquery.html;422.8837069805802;424.57542457542456;428.12448542730135;433.82430115803095;440.1724757455982;18944.715829262554;19531.218751249933;19693.704594431078;20124.597508049832;20687.793122068775;17552.91223543884;18436.446906592824;19339.903300483515;19380.709289360635;19394.70907936382;5341;5465.699386533739;5471.534919531845;5481.616286859921;5483.451654834525;5114.948850511484;5429.348478182604;5451.236826844245;5467;5507.862303442406;5513.669179849202;5526.530244929186;5534.723263836812;5537.252470916435;5542.085555883295;5265.236540701615;5466;5485.040117979338;5486.698231597261;5502.532284755801;5294.894102117936;5315.734213289359;5323.627346085776;5349.839504814861;5359.169328754049;14163.15021098727;14247.715045699131;20900.47748806292;24222.273331799897;24400.26799196027;2443.645671377658;2474.3937735254976;2476.120977053138;2494.550980823451;2497.825152239344;10686;10982.560697572168;11076.501557429934;11081.556737730481;11237.83143252852;2058.289889987967;2086.4158035750015;2134.6691262854283;2154.6121698094335;2172.2397160993683;79365;83085.16914830834;83678.99999999939;84466.57766711188;84468;11635.127365447624;11847.881521184678;11921.105917056255;11974.341411222293;12521.93739031299;16516.091614961053;16601.66796664084;16740.748888766677;16790.32838686439;16819.15904204785;7709.38324934004;8818.206361427503;8833.381663283508;8958.238549723215;9085.227755640712;8704.738857834274;8750.86873696895;8757.430767000125;8788;8809.603567839462;4945.23348880924;5069.518395752398;5102.540771330598;5207.921881171786;5246.291750613695;2102.306238941176;2102.9263975760773;2128.6372126939395;2172.424307558498;2177.466520702438;1034.8085604163255;1040.921930855189;1051.574112484445;1112.2881355932209;1153.0948205658517;17460.301587936596;19001.619967600847;19098.502146541705;19176.904115479334;19813.20747170126;1070.5878236878857;1080.9405482698528;1108.2962318927378;1117.8938000889902;1124.7131981344705;10911.945440272748;11276.605318813803;11328.603498877499;11337.659870203906;11373.431328433546;519402;521295;522818;537596;544473;89440;92992;93660.59509107369;94219.52890235643;97273.97879816385;1101.9944900275607;1104.2932523185168;1104.469854469859;1105.9668209953713;1107.7009207513927;17059.371837879782;17205.655886882443;17476.650466990843;17663.38178163758;17682.5579360517;32742.83628581842;34674;37753.433698493995;40901.38647920224;41478.585214147475;48038.519614804805;49261.753691231315;49360.25959610537;49370.50629493659;49951;8663.220310172115;8723.65105395777;8745.650173993223;9036.141566551167;9066.682666106655;11699.766004679688;12824.615261541796;12871.678208044874;13045.282509462071;13074.411651475517;11070;11161.274517156684;11170.832437513282;11419.429028548706;11468.655940321803;10624.787504250025;10711.25021248505;10729.30259533159;10737.765157007045;10929.890701092887;11081.168912331446;11081.83377249358;11416.086713063101;11548.711282218012;11550.48022838991;8725.694600688945;8939.821203576023;8948.149925757043;8954.776130596527;9239.445633262027;8961.193492585699;10824.729381765519;11092.889071109186;11099.445027748741;11185.272957257752 21:49:36 INFO - PROCESS | 29464 | |3;mootools.html;1560.3134620766864;1587.388855290713;1798.8650851186164;1931.3626503253927;1951.5608987977698;7505.549667019983;8263.132371101023;8295.419320647557;8504.957475212623;8563.91436085639;8144.714934977277;8290.295324897375;8305.875411868827;8516.914830851689;8557.187067228604;2306.3657494189147;2333.3349995251356;2347.1432926981615;2351.0243249051623;2354.7409784923643;2367.147826782357;2380.33350661815;2381.5475059738596;2386.5465561543338;2402.0391843262746;2295.8737269450176;2310.7111611048617;2316.6872472216287;2318.7797159269844;2319.408550819547;2325.37214951962;2340.169239919821;2358.3868194269526;2359.6578496118054;2361.4686695493547;2370.620700687899;2373.2524254859663;2391.27066244797;2394.114177754225;2395.616701327797;8657.134286571356;8710.216080552716;8715.69495067676;8727.04002559718;8733.519656418894;5285.4450282720145;5411.323584551931;5427.538659213971;5433.26650902131;5437.564994800407;3244.561984132159;3247.3018301065385;3263.2494526259093;3303.587051618548;3326.7837590556537;1115.6262652011585;1182.3201659045997;1182.355616189179;1183.3373310945897;1194;11989.220700654516;12076.939615301955;12097;12171.817422738666;12204.389780510939;4375.518692943773;4450.06548624786;4543.522930092358;4596.402467679247;4628.4445866496235;9724.465154416575;9724.611015559298;9725.90274097264;9730.12428881404;9789.706308810748;5069.569086627678;5072.467390923974;5077.1876499759865;5085.923711144335;5095.617828662865;4646.558576935186;4765;4780.976095119502;4792.281157826354;4824.0834241494;3857.1128640412303;3893.805309734502;3967.385055316373;3969.6427321540623;3988.222296652125;1200.1299058182747;1200.9939950300193;1211.5517258614368;1212.3998620682642;1229.8155276708565;933.5752232734089;1042.6819819954835;1066.9413182274893;1069.668402795136;1092.1426680056118;5980.401959803985;6232.657203853741;6280.183576135073;6291;6376.936230637727;483.8572621076791;486.6593384630773;486.9732164730974;487.3956294195243;493.4153028661048;4136.875893723193;4155.97922010388;4163.75017498951;4194.119234960693;4198.832046718097;10225.539850706737;10476.319039262424;10496.842547361795;10548.261621686408;10570.947145264377;10358.741031474123;10360.481975901175;10379.688609341732;10400.68797936048;10426;7758.379329653614;7985.361171106298;8034.879476807852;8065.39509536787;8104.311133553596;5748.137779333133;5768.53851691872;5856.707164641836;5915.645061296336;5947.345791962967;9123.908760912305;9281.767955801159;9288.907110928803;9435.433873967584;9482.431054136774;9157.679481218125;9432;9497.145256926633;9523.809523809623;9573.808523829624;7598.354139898171;7721.382289416945;7880.921190788019;7913.366930645419;7930.325922296439;8420.368472364475;8443;8444.831103377775;8470.745877623682;8487.575621218792;7552.358049565849;7710.4217183710325;7765.650545725569;7829.804254893673;7835.960820196091;7538.773836784905;7664.73173438927;7723.189065148079;7738.187490313661;7744;6979;6983.3714965653335;7010.018597396471;7027.4729395294535;7037.507374483737;5348.1442969124755;5358;5449.836504904859;5475;5478.123500240102;5284.418713941386;5385.649932754359;5503.174523821299;5520.613557050968;5589.748461319332 21:49:36 INFO - PROCESS | 29464 | |4;prototype.html;363.863551168312;365.6763764068799;368.09265161377215;369.0312928562523;371.40760487023186;6576.605403675783;6623.2383275923285;6642.402183803462;6647.700853461593;6683.131192944922;6476.74093036278;6530.510211734115;6533.771318003871;6539.117219175409;6550.475961923046;4799.3520874682;4903.8774030649165;4947.084789313988;4966.956939042805;4981.601471882259;4917.704937703749;4927.6550641455115;4936.605071594264;4954.281629163769;4958.380202474691;4891.14404979129;4904.558589726924;4906.945006823532;4914.901701965959;4916.950830491703;4897.1674815281485;4914.213725803872;4917.311576379309;4930.506949305077;4943.555080042777;4290.099079193373;4290.163418133465;4290.957090429087;4292.527821939584;4299.699021068526;6034.456898879122;6218.875622487571;6269.341719119518;6277.435030847246;6283.654399008053;5717.828465146053;5728.198052272685;5733.226014488033;5760.510356619692;5770.2498675171955;6946.37482626566;6954.513184077116;6960.617166055865;6962.060121883582;6967.930320696779;4750.976245118787;4754.263089221176;4765.856194513327;4767.284907263904;4769.642276829217;7232.457565682595;7237.3848222901115;7284.344409003215;7289.671964761598;7306.196318404971;4853.49038350968;4866.562009419169;4964.652474326835;4967.652264341534;5028.145215313405;5046.59627229828;5069.619778516626;5083.974580127076;5086.237064440364;5087.949120508822;5822.796202132905;5826.446487583673;5830.41695830422;5832.862591794644;5835.533157347487;7007.5795452272005;7007.964960175166;7008.194057683329;7010.439164866901;7014.088168538057;1784.0811981829368;1811.80975997521;1815;1815.9364422245158;1819.954501137482;1159.8086315757973;1186.3356520348632;1188.8811118888043;1201.2192075151222;1235.518147922311;6827.21487028998;6855.760048398282;6863.6568171591225;6867.622280774505;6868.896966545506;7089.468289878279;7236.493445458873;7236.493445458873;7256.310650488301;7256.528325658815;6916.100906882073;6920.550164239308;6927.618980955995;6942.444604431735;6947.687354069079;3906.453096566483;3923.843046278117;3928.1750832325533;3934.9606503935165;3939.074317535377;7602.543847369176;7621.618919054025;7630.7329243477325;7636.80907977305;7653.770386888402;7254.202037775841;7361.631918404166;7443.106827180773;7444.627768611548;7451.068616422947;7532.698692052366;7547.886781698279;7555.508891922117;7563.810904727316;7590.988388077164;7268.164161121539;7270.2366251544945;7277.526960747747;7281.453890958305;7282.453815963718;7141.428685705235;7146.071010768567;7177.1746249182015;7180.066591343092;7184.533005354635;7498.925010749823;7585;7624.80937976555;7636.389088872988;7657.46397752152;4873.658843881036;4876.829310974099;4914.189158788759;4915.43472500667;4925.950740492693;7247.202807691256;7289.942958271127;7292.708291668274;7296.96351518239;7303.671334790053;6988.79033628992;7010;7060.71757129709;7068;7068.151821781419;6859.965700171467;7052.294770523112;7054.506184567031;7057;7061.505694601328;6846.657667116525;6886.965565172142;6894.172699276119;6916.930830691628;6923;7004.824879378057;7058.082449281766;7073.080499535027;7074.893876591752;7098.467614929004;6860.0738900247825;6888.48336374784;6899.068625735462;6901.861962760616;6913.27168207948 21:49:36 INFO - PROCESS | 29464 | |5;yui.html;869.0093293645245;894.494610545042;918.5085979001236;947.4836214263225;958.7555173430774;15078.396864125436;15263.236838158075;15275.236238188101;15279.159646219454;15333.156676382794;14233.928830355833;14772.852271477283;14800.555983320492;14804;14804.851951480481;897.7396554999042;902.336782464891;903.8960519540238;905.4024343933006;924.7179610218881;925.9629614815399;928.4150984879517;931.2782593490032;932.1936524905941;932.4125800745521;950.8288508068545;952.7475219066953;954.1460392948342;954.1651055326589;959.9232061435104;947.8199142162968;948.7960088580953;949.2121539122512;953.0659953245857;959.3668179001826;706.8374273916977;707.9769732736147;708.9858202835966;710.989335159973;711.8754218011827;14794;14828.70342593153;14863.925680371529;14864.777028344584;14910.105393676415;14535.127892326494;14733.778993315109;14745.705085898222;14797.704045919128;14822.62943426423;12027.218230815057;12201.816972745415;12205.93897030518;12271.815922761165;12553.93723031388;4031.395290706388;4044.272031034442;4110.609492098276;4146.170765846843;4247.638950689196;56808.71595642037;57843;58383.41616583822;59055.40944590585;59102.40897591055;11599.422914282837;11674.474648640831;11704.941475292739;11720.472578733978;11824.11319151067;21014;21014.15943362279;21037.263695770576;21067.262645807325;21072.789272107388;8775.868361974575;8842.159994800484;8851.159139881702;8861.867071993925;8946.105389461001;4905.386826646669;4915.4592994771265;4964.925526117111;4978.178600530872;5121.564667003272;3602.5676918769914;3642.1987162824134;3648.0332711831384;3648.85404583819;3654.872079477259;663.1909070933453;665.4210836572116;714.3249629100554;719.6114098386918;724.9275072492813;762.4281788658507;781.1415769543418;794.0550744613892;807.7576726981882;826.9131741167212;9514.666986655573;9963;10326.535305911251;10419.426931518688;10426.478676066317;768.1780494870435;793.6782832006409;803.0323460230409;820.7332616899531;845.159066728609;4499.820007199675;4861.781219845116;4940.209566469348;4967.056259310792;4999.325091112726;61507.38492615106;62723.6863815678;62766.37233627697;63152.36847631465;63560.68219658872;55684;56046.439535604935;56087.43912560904;56140.71929640407;56528.15207771887;50700.49299506958;50921.74539127281;51293.487065129615;51400;51650.48349516457;10248.487575621339;10339.948300258751;10350.741231469274;10376.27366084397;10516.369017858953;36968.63031369652;37014.44478332881;37817.81091094527;38299;38499;44936.32595511135;47329;48474.515254847;49489.50510494849;49898.750506247234;12732.427040783;12888.484460621845;12947;13020;13160.670983225496;13437.328133593477;13448.798268025792;13553.051286409856;14059.22674252926;15350.385984560493;13718.108322958975;13840.792388113985;13942.930285348508;14219.360128794022;14347.784783228468;13707.109037912503;13756.65608359799;13895.374708137955;14014.369353379327;14031.228282444563;13468.86531134676;13550.39023243976;13767.586972390844;13803.861961380258;13875.72248555003;11200.21598488098;11352.205345625727;11421.31472111676;11492.885071149181;11515.654530364103;10462.424566648908;10682.198835087554;11485.885141148816;11506.964373206452;11611.187216894736 21:49:36 INFO - PROCESS | 29464 | __end_tp_report 21:49:36 INFO - PROCESS | 29464 | __start_cc_report 21:49:36 INFO - PROCESS | 29464 | _x_x_mozilla_cycle_collect,318 21:49:36 INFO - PROCESS | 29464 | __end_cc_report 21:49:36 INFO - PROCESS | 29464 | __startTimestamp1467607776682__endTimestamp 21:49:36 INFO - PROCESS | 29464 | 21:49:36 INFO - PROCESS | 29464 | ------- Summary: start ------- 21:49:36 INFO - PROCESS | 29464 | Number of tests: 6 21:49:36 INFO - PROCESS | 29464 | 21:49:36 INFO - PROCESS | 29464 | [#0] dojo.html Cycles:165 Average:5498.43 Median:5704.26 stddev:604.69 (10.6%) stddev-sans-first:581.34 21:49:36 INFO - PROCESS | 29464 | Values: 3296.3 3348.5 3352.2 3377.9 3389.9 5544.7 5561.3 5578.5 5599.8 5607.7 5502.6 5571.2 5572.1 5577.9 5581.0 4982.7 5053.8 5094.7 5097.8 5104.9 4904.7 5050.7 5136.4 5182.7 5206.4 5019.2 5048.1 5085.7 5112.2 5151.3 5061.7 5074.7 5077.7 5091.1 5109.7 4876.9 4997.0 5010.2 5040.7 5065.1 5253.8 5309.2 5331.7 5335.7 5395.7 5917.6 5933.0 5940.2 5954.2 5971.0 5807.0 5957.2 5973.8 5991.2 5997.6 5479.8 5527.3 5533.3 5540.0 5566.6 6002.1 6007.6 6009.1 6029.5 6030.5 5695.3 5784.6 5795.8 5795.9 5873.2 5886.9 5888.0 5891.5 5894.2 5902.9 5826.5 5834.9 5863.4 5865.2 5865.8 5794.3 5866.3 5930.0 5998.1 6011.2 4461.8 4470.1 4471.7 4472.0 4501.2 4028.8 4030.2 4034.9 4047.0 4061.9 5646.3 5695.6 5704.3 5735.1 5759.1 5878.6 5989.3 5998.3 6015.0 6038.0 5651.3 5679.0 5679.1 5705.3 5709.7 5036.1 5055.0 5139.9 5141.9 5157.5 6115.0 6119.8 6132.3 6143.2 6153.4 5756.7 5880.7 5919.3 5929.3 5933.9 6174.8 6177.4 6182.9 6193.9 6207.7 5729.3 5735.9 5737.9 5748.0 5768.6 5793.4 5832.8 5879.9 5892.1 6022.9 5727.7 5769.2 5769.7 5772.5 5805.7 5702.4 5717.7 5755.9 5757.1 5758.3 5555.1 5601.7 5634.9 5658.3 5680.4 5765.7 5769.9 5781.6 5813.4 5820.3 5655.5 5657.9 5660.2 5663.5 5674.2 21:49:36 INFO - PROCESS | 29464 | 21:49:36 INFO - PROCESS | 29464 | [#1] ext.html Cycles:180 Average:58610.14 Median:43844.06 stddev:112715.52 (257.1%) stddev-sans-first:112947.56 21:49:36 INFO - PROCESS | 29464 | Values: 597.9 663.9 665.3 667.9 675.2 40412.4 41584.2 41689.0 41813.6 41948.6 44443.8 44857.6 45103.5 45382.3 45436.0 8849.1 9003.7 9014.3 9032.6 9061.5 8828.8 8875.2 8885.4 8905.4 8933.1 9099.2 9099.8 9180.2 9202.3 9211.0 8991.6 8994.2 9083.5 9085.9 9114.5 9095.0 9102.6 9171.3 9173.7 9188.6 44896.0 45155.3 45250.3 45289.3 45298.3 3872.0 3927.9 4036.1 4097.2 4098.3 33815.3 34233.8 34513.1 34577.1 34868.0 4578.5 4634.9 4638.8 4656.8 4673.0 335990.0 339264.0 340093.0 341923.0 342586.0 39870.0 65251.7 66097.7 66187.7 66242.0 40122.2 40261.0 40347.6 40455.2 40473.0 17149.2 17227.1 17263.8 17331.6 17413.4 14149.4 14179.2 14226.9 14232.6 14264.6 9376.6 9386.2 9465.8 9466.0 9490.1 3556.3 3666.2 3667.9 3686.8 3688.7 1148.4 1149.1 1175.1 1186.5 1200.6 33682.2 34076.3 34179.8 34391.0 34837.7 1193.6 1196.8 1197.2 1273.9 1282.4 16467.3 17221.4 17287.5 17793.6 17906.3 516407.0 557164.0 565640.0 565968.0 568453.0 59287.0 59696.7 60005.4 60094.7 60113.4 1537.2 1538.0 1568.8 1595.7 1664.4 33880.7 34568.5 34705.8 34829.7 35136.8 56734.1 57093.0 57602.1 57610.0 57650.4 340713.0 344693.0 345168.0 346021.0 347138.0 31286.8 31925.8 31926.5 31957.7 51076.0 49778.5 50874.0 51771.2 52062.5 55028.4 43704.6 45938.0 46042.0 46969.0 46994.5 43983.6 45007.0 45129.3 45781.5 45785.8 45691.3 45945.5 46869.5 47089.0 47411.3 40103.6 40235.4 40239.2 40553.6 40767.2 30621.8 31029.4 31272.6 31568.4 31995.0 21:49:36 INFO - PROCESS | 29464 | 21:49:36 INFO - PROCESS | 29464 | [#2] jquery.html Cycles:180 Average:29790.33 Median:2174.95 stddev:87042.55 (4002.1%) stddev-sans-first:87258.79 21:49:36 INFO - PROCESS | 29464 | Values: 422.9 424.6 428.1 433.8 440.2 18944.7 19531.2 19693.7 20124.6 20687.8 17552.9 18436.4 19339.9 19380.7 19394.7 5341.0 5465.7 5471.5 5481.6 5483.5 5114.9 5429.3 5451.2 5467.0 5507.9 5513.7 5526.5 5534.7 5537.3 5542.1 5265.2 5466.0 5485.0 5486.7 5502.5 5294.9 5315.7 5323.6 5349.8 5359.2 14163.2 14247.7 20900.5 24222.3 24400.3 2443.6 2474.4 2476.1 2494.6 2497.8 10686.0 10982.6 11076.5 11081.6 11237.8 2058.3 2086.4 2134.7 2154.6 2172.2 79365.0 83085.2 83679.0 84466.6 84468.0 11635.1 11847.9 11921.1 11974.3 12521.9 16516.1 16601.7 16740.7 16790.3 16819.2 7709.4 8818.2 8833.4 8958.2 9085.2 8704.7 8750.9 8757.4 8788.0 8809.6 4945.2 5069.5 5102.5 5207.9 5246.3 2102.3 2102.9 2128.6 2172.4 2177.5 1034.8 1040.9 1051.6 1112.3 1153.1 17460.3 19001.6 19098.5 19176.9 19813.2 1070.6 1080.9 1108.3 1117.9 1124.7 10911.9 11276.6 11328.6 11337.7 11373.4 519402.0 521295.0 522818.0 537596.0 544473.0 89440.0 92992.0 93660.6 94219.5 97274.0 1102.0 1104.3 1104.5 1106.0 1107.7 17059.4 17205.7 17476.7 17663.4 17682.6 32742.8 34674.0 37753.4 40901.4 41478.6 48038.5 49261.8 49360.3 49370.5 49951.0 8663.2 8723.7 8745.7 9036.1 9066.7 11699.8 12824.6 12871.7 13045.3 13074.4 11070.0 11161.3 11170.8 11419.4 11468.7 10624.8 10711.3 10729.3 10737.8 10929.9 11081.2 11081.8 11416.1 11548.7 11550.5 8725.7 8939.8 8948.1 8954.8 9239.4 8961.2 10824.7 11092.9 11099.4 11185.3 21:49:36 INFO - PROCESS | 29464 | 21:49:36 INFO - PROCESS | 29464 | [#3] mootools.html Cycles:180 Average:5693.85 Median:5074.83 stddev:3147.15 (62.0%) stddev-sans-first:3140.65 21:49:36 INFO - PROCESS | 29464 | Values: 1560.3 1587.4 1798.9 1931.4 1951.6 7505.5 8263.1 8295.4 8505.0 8563.9 8144.7 8290.3 8305.9 8516.9 8557.2 2306.4 2333.3 2347.1 2351.0 2354.7 2367.1 2380.3 2381.5 2386.5 2402.0 2295.9 2310.7 2316.7 2318.8 2319.4 2325.4 2340.2 2358.4 2359.7 2361.5 2370.6 2373.3 2391.3 2394.1 2395.6 8657.1 8710.2 8715.7 8727.0 8733.5 5285.4 5411.3 5427.5 5433.3 5437.6 3244.6 3247.3 3263.2 3303.6 3326.8 1115.6 1182.3 1182.4 1183.3 1194.0 11989.2 12076.9 12097.0 12171.8 12204.4 4375.5 4450.1 4543.5 4596.4 4628.4 9724.5 9724.6 9725.9 9730.1 9789.7 5069.6 5072.5 5077.2 5085.9 5095.6 4646.6 4765.0 4781.0 4792.3 4824.1 3857.1 3893.8 3967.4 3969.6 3988.2 1200.1 1201.0 1211.6 1212.4 1229.8 933.6 1042.7 1066.9 1069.7 1092.1 5980.4 6232.7 6280.2 6291.0 6376.9 483.9 486.7 487.0 487.4 493.4 4136.9 4156.0 4163.8 4194.1 4198.8 10225.5 10476.3 10496.8 10548.3 10570.9 10358.7 10360.5 10379.7 10400.7 10426.0 7758.4 7985.4 8034.9 8065.4 8104.3 5748.1 5768.5 5856.7 5915.6 5947.3 9123.9 9281.8 9288.9 9435.4 9482.4 9157.7 9432.0 9497.1 9523.8 9573.8 7598.4 7721.4 7880.9 7913.4 7930.3 8420.4 8443.0 8444.8 8470.7 8487.6 7552.4 7710.4 7765.7 7829.8 7836.0 7538.8 7664.7 7723.2 7738.2 7744.0 6979.0 6983.4 7010.0 7027.5 7037.5 5348.1 5358.0 5449.8 5475.0 5478.1 5284.4 5385.6 5503.2 5520.6 5589.7 21:49:36 INFO - PROCESS | 29464 | 21:49:36 INFO - PROCESS | 29464 | [#4] prototype.html Cycles:180 Average:5859.75 Median:6836.94 stddev:1790.88 (26.2%) stddev-sans-first:1747.76 21:49:36 INFO - PROCESS | 29464 | Values: 363.9 365.7 368.1 369.0 371.4 6576.6 6623.2 6642.4 6647.7 6683.1 6476.7 6530.5 6533.8 6539.1 6550.5 4799.4 4903.9 4947.1 4967.0 4981.6 4917.7 4927.7 4936.6 4954.3 4958.4 4891.1 4904.6 4906.9 4914.9 4917.0 4897.2 4914.2 4917.3 4930.5 4943.6 4290.1 4290.2 4291.0 4292.5 4299.7 6034.5 6218.9 6269.3 6277.4 6283.7 5717.8 5728.2 5733.2 5760.5 5770.2 6946.4 6954.5 6960.6 6962.1 6967.9 4751.0 4754.3 4765.9 4767.3 4769.6 7232.5 7237.4 7284.3 7289.7 7306.2 4853.5 4866.6 4964.7 4967.7 5028.1 5046.6 5069.6 5084.0 5086.2 5087.9 5822.8 5826.4 5830.4 5832.9 5835.5 7007.6 7008.0 7008.2 7010.4 7014.1 1784.1 1811.8 1815.0 1815.9 1820.0 1159.8 1186.3 1188.9 1201.2 1235.5 6827.2 6855.8 6863.7 6867.6 6868.9 7089.5 7236.5 7236.5 7256.3 7256.5 6916.1 6920.6 6927.6 6942.4 6947.7 3906.5 3923.8 3928.2 3935.0 3939.1 7602.5 7621.6 7630.7 7636.8 7653.8 7254.2 7361.6 7443.1 7444.6 7451.1 7532.7 7547.9 7555.5 7563.8 7591.0 7268.2 7270.2 7277.5 7281.5 7282.5 7141.4 7146.1 7177.2 7180.1 7184.5 7498.9 7585.0 7624.8 7636.4 7657.5 4873.7 4876.8 4914.2 4915.4 4926.0 7247.2 7289.9 7292.7 7297.0 7303.7 6988.8 7010.0 7060.7 7068.0 7068.2 6860.0 7052.3 7054.5 7057.0 7061.5 6846.7 6887.0 6894.2 6916.9 6923.0 7004.8 7058.1 7073.1 7074.9 7098.5 6860.1 6888.5 6899.1 6901.9 6913.3 21:49:36 INFO - PROCESS | 29464 | 21:49:36 INFO - PROCESS | 29464 | [#5] yui.html Cycles:180 Average:15671.74 Median:4128.39 stddev:17665.60 (427.9%) stddev-sans-first:17680.18 21:49:36 INFO - PROCESS | 29464 | Values: 869.0 894.5 918.5 947.5 958.8 15078.4 15263.2 15275.2 15279.2 15333.2 14233.9 14772.9 14800.6 14804.0 14804.9 897.7 902.3 903.9 905.4 924.7 926.0 928.4 931.3 932.2 932.4 950.8 952.7 954.1 954.2 959.9 947.8 948.8 949.2 953.1 959.4 706.8 708.0 709.0 711.0 711.9 14794.0 14828.7 14863.9 14864.8 14910.1 14535.1 14733.8 14745.7 14797.7 14822.6 12027.2 12201.8 12205.9 12271.8 12553.9 4031.4 4044.3 4110.6 4146.2 4247.6 56808.7 57843.0 58383.4 59055.4 59102.4 11599.4 11674.5 11704.9 11720.5 11824.1 21014.0 21014.2 21037.3 21067.3 21072.8 8775.9 8842.2 8851.2 8861.9 8946.1 4905.4 4915.5 4964.9 4978.2 5121.6 3602.6 3642.2 3648.0 3648.9 3654.9 663.2 665.4 714.3 719.6 724.9 762.4 781.1 794.1 807.8 826.9 9514.7 9963.0 10326.5 10419.4 10426.5 768.2 793.7 803.0 820.7 845.2 4499.8 4861.8 4940.2 4967.1 4999.3 61507.4 62723.7 62766.4 63152.4 63560.7 55684.0 56046.4 56087.4 56140.7 56528.2 50700.5 50921.7 51293.5 51400.0 51650.5 10248.5 10339.9 10350.7 10376.3 10516.4 36968.6 37014.4 37817.8 38299.0 38499.0 44936.3 47329.0 48474.5 49489.5 49898.8 12732.4 12888.5 12947.0 13020.0 13160.7 13437.3 13448.8 13553.1 14059.2 15350.4 13718.1 13840.8 13942.9 14219.4 14347.8 13707.1 13756.7 13895.4 14014.4 14031.2 13468.9 13550.4 13767.6 13803.9 13875.7 11200.2 11352.2 11421.3 11492.9 11515.7 10462.4 10682.2 11485.9 11507.0 11611.2 21:49:36 INFO - PROCESS | 29464 | -------- Summary: end -------- 21:49:36 INFO - PROCESS | 29464 | 21:49:37 INFO - TEST-INFO | 29464: exit 0 21:49:37 INFO - TEST-OK | dromaeo_css | took 1108978ms 21:49:37 INFO - TEST-START | kraken 21:49:37 INFO - operating with platform_type : linux_ 21:49:37 INFO - Initialising browser for kraken test... 21:49:37 INFO - TEST-INFO | started process 29703 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo7a2tl/profile http://localhost:40995/getInfo.html) 21:49:41 INFO - PROCESS | 29703 | 21:49:41 INFO - PROCESS | 29703 | (process:29778): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:49:41 INFO - PROCESS | 29703 | __metrics Screen width/height:1600/1200 21:49:41 INFO - PROCESS | 29703 | colorDepth:24 21:49:41 INFO - PROCESS | 29703 | Browser inner width/height: 1024/697 21:49:41 INFO - PROCESS | 29703 | __metrics 21:49:42 ERROR - PROCESS | 29703 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 21:49:43 INFO - TEST-INFO | 29703: exit 0 21:49:43 INFO - Browser initialized. 21:49:43 INFO - Running cycle 1/1 for kraken test... 21:49:43 INFO - TEST-INFO | started process 29828 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo7a2tl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:44 INFO - PROCESS | 29828 | 21:49:44 INFO - PROCESS | 29828 | (process:29898): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:49:54 INFO - PROCESS | 29828 | RSS: Main: 151117824 21:49:54 INFO - PROCESS | 29828 | 21:49:57 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40995/tests/kraken/driver.html?testName=audio-beat-detection) 21:49:58 INFO - PROCESS | 29828 | RSS: Main: 156356608 21:49:58 INFO - PROCESS | 29828 | 21:50:00 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40995/tests/kraken/driver.html?testName=audio-dft) 21:50:01 INFO - PROCESS | 29828 | RSS: Main: 156200960 21:50:01 INFO - PROCESS | 29828 | 21:50:04 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40995/tests/kraken/driver.html?testName=audio-fft) 21:50:05 INFO - PROCESS | 29828 | RSS: Main: 156569600 21:50:05 INFO - PROCESS | 29828 | 21:50:07 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40995/tests/kraken/driver.html?testName=audio-oscillator) 21:50:07 INFO - PROCESS | 29828 | RSS: Main: 156344320 21:50:07 INFO - PROCESS | 29828 | 21:50:09 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40995/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:50:10 INFO - PROCESS | 29828 | RSS: Main: 156327936 21:50:10 INFO - PROCESS | 29828 | 21:50:13 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40995/tests/kraken/driver.html?testName=imaging-darkroom) 21:50:14 INFO - PROCESS | 29828 | RSS: Main: 156794880 21:50:14 INFO - PROCESS | 29828 | 21:50:17 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40995/tests/kraken/driver.html?testName=imaging-desaturate) 21:50:18 INFO - PROCESS | 29828 | RSS: Main: 156917760 21:50:18 INFO - PROCESS | 29828 | 21:50:20 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40995/tests/kraken/driver.html?testName=json-parse-financial) 21:50:21 INFO - PROCESS | 29828 | RSS: Main: 156364800 21:50:21 INFO - PROCESS | 29828 | 21:50:23 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40995/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:50:23 INFO - PROCESS | 29828 | RSS: Main: 156078080 21:50:23 INFO - PROCESS | 29828 | 21:50:25 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:50:25 INFO - PROCESS | 29828 | RSS: Main: 156192768 21:50:25 INFO - PROCESS | 29828 | 21:50:27 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:50:28 INFO - PROCESS | 29828 | RSS: Main: 156528640 21:50:28 INFO - PROCESS | 29828 | 21:50:31 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:50:31 INFO - PROCESS | 29828 | RSS: Main: 156913664 21:50:31 INFO - PROCESS | 29828 | 21:50:34 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:50:34 INFO - PROCESS | 29828 | RSS: Main: 157073408 21:50:34 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40995/tests/kraken/driver.html?testName=ai-astar) 21:50:36 INFO - PROCESS | 29828 | RSS: Main: 158650368 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | __start_tp_report 21:50:36 INFO - PROCESS | 29828 | _x_x_mozilla_page_load 21:50:36 INFO - PROCESS | 29828 | _x_x_mozilla_page_load_details 21:50:36 INFO - PROCESS | 29828 | |i|pagename|runs| 21:50:36 INFO - PROCESS | 29828 | |0;ai-astar;107;111;102;110;113;112;101;114;109;112 21:50:36 INFO - PROCESS | 29828 | |1;audio-beat-detection;157;126;159;172;134;149;151;126;176;134 21:50:36 INFO - PROCESS | 29828 | |2;audio-dft;165;162;162;163;162;162;161;164;162;162 21:50:36 INFO - PROCESS | 29828 | |3;audio-fft;76;76;73;175;75;76;74;76;165;76 21:50:36 INFO - PROCESS | 29828 | |4;audio-oscillator;93;94;152;91;94;93;153;93;93;147 21:50:36 INFO - PROCESS | 29828 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;116;116;117 21:50:36 INFO - PROCESS | 29828 | |6;imaging-darkroom;147;147;148;148;146;147;148;147;146;147 21:50:36 INFO - PROCESS | 29828 | |7;imaging-desaturate;95;95;95;96;95;94;94;96;95;95 21:50:36 INFO - PROCESS | 29828 | |8;json-parse-financial;60;62;58;56;58;56;55;81;54;53 21:50:36 INFO - PROCESS | 29828 | |9;json-stringify-tinderbox;57;58;58;58;57;57;58;57;58;58 21:50:36 INFO - PROCESS | 29828 | |10;stanford-crypto-aes;80;73;74;74;74;75;76;73;81;73 21:50:36 INFO - PROCESS | 29828 | |11;stanford-crypto-ccm;112;120;114;115;116;118;122;113;129;129 21:50:36 INFO - PROCESS | 29828 | |12;stanford-crypto-pbkdf2;156;157;160;158;157;160;157;163;156;159 21:50:36 INFO - PROCESS | 29828 | |13;stanford-crypto-sha256-iterative;70;69;67;68;68;69;68;67;67;68 21:50:36 INFO - PROCESS | 29828 | __end_tp_report 21:50:36 INFO - PROCESS | 29828 | __start_cc_report 21:50:36 INFO - PROCESS | 29828 | _x_x_mozilla_cycle_collect,826 21:50:36 INFO - PROCESS | 29828 | __end_cc_report 21:50:36 INFO - PROCESS | 29828 | __startTimestamp1467607836854__endTimestamp 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | ------- Summary: start ------- 21:50:36 INFO - PROCESS | 29828 | Number of tests: 14 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#0] ai-astar Cycles:10 Average:109.10 Median:111.50 stddev:4.48 (4.0%) stddev-sans-first:4.69 21:50:36 INFO - PROCESS | 29828 | Values: 107.0 111.0 102.0 110.0 113.0 112.0 101.0 114.0 109.0 112.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#1] audio-beat-detection Cycles:10 Average:148.40 Median:154.00 stddev:18.04 (11.7%) stddev-sans-first:18.87 21:50:36 INFO - PROCESS | 29828 | Values: 157.0 126.0 159.0 172.0 134.0 149.0 151.0 126.0 176.0 134.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.00 stddev:1.18 (0.7%) stddev-sans-first:0.83 21:50:36 INFO - PROCESS | 29828 | Values: 165.0 162.0 162.0 163.0 162.0 162.0 161.0 164.0 162.0 162.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#3] audio-fft Cycles:10 Average:94.20 Median:76.00 stddev:40.03 (52.7%) stddev-sans-first:41.92 21:50:36 INFO - PROCESS | 29828 | Values: 76.0 76.0 73.0 175.0 75.0 76.0 74.0 76.0 165.0 76.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#4] audio-oscillator Cycles:10 Average:110.30 Median:93.00 stddev:27.91 (30.0%) stddev-sans-first:28.89 21:50:36 INFO - PROCESS | 29828 | Values: 93.0 94.0 152.0 91.0 94.0 93.0 153.0 93.0 93.0 147.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 21:50:36 INFO - PROCESS | 29828 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 21:50:36 INFO - PROCESS | 29828 | Values: 147.0 147.0 148.0 148.0 146.0 147.0 148.0 147.0 146.0 147.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 21:50:36 INFO - PROCESS | 29828 | Values: 95.0 95.0 95.0 96.0 95.0 94.0 94.0 96.0 95.0 95.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:58.00 stddev:8.10 (14.0%) stddev-sans-first:8.58 21:50:36 INFO - PROCESS | 29828 | Values: 60.0 62.0 58.0 56.0 58.0 56.0 55.0 81.0 54.0 53.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#9] json-stringify-tinderbox Cycles:10 Average:57.60 Median:58.00 stddev:0.52 (0.9%) stddev-sans-first:0.50 21:50:36 INFO - PROCESS | 29828 | Values: 57.0 58.0 58.0 58.0 57.0 57.0 58.0 57.0 58.0 58.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:74.50 stddev:2.91 (3.9%) stddev-sans-first:2.54 21:50:36 INFO - PROCESS | 29828 | Values: 80.0 73.0 74.0 74.0 74.0 75.0 76.0 73.0 81.0 73.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#11] stanford-crypto-ccm Cycles:10 Average:118.80 Median:119.00 stddev:6.20 (5.2%) stddev-sans-first:6.06 21:50:36 INFO - PROCESS | 29828 | Values: 112.0 120.0 114.0 115.0 116.0 118.0 122.0 113.0 129.0 129.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.30 Median:158.50 stddev:2.21 (1.4%) stddev-sans-first:2.19 21:50:36 INFO - PROCESS | 29828 | Values: 156.0 157.0 160.0 158.0 157.0 160.0 157.0 163.0 156.0 159.0 21:50:36 INFO - PROCESS | 29828 | 21:50:36 INFO - PROCESS | 29828 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:0.78 21:50:36 INFO - PROCESS | 29828 | Values: 70.0 69.0 67.0 68.0 68.0 69.0 68.0 67.0 67.0 68.0 21:50:36 INFO - PROCESS | 29828 | -------- Summary: end -------- 21:50:36 INFO - PROCESS | 29828 | 21:50:37 INFO - TEST-INFO | 29828: exit 0 21:50:37 INFO - TEST-OK | kraken | took 60389ms 21:50:37 INFO - SUITE-END | took 1169s 21:50:38 INFO - Completed test suite (00:19:29) 21:50:38 INFO - javascript benchmark 21:50:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3296.274819539701, 3348.5479460272854, 3352.195473086459, 3377.864885404584, 3389.9322013559713, 5544.708708048913, 5561.33264008319, 5578.497935185833, 5599.776008959647, 5607.7476513556885, 5502.614816962814, 5571.192177134323, 5572.0527510323245, 5577.8605534861645, 5581.0, 4982.676126051804, 5053.77258023388, 5094.7197904115255, 5097.770600322994, 5104.872378190556, 4904.705717656952, 5050.7222102784335, 5136.357955255593, 5182.663126896739, 5206.375234971809, 5019.247112933052, 5048.141815891289, 5085.745712714368, 5112.156494178456, 5151.253068305094, 5061.6962982221, 5074.720890351029, 5077.669951453144, 5091.083604951107, 5109.718965456898, 4876.853694389139, 4996.975015124937, 5010.173321401964, 5040.6723562968655, 5065.11360511909, 5253.789848406059, 5309.203619457074, 5331.680099194022, 5335.733213339357, 5395.7032363220005, 5917.556183286228, 5932.9703351483395, 5940.227770389822, 5954.2259506264345, 5971.044632858721, 5807.0, 5957.165996760457, 5973.8207853764025, 5991.161237426763, 5997.610155339932, 5479.835604931858, 5527.2538207341895, 5533.335999680024, 5540.0, 5566.610337276352, 6002.069679199731, 6007.639541627516, 6009.098635204754, 6029.517638588903, 6030.547708921848, 5695.316562012585, 5784.566157538202, 5795.82612521625, 5795.8551036224435, 5873.177755114288, 5886.941130588725, 5888.028475301613, 5891.49922256613, 5894.233749612608, 5902.852428689317, 5826.533877289807, 5834.883302333929, 5863.413658634103, 5865.178874957509, 5865.794697185578, 5794.304683438015, 5866.2960444746905, 5929.970350148222, 5998.100284957204, 6011.248593925759, 4461.754603496839, 4470.061287129739, 4471.709338892962, 4471.977640111779, 4501.234790085693, 4028.798560072043, 4030.234255491447, 4034.9394759078637, 4047.0287131088326, 4061.918761624751, 5646.2942132233475, 5695.601307908407, 5704.258446402024, 5735.139729040594, 5759.136129580596, 5878.617889837147, 5989.31122920861, 5998.25021872266, 6015.0, 6038.0, 5651.2935883014625, 5679.0, 5679.119736440858, 5705.286839145107, 5709.65742055478, 5036.093503169392, 5055.0, 5139.871503212449, 5141.871453213699, 5157.484251574812, 6114.9694251529345, 6119.785807496716, 6132.264128304632, 6143.170671959318, 6153.446189842935, 5756.683382414008, 5880.735366908585, 5919.31927828288, 5929.347771745019, 5933.910991335133, 6174.753009879554, 6177.444030037139, 6182.938170618237, 6193.876122477435, 6207.720652570735, 5729.255196824386, 5735.8566035849435, 5737.885242295048, 5747.994101032386, 5768.596198266249, 5793.449622285876, 5832.825015249549, 5879.911801323069, 5892.145638882424, 6022.879542409215, 5727.742251598771, 5769.249997500466, 5769.74036168365, 5772.480476757112, 5805.651660900359, 5702.37273899879, 5717.656940583578, 5755.884882302415, 5757.136429535436, 5758.337791153904, 5555.055640541037, 5601.747921343468, 5634.859128521656, 5658.349289831722, 5680.40355762639, 5765.682887441231, 5769.913451298318, 5781.624194427349, 5813.418658134153, 5820.272465941757, 5655.547556195576, 5657.85855353603, 5660.15097735343, 5663.5469162467725, 5674.233978413026], "value": 5459.5483056558705, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.9342272350041, 663.9170103737033, 665.2615596687677, 667.9198496180456, 675.1560549313357, 40412.393814092815, 41584.16831663365, 41688.95777605558, 41813.581864181346, 41948.58051419485, 44443.77778111113, 44857.55142448583, 45103.548964510344, 45382.319265211045, 45435.99999999992, 8849.115088491148, 9003.684871029514, 9014.2788576914, 9032.638694452247, 9061.546922653873, 8828.823423531525, 8875.156860098281, 8885.422447540921, 8905.421147625384, 8933.106689331047, 9099.181073703365, 9099.818003639923, 9180.219681327095, 9202.309826762987, 9210.953945230298, 8991.640334386617, 8994.190522852941, 9083.45499270046, 9085.909140908572, 9114.45313281202, 9095.045020272843, 9102.590383432762, 9171.266298696088, 9173.724788256362, 9188.632454701803, 44896.0, 45155.32267015997, 45250.321245181345, 45289.320660190126, 45298.320525192146, 3872.0, 3927.9214415711526, 4036.1120553478186, 4097.201045796071, 4098.303288440972, 33815.32369352624, 34233.828830855935, 34513.13717157066, 34577.13557161091, 34868.0, 4578.473475550321, 4634.930476042862, 4638.814447422099, 4656.837010704643, 4672.976635116803, 335990.0, 339264.0, 340093.0, 341923.0, 342586.0, 39870.0, 65251.67374163099, 66097.66951165213, 66187.66906165438, 66242.0, 40122.19755604872, 40261.0, 40347.59652403497, 40455.1908961825, 40473.0, 17149.228284727218, 17227.052512111703, 17263.827361726475, 17331.56671083233, 17413.390531331344, 14149.434022639185, 14179.220142892034, 14226.928865355814, 14232.644183895485, 14264.572062838131, 9376.578053987587, 9386.15524602789, 9465.763355916157, 9466.0, 9490.098440648266, 3556.270964452281, 3666.1934374437583, 3667.94498082529, 3686.8340924658455, 3688.7233457490797, 1148.3569201247162, 1149.1036991146918, 1175.1186610042469, 1186.4957393107895, 1200.5677955935855, 33682.20174732383, 34076.31847363088, 34179.82910085434, 34391.0, 34837.65162348395, 1193.5524178433088, 1196.7845787758113, 1197.1560050164612, 1273.8662440443804, 1282.352412031918, 16467.25897334599, 17221.39725109615, 17287.481375558753, 17793.555161120818, 17906.28374865017, 516407.0, 557164.0, 565640.0, 565968.0, 568453.0, 59287.0, 59696.701516493005, 60005.39994599998, 60094.69952650209, 60113.39886601165, 1537.2313843078462, 1537.9923100384651, 1568.7882135911734, 1595.7367034439187, 1664.3592216996544, 33880.66119338775, 34568.48147277843, 34705.82647086748, 34829.65170348264, 35136.82431587826, 56734.14898776604, 57093.0, 57602.13596795968, 57610.0, 57650.4234957645, 340713.0, 344693.0, 345168.0, 346021.0, 347138.0, 31286.843565782026, 31925.840370797996, 31926.52110218395, 31957.68042319547, 51076.0, 49778.502214978835, 50874.0, 51771.2234316478, 52062.47937520577, 55028.449715502335, 43704.56295437005, 45938.0, 46042.0, 46969.0, 46994.53005469902, 43983.560164397946, 45007.0, 45129.32306015347, 45781.54218457906, 45785.77107114443, 45691.31463027991, 45945.54054459413, 46869.53130468788, 47089.0, 47411.288830666876, 40103.59896400999, 40235.3964690524, 40239.19521609493, 40553.59446405498, 40767.1846563073, 30621.846890765402, 31029.379412411174, 31272.5927333266, 31568.422578726415, 31995.0], "value": 20236.246027777153, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.8837069805802, 424.57542457542456, 428.12448542730135, 433.82430115803095, 440.1724757455982, 18944.715829262554, 19531.218751249933, 19693.704594431078, 20124.597508049832, 20687.793122068775, 17552.91223543884, 18436.446906592824, 19339.903300483515, 19380.709289360635, 19394.70907936382, 5341.0, 5465.699386533739, 5471.534919531845, 5481.616286859921, 5483.451654834525, 5114.948850511484, 5429.348478182604, 5451.236826844245, 5467.0, 5507.862303442406, 5513.669179849202, 5526.530244929186, 5534.723263836812, 5537.252470916435, 5542.085555883295, 5265.236540701615, 5466.0, 5485.040117979338, 5486.698231597261, 5502.532284755801, 5294.894102117936, 5315.734213289359, 5323.627346085776, 5349.839504814861, 5359.169328754049, 14163.15021098727, 14247.715045699131, 20900.47748806292, 24222.273331799897, 24400.26799196027, 2443.645671377658, 2474.3937735254976, 2476.120977053138, 2494.550980823451, 2497.825152239344, 10686.0, 10982.560697572168, 11076.501557429934, 11081.556737730481, 11237.83143252852, 2058.289889987967, 2086.4158035750015, 2134.6691262854283, 2154.6121698094335, 2172.2397160993683, 79365.0, 83085.16914830834, 83678.99999999939, 84466.57766711188, 84468.0, 11635.127365447624, 11847.881521184678, 11921.105917056255, 11974.341411222293, 12521.93739031299, 16516.091614961053, 16601.66796664084, 16740.748888766677, 16790.32838686439, 16819.15904204785, 7709.38324934004, 8818.206361427503, 8833.381663283508, 8958.238549723215, 9085.227755640712, 8704.738857834274, 8750.86873696895, 8757.430767000125, 8788.0, 8809.603567839462, 4945.23348880924, 5069.518395752398, 5102.540771330598, 5207.921881171786, 5246.291750613695, 2102.306238941176, 2102.9263975760773, 2128.6372126939395, 2172.424307558498, 2177.466520702438, 1034.8085604163255, 1040.921930855189, 1051.574112484445, 1112.2881355932209, 1153.0948205658517, 17460.301587936596, 19001.619967600847, 19098.502146541705, 19176.904115479334, 19813.20747170126, 1070.5878236878857, 1080.9405482698528, 1108.2962318927378, 1117.8938000889902, 1124.7131981344705, 10911.945440272748, 11276.605318813803, 11328.603498877499, 11337.659870203906, 11373.431328433546, 519402.0, 521295.0, 522818.0, 537596.0, 544473.0, 89440.0, 92992.0, 93660.59509107369, 94219.52890235643, 97273.97879816385, 1101.9944900275607, 1104.2932523185168, 1104.469854469859, 1105.9668209953713, 1107.7009207513927, 17059.371837879782, 17205.655886882443, 17476.650466990843, 17663.38178163758, 17682.5579360517, 32742.83628581842, 34674.0, 37753.433698493995, 40901.38647920224, 41478.585214147475, 48038.519614804805, 49261.753691231315, 49360.25959610537, 49370.50629493659, 49951.0, 8663.220310172115, 8723.65105395777, 8745.650173993223, 9036.141566551167, 9066.682666106655, 11699.766004679688, 12824.615261541796, 12871.678208044874, 13045.282509462071, 13074.411651475517, 11070.0, 11161.274517156684, 11170.832437513282, 11419.429028548706, 11468.655940321803, 10624.787504250025, 10711.25021248505, 10729.30259533159, 10737.765157007045, 10929.890701092887, 11081.168912331446, 11081.83377249358, 11416.086713063101, 11548.711282218012, 11550.48022838991, 8725.694600688945, 8939.821203576023, 8948.149925757043, 8954.776130596527, 9239.445633262027, 8961.193492585699, 10824.729381765519, 11092.889071109186, 11099.445027748741, 11185.272957257752], "value": 9313.656821762104, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1560.3134620766864, 1587.388855290713, 1798.8650851186164, 1931.3626503253927, 1951.5608987977698, 7505.549667019983, 8263.132371101023, 8295.419320647557, 8504.957475212623, 8563.91436085639, 8144.714934977277, 8290.295324897375, 8305.875411868827, 8516.914830851689, 8557.187067228604, 2306.3657494189147, 2333.3349995251356, 2347.1432926981615, 2351.0243249051623, 2354.7409784923643, 2367.147826782357, 2380.33350661815, 2381.5475059738596, 2386.5465561543338, 2402.0391843262746, 2295.8737269450176, 2310.7111611048617, 2316.6872472216287, 2318.7797159269844, 2319.408550819547, 2325.37214951962, 2340.169239919821, 2358.3868194269526, 2359.6578496118054, 2361.4686695493547, 2370.620700687899, 2373.2524254859663, 2391.27066244797, 2394.114177754225, 2395.616701327797, 8657.134286571356, 8710.216080552716, 8715.69495067676, 8727.04002559718, 8733.519656418894, 5285.4450282720145, 5411.323584551931, 5427.538659213971, 5433.26650902131, 5437.564994800407, 3244.561984132159, 3247.3018301065385, 3263.2494526259093, 3303.587051618548, 3326.7837590556537, 1115.6262652011585, 1182.3201659045997, 1182.355616189179, 1183.3373310945897, 1194.0, 11989.220700654516, 12076.939615301955, 12097.0, 12171.817422738666, 12204.389780510939, 4375.518692943773, 4450.06548624786, 4543.522930092358, 4596.402467679247, 4628.4445866496235, 9724.465154416575, 9724.611015559298, 9725.90274097264, 9730.12428881404, 9789.706308810748, 5069.569086627678, 5072.467390923974, 5077.1876499759865, 5085.923711144335, 5095.617828662865, 4646.558576935186, 4765.0, 4780.976095119502, 4792.281157826354, 4824.0834241494, 3857.1128640412303, 3893.805309734502, 3967.385055316373, 3969.6427321540623, 3988.222296652125, 1200.1299058182747, 1200.9939950300193, 1211.5517258614368, 1212.3998620682642, 1229.8155276708565, 933.5752232734089, 1042.6819819954835, 1066.9413182274893, 1069.668402795136, 1092.1426680056118, 5980.401959803985, 6232.657203853741, 6280.183576135073, 6291.0, 6376.936230637727, 483.8572621076791, 486.6593384630773, 486.9732164730974, 487.3956294195243, 493.4153028661048, 4136.875893723193, 4155.97922010388, 4163.75017498951, 4194.119234960693, 4198.832046718097, 10225.539850706737, 10476.319039262424, 10496.842547361795, 10548.261621686408, 10570.947145264377, 10358.741031474123, 10360.481975901175, 10379.688609341732, 10400.68797936048, 10426.0, 7758.379329653614, 7985.361171106298, 8034.879476807852, 8065.39509536787, 8104.311133553596, 5748.137779333133, 5768.53851691872, 5856.707164641836, 5915.645061296336, 5947.345791962967, 9123.908760912305, 9281.767955801159, 9288.907110928803, 9435.433873967584, 9482.431054136774, 9157.679481218125, 9432.0, 9497.145256926633, 9523.809523809623, 9573.808523829624, 7598.354139898171, 7721.382289416945, 7880.921190788019, 7913.366930645419, 7930.325922296439, 8420.368472364475, 8443.0, 8444.831103377775, 8470.745877623682, 8487.575621218792, 7552.358049565849, 7710.4217183710325, 7765.650545725569, 7829.804254893673, 7835.960820196091, 7538.773836784905, 7664.73173438927, 7723.189065148079, 7738.187490313661, 7744.0, 6979.0, 6983.3714965653335, 7010.018597396471, 7027.4729395294535, 7037.507374483737, 5348.1442969124755, 5358.0, 5449.836504904859, 5475.0, 5478.123500240102, 5284.418713941386, 5385.649932754359, 5503.174523821299, 5520.613557050968, 5589.748461319332], "value": 4544.6940425375315, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.863551168312, 365.6763764068799, 368.09265161377215, 369.0312928562523, 371.40760487023186, 6576.605403675783, 6623.2383275923285, 6642.402183803462, 6647.700853461593, 6683.131192944922, 6476.74093036278, 6530.510211734115, 6533.771318003871, 6539.117219175409, 6550.475961923046, 4799.3520874682, 4903.8774030649165, 4947.084789313988, 4966.956939042805, 4981.601471882259, 4917.704937703749, 4927.6550641455115, 4936.605071594264, 4954.281629163769, 4958.380202474691, 4891.14404979129, 4904.558589726924, 4906.945006823532, 4914.901701965959, 4916.950830491703, 4897.1674815281485, 4914.213725803872, 4917.311576379309, 4930.506949305077, 4943.555080042777, 4290.099079193373, 4290.163418133465, 4290.957090429087, 4292.527821939584, 4299.699021068526, 6034.456898879122, 6218.875622487571, 6269.341719119518, 6277.435030847246, 6283.654399008053, 5717.828465146053, 5728.198052272685, 5733.226014488033, 5760.510356619692, 5770.2498675171955, 6946.37482626566, 6954.513184077116, 6960.617166055865, 6962.060121883582, 6967.930320696779, 4750.976245118787, 4754.263089221176, 4765.856194513327, 4767.284907263904, 4769.642276829217, 7232.457565682595, 7237.3848222901115, 7284.344409003215, 7289.671964761598, 7306.196318404971, 4853.49038350968, 4866.562009419169, 4964.652474326835, 4967.652264341534, 5028.145215313405, 5046.59627229828, 5069.619778516626, 5083.974580127076, 5086.237064440364, 5087.949120508822, 5822.796202132905, 5826.446487583673, 5830.41695830422, 5832.862591794644, 5835.533157347487, 7007.5795452272005, 7007.964960175166, 7008.194057683329, 7010.439164866901, 7014.088168538057, 1784.0811981829368, 1811.80975997521, 1815.0, 1815.9364422245158, 1819.954501137482, 1159.8086315757973, 1186.3356520348632, 1188.8811118888043, 1201.2192075151222, 1235.518147922311, 6827.21487028998, 6855.760048398282, 6863.6568171591225, 6867.622280774505, 6868.896966545506, 7089.468289878279, 7236.493445458873, 7236.493445458873, 7256.310650488301, 7256.528325658815, 6916.100906882073, 6920.550164239308, 6927.618980955995, 6942.444604431735, 6947.687354069079, 3906.453096566483, 3923.843046278117, 3928.1750832325533, 3934.9606503935165, 3939.074317535377, 7602.543847369176, 7621.618919054025, 7630.7329243477325, 7636.80907977305, 7653.770386888402, 7254.202037775841, 7361.631918404166, 7443.106827180773, 7444.627768611548, 7451.068616422947, 7532.698692052366, 7547.886781698279, 7555.508891922117, 7563.810904727316, 7590.988388077164, 7268.164161121539, 7270.2366251544945, 7277.526960747747, 7281.453890958305, 7282.453815963718, 7141.428685705235, 7146.071010768567, 7177.1746249182015, 7180.066591343092, 7184.533005354635, 7498.925010749823, 7585.0, 7624.80937976555, 7636.389088872988, 7657.46397752152, 4873.658843881036, 4876.829310974099, 4914.189158788759, 4915.43472500667, 4925.950740492693, 7247.202807691256, 7289.942958271127, 7292.708291668274, 7296.96351518239, 7303.671334790053, 6988.79033628992, 7010.0, 7060.71757129709, 7068.0, 7068.151821781419, 6859.965700171467, 7052.294770523112, 7054.506184567031, 7057.0, 7061.505694601328, 6846.657667116525, 6886.965565172142, 6894.172699276119, 6916.930830691628, 6923.0, 7004.824879378057, 7058.082449281766, 7073.080499535027, 7074.893876591752, 7098.467614929004, 6860.0738900247825, 6888.48336374784, 6899.068625735462, 6901.861962760616, 6913.27168207948], "value": 5279.164212181089, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [869.0093293645245, 894.494610545042, 918.5085979001236, 947.4836214263225, 958.7555173430774, 15078.396864125436, 15263.236838158075, 15275.236238188101, 15279.159646219454, 15333.156676382794, 14233.928830355833, 14772.852271477283, 14800.555983320492, 14804.0, 14804.851951480481, 897.7396554999042, 902.336782464891, 903.8960519540238, 905.4024343933006, 924.7179610218881, 925.9629614815399, 928.4150984879517, 931.2782593490032, 932.1936524905941, 932.4125800745521, 950.8288508068545, 952.7475219066953, 954.1460392948342, 954.1651055326589, 959.9232061435104, 947.8199142162968, 948.7960088580953, 949.2121539122512, 953.0659953245857, 959.3668179001826, 706.8374273916977, 707.9769732736147, 708.9858202835966, 710.989335159973, 711.8754218011827, 14794.0, 14828.70342593153, 14863.925680371529, 14864.777028344584, 14910.105393676415, 14535.127892326494, 14733.778993315109, 14745.705085898222, 14797.704045919128, 14822.62943426423, 12027.218230815057, 12201.816972745415, 12205.93897030518, 12271.815922761165, 12553.93723031388, 4031.395290706388, 4044.272031034442, 4110.609492098276, 4146.170765846843, 4247.638950689196, 56808.71595642037, 57843.0, 58383.41616583822, 59055.40944590585, 59102.40897591055, 11599.422914282837, 11674.474648640831, 11704.941475292739, 11720.472578733978, 11824.11319151067, 21014.0, 21014.15943362279, 21037.263695770576, 21067.262645807325, 21072.789272107388, 8775.868361974575, 8842.159994800484, 8851.159139881702, 8861.867071993925, 8946.105389461001, 4905.386826646669, 4915.4592994771265, 4964.925526117111, 4978.178600530872, 5121.564667003272, 3602.5676918769914, 3642.1987162824134, 3648.0332711831384, 3648.85404583819, 3654.872079477259, 663.1909070933453, 665.4210836572116, 714.3249629100554, 719.6114098386918, 724.9275072492813, 762.4281788658507, 781.1415769543418, 794.0550744613892, 807.7576726981882, 826.9131741167212, 9514.666986655573, 9963.0, 10326.535305911251, 10419.426931518688, 10426.478676066317, 768.1780494870435, 793.6782832006409, 803.0323460230409, 820.7332616899531, 845.159066728609, 4499.820007199675, 4861.781219845116, 4940.209566469348, 4967.056259310792, 4999.325091112726, 61507.38492615106, 62723.6863815678, 62766.37233627697, 63152.36847631465, 63560.68219658872, 55684.0, 56046.439535604935, 56087.43912560904, 56140.71929640407, 56528.15207771887, 50700.49299506958, 50921.74539127281, 51293.487065129615, 51400.0, 51650.48349516457, 10248.487575621339, 10339.948300258751, 10350.741231469274, 10376.27366084397, 10516.369017858953, 36968.63031369652, 37014.44478332881, 37817.81091094527, 38299.0, 38499.0, 44936.32595511135, 47329.0, 48474.515254847, 49489.50510494849, 49898.750506247234, 12732.427040783, 12888.484460621845, 12947.0, 13020.0, 13160.670983225496, 13437.328133593477, 13448.798268025792, 13553.051286409856, 14059.22674252926, 15350.385984560493, 13718.108322958975, 13840.792388113985, 13942.930285348508, 14219.360128794022, 14347.784783228468, 13707.109037912503, 13756.65608359799, 13895.374708137955, 14014.369353379327, 14031.228282444563, 13468.86531134676, 13550.39023243976, 13767.586972390844, 13803.861961380258, 13875.72248555003, 11200.21598488098, 11352.205345625727, 11421.31472111676, 11492.885071149181, 11515.654530364103, 10462.424566648908, 10682.198835087554, 11485.885141148816, 11506.964373206452, 11611.187216894736], "value": 7315.957467455181, "unit": "score"}], "name": "dromaeo_css", "value": 7518.496833767446, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 111.0, 102.0, 110.0, 113.0, 112.0, 101.0, 114.0, 109.0, 112.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 126.0, 159.0, 172.0, 134.0, 149.0, 151.0, 126.0, 176.0, 134.0], "value": 148.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 162.0, 163.0, 162.0, 162.0, 161.0, 164.0, 162.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 73.0, 175.0, 75.0, 76.0, 74.0, 76.0, 165.0, 76.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 152.0, 91.0, 94.0, 93.0, 153.0, 93.0, 93.0, 147.0], "value": 110.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 148.0, 146.0, 147.0, 148.0, 147.0, 146.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 94.0, 96.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 58.0, 56.0, 58.0, 56.0, 55.0, 81.0, 54.0, 53.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 58.0, 57.0, 57.0, 58.0, 57.0, 58.0, 58.0], "value": 57.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 74.0, 74.0, 74.0, 75.0, 76.0, 73.0, 81.0, 73.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 120.0, 114.0, 115.0, 116.0, 118.0, 122.0, 113.0, 129.0, 129.0], "value": 118.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 157.0, 160.0, 158.0, 157.0, 160.0, 157.0, 163.0, 156.0, 159.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 67.0, 68.0, 68.0, 69.0, 68.0, 67.0, 67.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1520.8999999999996, "alertThreshold": 2.0}]} 21:50:38 INFO - Return code: 0 21:50:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:50:38 ERROR - # TBPL SUCCESS # 21:50:38 INFO - Running post-action listener: _resource_record_post_action 21:50:38 INFO - Running post-run listener: _resource_record_post_run 21:50:39 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 464158720; Read time: 16; Write time: 401096 21:50:39 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 241876992; Read time: 0; Write time: 298164 21:50:39 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 4096; Write bytes: 222281728; Read time: 16; Write time: 102932 21:50:39 INFO - Running post-run listener: _upload_blobber_files 21:50:39 INFO - Blob upload gear active. 21:50:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:50:39 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 21:50:39 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:50:39 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:50:39 INFO - (blobuploader) - INFO - Open directory for files ... 21:50:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:50:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:50:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:50:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:50:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:50:40 INFO - (blobuploader) - INFO - Done attempting. 21:50:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:50:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:50:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:50:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:50:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:50:41 INFO - (blobuploader) - INFO - Done attempting. 21:50:41 INFO - (blobuploader) - INFO - Iteration through files over. 21:50:41 INFO - Return code: 0 21:50:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:50:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:50:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf0d8806ae9ffd63f7687abc57448cf106c3dacb239ce3e4b3d15ad96fa794120fd3444c5da847d66ee6d1bf55d3c8e014a414e3ed6222ce82935a429cc3bd73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a2f0bb0b5559bc2e43407d94a5fbcb4ded0f2b91daa7cc266e76322202b97345d95926aeedc9f77aded83c0e54419c169ae917000a16c5829392a09bfe44660"} 21:50:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:50:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:50:41 INFO - Contents: 21:50:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf0d8806ae9ffd63f7687abc57448cf106c3dacb239ce3e4b3d15ad96fa794120fd3444c5da847d66ee6d1bf55d3c8e014a414e3ed6222ce82935a429cc3bd73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a2f0bb0b5559bc2e43407d94a5fbcb4ded0f2b91daa7cc266e76322202b97345d95926aeedc9f77aded83c0e54419c169ae917000a16c5829392a09bfe44660"} 21:50:41 INFO - Running post-run listener: copy_logs_to_upload_dir 21:50:41 INFO - Copying logs to upload dir... 21:50:41 INFO - mkdir: /builds/slave/test/build/upload/logs 21:50:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.032144 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-03 21:50:40.215312) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 21:50:40.218934) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf0d8806ae9ffd63f7687abc57448cf106c3dacb239ce3e4b3d15ad96fa794120fd3444c5da847d66ee6d1bf55d3c8e014a414e3ed6222ce82935a429cc3bd73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a2f0bb0b5559bc2e43407d94a5fbcb4ded0f2b91daa7cc266e76322202b97345d95926aeedc9f77aded83c0e54419c169ae917000a16c5829392a09bfe44660"} build_url:https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011382 build_url: 'https://queue.taskcluster.net/v1/task/Dg3dS7FLRFOuz1IYdQJbWg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf0d8806ae9ffd63f7687abc57448cf106c3dacb239ce3e4b3d15ad96fa794120fd3444c5da847d66ee6d1bf55d3c8e014a414e3ed6222ce82935a429cc3bd73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a2f0bb0b5559bc2e43407d94a5fbcb4ded0f2b91daa7cc266e76322202b97345d95926aeedc9f77aded83c0e54419c169ae917000a16c5829392a09bfe44660"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 21:50:40.243878) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 21:50:40.244178) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1467605942.681970-375341809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004404 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 21:50:40.326471) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 21:50:40.329861) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 21:50:40.330209) ========= ========= Total master_lag: 0.18 =========