builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-003 starttime: 1465103837.89 results: success (0) buildid: 20160604190644 builduid: 8c8cb62ca25f4a55bba56c381457195b revision: dc972d877cd697c2c8f6248534f21bb5e1dba9ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:17.891718) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:17.894309) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:17.894606) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:17.909235) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:17.909559) ========= 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=7de08804244eb36560b44971000001c3-1465103527.889053-2065970043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-04 22:17:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-06-04 22:17:18 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111810 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:18.034704) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:18.034999) ========= 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=7de08804244eb36560b44971000001c3-1465103527.889053-2065970043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018798 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:18.084141) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:18.084474) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc972d877cd697c2c8f6248534f21bb5e1dba9ae --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc972d877cd697c2c8f6248534f21bb5e1dba9ae --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=7de08804244eb36560b44971000001c3-1465103527.889053-2065970043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-04 22:17:18,439 truncating revision to first 12 chars 2016-06-04 22:17:18,439 Setting DEBUG logging. 2016-06-04 22:17:18,439 attempt 1/10 2016-06-04 22:17:18,439 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dc972d877cd6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-04 22:17:18,962 unpacking tar archive at: mozilla-aurora-dc972d877cd6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.844020 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:18.943839) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:18.944381) ========= script_repo_revision: dc972d877cd697c2c8f6248534f21bb5e1dba9ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:18.945045) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:18.945522) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:18.956715) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-06-04 22:17:18.957312) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1465103527.889053-2065970043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:17:19 INFO - MultiFileLogger online at 20160604 22:17:19 in /builds/slave/test 22:17:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:17:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:17:19 INFO - {'append_to_log': False, 22:17:19 INFO - 'base_work_dir': '/builds/slave/test', 22:17:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:17:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:17:19 INFO - 'branch': 'Mozilla-Aurora', 22:17:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:17:19 INFO - 'config_files': ('talos/linux_config.py',), 22:17:19 INFO - 'default_actions': ('clobber', 22:17:19 INFO - 'read-buildbot-config', 22:17:19 INFO - 'download-and-extract', 22:17:19 INFO - 'populate-webroot', 22:17:19 INFO - 'create-virtualenv', 22:17:19 INFO - 'install', 22:17:19 INFO - 'run-tests'), 22:17:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:17:19 INFO - 'download_minidump_stackwalk': True, 22:17:19 INFO - 'download_symbols': 'ondemand', 22:17:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:17:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:17:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:17:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:17:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:17:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:17:19 INFO - 'installer_path': 'installer.exe', 22:17:19 INFO - 'log_level': 'info', 22:17:19 INFO - 'log_name': 'talos', 22:17:19 INFO - 'log_to_console': True, 22:17:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:17:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:17:19 INFO - 'opt_config_files': (), 22:17:19 INFO - 'pip_index': False, 22:17:19 INFO - 'sps_profile': False, 22:17:19 INFO - 'sps_profile_interval': 0, 22:17:19 INFO - 'suite': 'dromaeojs-e10s', 22:17:19 INFO - 'system_bits': '32', 22:17:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:17:19 INFO - 'title': 'talos-linux64-ix-003', 22:17:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:17:19 INFO - 'use_talos_json': True, 22:17:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:17:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:17:19 INFO - 'work_dir': 'build'} 22:17:19 INFO - ##### 22:17:19 INFO - ##### Running clobber step. 22:17:19 INFO - ##### 22:17:19 INFO - Running pre-action listener: _resource_record_pre_action 22:17:19 INFO - Running main action method: clobber 22:17:19 INFO - rmtree: /builds/slave/test/build 22:17:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:17:19 INFO - Running post-action listener: _resource_record_post_action 22:17:19 INFO - ##### 22:17:19 INFO - ##### Running read-buildbot-config step. 22:17:19 INFO - ##### 22:17:19 INFO - Running pre-action listener: _resource_record_pre_action 22:17:19 INFO - Running main action method: read_buildbot_config 22:17:19 INFO - Using buildbot properties: 22:17:19 INFO - { 22:17:19 INFO - "project": "", 22:17:19 INFO - "product": "firefox", 22:17:19 INFO - "script_repo_revision": "production", 22:17:19 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 22:17:19 INFO - "repository": "", 22:17:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 22:17:19 INFO - "buildid": "20160604190644", 22:17:19 INFO - "pgo_build": "True", 22:17:19 INFO - "basedir": "/builds/slave/test", 22:17:19 INFO - "buildnumber": 330, 22:17:19 INFO - "slavename": "talos-linux64-ix-003", 22:17:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:17:19 INFO - "platform": "ubuntu64_hw", 22:17:19 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:17:19 INFO - "branch": "mozilla-aurora", 22:17:19 INFO - "repo_path": "releases/mozilla-aurora", 22:17:19 INFO - "revision": "dc972d877cd697c2c8f6248534f21bb5e1dba9ae", 22:17:19 INFO - "stage_platform": "linux64-pgo", 22:17:19 INFO - "builduid": "8c8cb62ca25f4a55bba56c381457195b", 22:17:19 INFO - "slavebuilddir": "test" 22:17:19 INFO - } 22:17:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 22:17:19 INFO - Running post-action listener: _resource_record_post_action 22:17:19 INFO - ##### 22:17:19 INFO - ##### Running download-and-extract step. 22:17:19 INFO - ##### 22:17:19 INFO - Running pre-action listener: _resource_record_pre_action 22:17:19 INFO - Running main action method: download_and_extract 22:17:19 INFO - mkdir: /builds/slave/test/build/tests 22:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:19 INFO - https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:17:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 22:17:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 22:17:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 22:17:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:17:20 INFO - Downloaded 1361 bytes. 22:17:20 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 22:17:20 INFO - Using the following test package requirements: 22:17:20 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 22:17:20 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:20 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:17:20 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:20 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:17:20 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:20 INFO - u'jsshell-linux-x86_64.zip'], 22:17:20 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:20 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:17:20 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 22:17:20 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:20 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:17:20 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:20 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:17:20 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:20 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:17:20 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:20 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:17:20 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:20 INFO - https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:17:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:17:21 INFO - Downloaded 24177288 bytes. 22:17:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:17:22 INFO - Return code: 0 22:17:22 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:17: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')]} 22:17:22 INFO - https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:17:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:17:29 INFO - Downloaded 24177288 bytes. 22:17:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:17:30 INFO - Return code: 0 22:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:30 INFO - https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:17:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 22:17:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 22:17:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 22:17:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:17:31 INFO - Downloaded 10840145 bytes. 22:17:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:17:32 INFO - Return code: 0 22:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:32 INFO - https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:17:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 22:17:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 22:17:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 22:17:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:17:33 INFO - Downloaded 62812559 bytes. 22:17:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 22:17:33 INFO - mkdir: /builds/slave/test/properties 22:17:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:17:33 INFO - Writing to file /builds/slave/test/properties/build_url 22:17:33 INFO - Contents: 22:17:33 INFO - build_url:https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 22:17:34 INFO - Running post-action listener: _resource_record_post_action 22:17:34 INFO - Running post-action listener: set_extra_try_arguments 22:17:34 INFO - ##### 22:17:34 INFO - ##### Running populate-webroot step. 22:17:34 INFO - ##### 22:17:34 INFO - Running pre-action listener: _resource_record_pre_action 22:17:34 INFO - Running main action method: populate_webroot 22:17:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:17:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:17:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:17:34 INFO - '--tppagecycles', 22:17:34 INFO - '7'], 22:17:34 INFO - 'tests': ['tsvgm']}}, 22:17:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:17:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:17:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:17:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:17:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:17:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:17:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:34 INFO - 'talos_options': ['--disable-e10s'], 22:17:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:34 INFO - 'talos_options': ['--disable-e10s'], 22:17:34 INFO - 'tests': ['damp', 'tps']}, 22:17:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:34 INFO - 'tests': ['damp', 'tps']}, 22:17:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:17:34 INFO - 'tests': ['dromaeo_dom']}, 22:17:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:17:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:17:34 INFO - 'tests': ['a11yr', 22:17:34 INFO - 'ts_paint', 22:17:34 INFO - 'tpaint', 22:17:34 INFO - 'sessionrestore', 22:17:34 INFO - 'sessionrestore_no_auto_restore', 22:17:34 INFO - 'tabpaint']}, 22:17:34 INFO - 'other-e10s': {'tests': ['a11yr', 22:17:34 INFO - 'ts_paint', 22:17:34 INFO - 'tpaint', 22:17:34 INFO - 'sessionrestore', 22:17:34 INFO - 'sessionrestore_no_auto_restore', 22:17:34 INFO - 'tabpaint']}, 22:17:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:17:34 INFO - 'ts_paint', 22:17:34 INFO - 'tpaint', 22:17:34 INFO - 'sessionrestore', 22:17:34 INFO - 'sessionrestore_no_auto_restore', 22:17:34 INFO - 'tabpaint']}, 22:17:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:17:34 INFO - 'ts_paint', 22:17:34 INFO - 'tpaint', 22:17:34 INFO - 'sessionrestore', 22:17:34 INFO - 'sessionrestore_no_auto_restore', 22:17:34 INFO - 'tabpaint']}, 22:17:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:17:34 INFO - 'tests': ['a11yr', 22:17:34 INFO - 'ts_paint', 22:17:34 INFO - 'tpaint', 22:17:34 INFO - 'sessionrestore', 22:17:34 INFO - 'sessionrestore_no_auto_restore', 22:17:34 INFO - 'tabpaint']}, 22:17:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:17:34 INFO - 'tests': ['a11yr', 22:17:34 INFO - 'ts_paint', 22:17:34 INFO - 'tpaint', 22:17:34 INFO - 'sessionrestore', 22:17:34 INFO - 'sessionrestore_no_auto_restore', 22:17:34 INFO - 'tabpaint']}, 22:17:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:17:34 INFO - 'tests': ['tsvgx', 22:17:34 INFO - 'tsvgr_opacity', 22:17:34 INFO - 'tart', 22:17:34 INFO - 'tscrollx', 22:17:34 INFO - 'cart']}, 22:17:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:17:34 INFO - 'tsvgr_opacity', 22:17:34 INFO - 'tart', 22:17:34 INFO - 'tscrollx', 22:17:34 INFO - 'cart']}, 22:17:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:34 INFO - 'talos_options': ['--disable-e10s'], 22:17:34 INFO - 'tests': ['tp5o']}, 22:17:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:34 INFO - 'tests': ['tp5o']}, 22:17:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:34 INFO - 'talos_options': ['--disable-e10s', 22:17:34 INFO - '--xperf_path', 22:17:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:34 INFO - 'tests': ['tp5n']}, 22:17:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:34 INFO - 'talos_options': ['--xperf_path', 22:17:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:34 INFO - 'tests': ['tp5n']}}, 22:17:34 INFO - 'talos.zip': {'path': '', 22:17:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:17:34 INFO - Running post-action listener: _resource_record_post_action 22:17:34 INFO - ##### 22:17:34 INFO - ##### Running create-virtualenv step. 22:17:34 INFO - ##### 22:17:34 INFO - Running pre-action listener: _resource_record_pre_action 22:17:34 INFO - Running main action method: create_virtualenv 22:17:34 INFO - Creating virtualenv /builds/slave/test/build/venv 22:17:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:17:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:17:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:17:34 INFO - Using real prefix '/usr' 22:17:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:17:34 INFO - Installing distribute.............................................................................................................................................................................................done. 22:17:36 INFO - Installing pip.................done. 22:17:36 INFO - Return code: 0 22:17:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:17: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')]} 22:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17: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')]} 22:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1be9f88f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144e410>, '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 0x144f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144f7a0>, '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': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17: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', 'pip>=1.5'] in /builds/slave/test/build 22:17: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 pip>=1.5 22:17:36 INFO - Using env: {'DISPLAY': ':0', 22:17:36 INFO - 'HOME': '/home/cltbld', 22:17:36 INFO - 'LANG': 'en_US.UTF-8', 22:17:36 INFO - 'LANGUAGE': 'en_US:en', 22:17:36 INFO - 'LOGNAME': 'cltbld', 22:17:36 INFO - 'MAIL': '/var/mail/cltbld', 22:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:36 INFO - 'MOZ_NO_REMOTE': '1', 22:17:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:36 INFO - 'NO_EM_RESTART': '1', 22:17:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:36 INFO - 'PWD': '/builds/slave/test', 22:17:36 INFO - 'SHELL': '/bin/bash', 22:17:36 INFO - 'SHLVL': '1', 22:17:36 INFO - 'TERM': 'linux', 22:17:36 INFO - 'TMOUT': '86400', 22:17:36 INFO - 'USER': 'cltbld', 22:17:36 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 22:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:36 INFO - '_': '/tools/buildbot/bin/python'} 22:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:17:37 INFO - Cleaning up... 22:17:37 INFO - Return code: 0 22:17:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:37 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 0x7f1be9f88f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144e410>, '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 0x144f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144f7a0>, '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': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:37 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 22:17:37 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 22:17:37 INFO - Using env: {'DISPLAY': ':0', 22:17:37 INFO - 'HOME': '/home/cltbld', 22:17:37 INFO - 'LANG': 'en_US.UTF-8', 22:17:37 INFO - 'LANGUAGE': 'en_US:en', 22:17:37 INFO - 'LOGNAME': 'cltbld', 22:17:37 INFO - 'MAIL': '/var/mail/cltbld', 22:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:37 INFO - 'MOZ_NO_REMOTE': '1', 22:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:37 INFO - 'NO_EM_RESTART': '1', 22:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:37 INFO - 'PWD': '/builds/slave/test', 22:17:37 INFO - 'SHELL': '/bin/bash', 22:17:37 INFO - 'SHLVL': '1', 22:17:37 INFO - 'TERM': 'linux', 22:17:37 INFO - 'TMOUT': '86400', 22:17:37 INFO - 'USER': 'cltbld', 22:17:37 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 22:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:37 INFO - '_': '/tools/buildbot/bin/python'} 22:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:37 INFO - Downloading/unpacking psutil>=0.7.1 22:17: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 22:17: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 22:17: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 22:17: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 22:17: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 22:17: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 22:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:17:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:39 INFO - Installing collected packages: psutil 22:17:39 INFO - Running setup.py install for psutil 22:17:39 INFO - building 'psutil._psutil_linux' extension 22:17:39 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:17:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:17:39 INFO - building 'psutil._psutil_posix' extension 22:17:39 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:17:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:17:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:39 INFO - Successfully installed psutil 22:17:39 INFO - Cleaning up... 22:17:39 INFO - Return code: 0 22:17:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:39 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 0x7f1be9f88f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144e410>, '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 0x144f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144f7a0>, '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': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:39 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 22:17:39 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 22:17:39 INFO - Using env: {'DISPLAY': ':0', 22:17:39 INFO - 'HOME': '/home/cltbld', 22:17:39 INFO - 'LANG': 'en_US.UTF-8', 22:17:39 INFO - 'LANGUAGE': 'en_US:en', 22:17:39 INFO - 'LOGNAME': 'cltbld', 22:17:39 INFO - 'MAIL': '/var/mail/cltbld', 22:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:39 INFO - 'MOZ_NO_REMOTE': '1', 22:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:39 INFO - 'NO_EM_RESTART': '1', 22:17:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:39 INFO - 'PWD': '/builds/slave/test', 22:17:39 INFO - 'SHELL': '/bin/bash', 22:17:39 INFO - 'SHLVL': '1', 22:17:39 INFO - 'TERM': 'linux', 22:17:39 INFO - 'TMOUT': '86400', 22:17:39 INFO - 'USER': 'cltbld', 22:17:39 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 22:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:39 INFO - '_': '/tools/buildbot/bin/python'} 22:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:40 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:17:41 INFO - Installing collected packages: mozsystemmonitor 22:17:41 INFO - Running setup.py install for mozsystemmonitor 22:17:42 INFO - Successfully installed mozsystemmonitor 22:17:42 INFO - Cleaning up... 22:17:42 INFO - Return code: 0 22:17:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:42 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 0x7f1be9f88f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144e410>, '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 0x144f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144f7a0>, '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': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:17:42 INFO - Using env: {'DISPLAY': ':0', 22:17:42 INFO - 'HOME': '/home/cltbld', 22:17:42 INFO - 'LANG': 'en_US.UTF-8', 22:17:42 INFO - 'LANGUAGE': 'en_US:en', 22:17:42 INFO - 'LOGNAME': 'cltbld', 22:17:42 INFO - 'MAIL': '/var/mail/cltbld', 22:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:42 INFO - 'MOZ_NO_REMOTE': '1', 22:17:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:42 INFO - 'NO_EM_RESTART': '1', 22:17:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:42 INFO - 'PWD': '/builds/slave/test', 22:17:42 INFO - 'SHELL': '/bin/bash', 22:17:42 INFO - 'SHLVL': '1', 22:17:42 INFO - 'TERM': 'linux', 22:17:42 INFO - 'TMOUT': '86400', 22:17:42 INFO - 'USER': 'cltbld', 22:17:42 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 22:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:42 INFO - '_': '/tools/buildbot/bin/python'} 22:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:42 INFO - Downloading/unpacking blobuploader==1.2.4 22:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:44 INFO - Downloading blobuploader-1.2.4.tar.gz 22:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:17:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:17:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:44 INFO - Downloading docopt-0.6.1.tar.gz 22:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:17:44 INFO - Installing collected packages: blobuploader, requests, docopt 22:17:44 INFO - Running setup.py install for blobuploader 22:17:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:17:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:17:45 INFO - Running setup.py install for requests 22:17:45 INFO - Running setup.py install for docopt 22:17:45 INFO - Successfully installed blobuploader requests docopt 22:17:45 INFO - Cleaning up... 22:17:45 INFO - Return code: 0 22:17:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:45 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 0x7f1be9f88f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144e410>, '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 0x144f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144f7a0>, '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': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:17:45 INFO - Using env: {'DISPLAY': ':0', 22:17:45 INFO - 'HOME': '/home/cltbld', 22:17:45 INFO - 'LANG': 'en_US.UTF-8', 22:17:45 INFO - 'LANGUAGE': 'en_US:en', 22:17:45 INFO - 'LOGNAME': 'cltbld', 22:17:45 INFO - 'MAIL': '/var/mail/cltbld', 22:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:45 INFO - 'MOZ_NO_REMOTE': '1', 22:17:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:45 INFO - 'NO_EM_RESTART': '1', 22:17:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:45 INFO - 'PWD': '/builds/slave/test', 22:17:45 INFO - 'SHELL': '/bin/bash', 22:17:45 INFO - 'SHLVL': '1', 22:17:45 INFO - 'TERM': 'linux', 22:17:45 INFO - 'TMOUT': '86400', 22:17:45 INFO - 'USER': 'cltbld', 22:17:45 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 22:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:45 INFO - '_': '/tools/buildbot/bin/python'} 22:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:45 INFO - Running setup.py (path:/tmp/pip-wY71JR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:45 INFO - Running setup.py (path:/tmp/pip-arJRnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:45 INFO - Running setup.py (path:/tmp/pip-g_u5a7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:46 INFO - Running setup.py (path:/tmp/pip-dKIOvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:46 INFO - Running setup.py (path:/tmp/pip-tgFb_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:46 INFO - Running setup.py (path:/tmp/pip-K9epTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:46 INFO - Running setup.py (path:/tmp/pip-hxoofQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:46 INFO - Running setup.py (path:/tmp/pip-CJXFuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:46 INFO - Running setup.py (path:/tmp/pip-HqbweD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:46 INFO - Running setup.py (path:/tmp/pip-qP4iAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:46 INFO - Running setup.py (path:/tmp/pip-0PCxyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:46 INFO - Running setup.py (path:/tmp/pip-uE0JRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:46 INFO - Running setup.py (path:/tmp/pip-VB0y3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:46 INFO - Running setup.py (path:/tmp/pip-UA0L9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:46 INFO - Running setup.py (path:/tmp/pip-MSLnCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:46 INFO - Running setup.py (path:/tmp/pip-J6OLpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:46 INFO - Running setup.py (path:/tmp/pip-PZYb5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:17:46 INFO - Running setup.py install for manifestparser 22:17:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:17:47 INFO - Running setup.py install for mozcrash 22:17:47 INFO - Running setup.py install for mozdebug 22:17:47 INFO - Running setup.py install for mozdevice 22:17:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:17:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:17:47 INFO - Running setup.py install for mozfile 22:17:47 INFO - Running setup.py install for mozhttpd 22:17:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:17:47 INFO - Running setup.py install for mozinfo 22:17:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:17:47 INFO - Running setup.py install for mozInstall 22:17:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:17:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:17:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:17:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:17:47 INFO - Running setup.py install for mozleak 22:17:47 INFO - Running setup.py install for mozlog 22:17:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:17:48 INFO - Running setup.py install for moznetwork 22:17:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:17:48 INFO - Running setup.py install for mozprocess 22:17:48 INFO - Running setup.py install for mozprofile 22:17:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:17:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:17:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:17:48 INFO - Running setup.py install for mozrunner 22:17:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:17:48 INFO - Running setup.py install for mozscreenshot 22:17:48 INFO - Running setup.py install for moztest 22:17:48 INFO - Running setup.py install for mozversion 22:17:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:17:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:17:48 INFO - Cleaning up... 22:17:48 INFO - Return code: 0 22:17:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:48 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 0x7f1be9f88f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144e410>, '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 0x144f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144f7a0>, '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': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:17:48 INFO - Using env: {'DISPLAY': ':0', 22:17:48 INFO - 'HOME': '/home/cltbld', 22:17:48 INFO - 'LANG': 'en_US.UTF-8', 22:17:48 INFO - 'LANGUAGE': 'en_US:en', 22:17:48 INFO - 'LOGNAME': 'cltbld', 22:17:48 INFO - 'MAIL': '/var/mail/cltbld', 22:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:48 INFO - 'MOZ_NO_REMOTE': '1', 22:17:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:48 INFO - 'NO_EM_RESTART': '1', 22:17:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:48 INFO - 'PWD': '/builds/slave/test', 22:17:48 INFO - 'SHELL': '/bin/bash', 22:17:48 INFO - 'SHLVL': '1', 22:17:48 INFO - 'TERM': 'linux', 22:17:48 INFO - 'TMOUT': '86400', 22:17:48 INFO - 'USER': 'cltbld', 22:17:48 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 22:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:48 INFO - '_': '/tools/buildbot/bin/python'} 22:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:49 INFO - Running setup.py (path:/tmp/pip-keefX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:49 INFO - Running setup.py (path:/tmp/pip-O1K72W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:49 INFO - Running setup.py (path:/tmp/pip-yoRtAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:49 INFO - Running setup.py (path:/tmp/pip-GAQ7Rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:49 INFO - Running setup.py (path:/tmp/pip-5gJgmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:49 INFO - Running setup.py (path:/tmp/pip-v_nQum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:49 INFO - Running setup.py (path:/tmp/pip-eg4bHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:49 INFO - Running setup.py (path:/tmp/pip-BxiofN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:49 INFO - Running setup.py (path:/tmp/pip-T2UrOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:49 INFO - Running setup.py (path:/tmp/pip-GgftKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:49 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)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:49 INFO - Running setup.py (path:/tmp/pip-FS7JxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:49 INFO - Running setup.py (path:/tmp/pip-qUSZ6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:49 INFO - Running setup.py (path:/tmp/pip-6ICDqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:49 INFO - Running setup.py (path:/tmp/pip-7NXeIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:50 INFO - Running setup.py (path:/tmp/pip-r4AN5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:50 INFO - Running setup.py (path:/tmp/pip-WLsBgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:50 INFO - Running setup.py (path:/tmp/pip-OXLsA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:17:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:17: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 22:17: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 22:17: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 22:17: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 22:17: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 22:17: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 22:17:52 INFO - Downloading blessings-1.6.tar.gz 22:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:17:52 INFO - Installing collected packages: blessings 22:17:52 INFO - Running setup.py install for blessings 22:17:52 INFO - Successfully installed blessings 22:17:52 INFO - Cleaning up... 22:17:52 INFO - Return code: 0 22:17:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:52 INFO - Reading from file tmpfile_stdout 22:17:52 INFO - Current package versions: 22:17:52 INFO - argparse == 1.2.1 22:17:52 INFO - blessings == 1.6 22:17:52 INFO - blobuploader == 1.2.4 22:17:52 INFO - docopt == 0.6.1 22:17:52 INFO - manifestparser == 1.1 22:17:52 INFO - mozInstall == 1.12 22:17:52 INFO - mozcrash == 0.17 22:17:52 INFO - mozdebug == 0.1 22:17:52 INFO - mozdevice == 0.48 22:17:52 INFO - mozfile == 1.2 22:17:52 INFO - mozhttpd == 0.7 22:17:52 INFO - mozinfo == 0.9 22:17:52 INFO - mozleak == 0.1 22:17:52 INFO - mozlog == 3.1 22:17:52 INFO - moznetwork == 0.27 22:17:52 INFO - mozprocess == 0.22 22:17:52 INFO - mozprofile == 0.28 22:17:52 INFO - mozrunner == 6.11 22:17:52 INFO - mozscreenshot == 0.1 22:17:52 INFO - mozsystemmonitor == 0.0 22:17:52 INFO - moztest == 0.7 22:17:52 INFO - mozversion == 1.4 22:17:52 INFO - psutil == 3.1.1 22:17:52 INFO - requests == 1.2.3 22:17:52 INFO - wsgiref == 0.1.2 22:17:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:52 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 0x7f1be9f88f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144e410>, '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 0x144f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144f7a0>, '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': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:17:52 INFO - Using env: {'DISPLAY': ':0', 22:17:52 INFO - 'HOME': '/home/cltbld', 22:17:52 INFO - 'LANG': 'en_US.UTF-8', 22:17:52 INFO - 'LANGUAGE': 'en_US:en', 22:17:52 INFO - 'LOGNAME': 'cltbld', 22:17:52 INFO - 'MAIL': '/var/mail/cltbld', 22:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:52 INFO - 'MOZ_NO_REMOTE': '1', 22:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:52 INFO - 'NO_EM_RESTART': '1', 22:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:52 INFO - 'PWD': '/builds/slave/test', 22:17:52 INFO - 'SHELL': '/bin/bash', 22:17:52 INFO - 'SHLVL': '1', 22:17:52 INFO - 'TERM': 'linux', 22:17:52 INFO - 'TMOUT': '86400', 22:17:52 INFO - 'USER': 'cltbld', 22:17:52 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 22:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:52 INFO - '_': '/tools/buildbot/bin/python'} 22:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:17:52 INFO - Cleaning up... 22:17:52 INFO - Return code: 0 22:17:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:52 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 0x7f1be9f88f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144e410>, '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 0x144f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144f7a0>, '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': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:17:52 INFO - Using env: {'DISPLAY': ':0', 22:17:52 INFO - 'HOME': '/home/cltbld', 22:17:52 INFO - 'LANG': 'en_US.UTF-8', 22:17:52 INFO - 'LANGUAGE': 'en_US:en', 22:17:52 INFO - 'LOGNAME': 'cltbld', 22:17:52 INFO - 'MAIL': '/var/mail/cltbld', 22:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:52 INFO - 'MOZ_NO_REMOTE': '1', 22:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:52 INFO - 'NO_EM_RESTART': '1', 22:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:52 INFO - 'PWD': '/builds/slave/test', 22:17:52 INFO - 'SHELL': '/bin/bash', 22:17:52 INFO - 'SHLVL': '1', 22:17:52 INFO - 'TERM': 'linux', 22:17:52 INFO - 'TMOUT': '86400', 22:17:52 INFO - 'USER': 'cltbld', 22:17:52 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 22:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:52 INFO - '_': '/tools/buildbot/bin/python'} 22:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:52 INFO - Downloading/unpacking jsonschema 22:17: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 22:17: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 22:17: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 22:17: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 22:17: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 22:17: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 22:17:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:17:54 INFO - Downloading/unpacking functools32 (from jsonschema) 22:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:55 INFO - Downloading functools32-3.2.3-2.tar.gz 22:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:17:55 INFO - warning: no files found matching '*.txt' 22:17:55 INFO - no previously-included directories found matching 'build' 22:17:55 INFO - no previously-included directories found matching 'dist' 22:17:55 INFO - no previously-included directories found matching '.git*' 22:17:55 INFO - Installing collected packages: jsonschema, functools32 22:17:55 INFO - Running setup.py install for functools32 22:17:55 INFO - warning: no files found matching '*.txt' 22:17:55 INFO - no previously-included directories found matching 'build' 22:17:55 INFO - no previously-included directories found matching 'dist' 22:17:55 INFO - no previously-included directories found matching '.git*' 22:17:55 INFO - Successfully installed jsonschema functools32 22:17:55 INFO - Cleaning up... 22:17:55 INFO - Return code: 0 22:17:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:55 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 0x7f1be9f88f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144e410>, '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 0x144f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144f7a0>, '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': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:17:55 INFO - Using env: {'DISPLAY': ':0', 22:17:55 INFO - 'HOME': '/home/cltbld', 22:17:55 INFO - 'LANG': 'en_US.UTF-8', 22:17:55 INFO - 'LANGUAGE': 'en_US:en', 22:17:55 INFO - 'LOGNAME': 'cltbld', 22:17:55 INFO - 'MAIL': '/var/mail/cltbld', 22:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:55 INFO - 'MOZ_NO_REMOTE': '1', 22:17:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:55 INFO - 'NO_EM_RESTART': '1', 22:17:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:55 INFO - 'PWD': '/builds/slave/test', 22:17:55 INFO - 'SHELL': '/bin/bash', 22:17:55 INFO - 'SHLVL': '1', 22:17:55 INFO - 'TERM': 'linux', 22:17:55 INFO - 'TMOUT': '86400', 22:17:55 INFO - 'USER': 'cltbld', 22:17:55 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 22:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:55 INFO - '_': '/tools/buildbot/bin/python'} 22:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:55 INFO - Downloading/unpacking flake8 22:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:17:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:17:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:17:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:17:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:17:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:17:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:17:58 INFO - no previously-included directories found matching 'docs/_build' 22:17:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:17:58 INFO - Running setup.py install for pep8 22:17:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:17:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:17:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:17:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:17:58 INFO - no previously-included directories found matching 'docs/_build' 22:17:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:17:58 INFO - Cleaning up... 22:17:58 INFO - Return code: 0 22:17:58 INFO - Running post-action listener: _resource_record_post_action 22:17:58 INFO - Running post-action listener: _start_resource_monitoring 22:17:58 INFO - Starting resource monitoring. 22:17:58 INFO - ##### 22:17:58 INFO - ##### Running install step. 22:17:58 INFO - ##### 22:17:58 INFO - Running pre-action listener: _resource_record_pre_action 22:17:58 INFO - Running main action method: install 22:17:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:58 INFO - Reading from file tmpfile_stdout 22:17:58 INFO - Detecting whether we're running mozinstall >=1.0... 22:17:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:17:58 INFO - Reading from file tmpfile_stdout 22:17:58 INFO - Output received: 22:17:58 INFO - Usage: mozinstall [options] installer 22:17:58 INFO - Options: 22:17:58 INFO - -h, --help show this help message and exit 22:17:58 INFO - -d DEST, --destination=DEST 22:17:58 INFO - Directory to install application into. [default: 22:17:58 INFO - "/builds/slave/test"] 22:17:58 INFO - --app=APP Application being installed. [default: firefox] 22:17:58 INFO - mkdir: /builds/slave/test/build/application 22:17:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:18:14 INFO - Reading from file tmpfile_stdout 22:18:14 INFO - Output received: 22:18:14 INFO - /builds/slave/test/build/application/firefox/firefox 22:18:14 INFO - Running post-action listener: _resource_record_post_action 22:18:14 INFO - ##### 22:18:14 INFO - ##### Running run-tests step. 22:18:14 INFO - ##### 22:18:14 INFO - Running pre-action listener: _resource_record_pre_action 22:18:14 INFO - Running main action method: run_tests 22:18:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:18:14 INFO - Python 2.7.3 22:18:14 INFO - Return code: 0 22:18:14 INFO - grabbing minidump binary from tooltool 22:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:18:14 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 0x144e410>, '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 0x144f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144f7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:18:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:18:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:18:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:18:14 INFO - Return code: 0 22:18:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:18:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:18:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:18:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:18:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:18:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:18:15 INFO - Return code: 0 22:18:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 22:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 22:18:15 INFO - Using env: {'DISPLAY': ':0', 22:18:15 INFO - 'HOME': '/home/cltbld', 22:18:15 INFO - 'LANG': 'en_US.UTF-8', 22:18:15 INFO - 'LANGUAGE': 'en_US:en', 22:18:15 INFO - 'LOGNAME': 'cltbld', 22:18:15 INFO - 'MAIL': '/var/mail/cltbld', 22:18:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:18:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:15 INFO - 'MOZ_NO_REMOTE': '1', 22:18:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:15 INFO - 'NO_EM_RESTART': '1', 22:18:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:15 INFO - 'PWD': '/builds/slave/test', 22:18:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:18:15 INFO - 'SHELL': '/bin/bash', 22:18:15 INFO - 'SHLVL': '1', 22:18:15 INFO - 'TERM': 'linux', 22:18:15 INFO - 'TMOUT': '86400', 22:18:15 INFO - 'USER': 'cltbld', 22:18:15 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1465103527.889053-2065970043', 22:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:15 INFO - '_': '/tools/buildbot/bin/python'} 22:18:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 22:18:15 INFO - mozversion application_buildid: 20160604190644 22:18:15 INFO - mozversion application_changeset: dc972d877cd697c2c8f6248534f21bb5e1dba9ae 22:18:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:18:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:18:15 INFO - mozversion application_name: Firefox 22:18:15 INFO - mozversion application_remotingname: firefox-dev 22:18:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:18:15 INFO - mozversion application_vendor: Mozilla 22:18:15 INFO - mozversion application_version: 48.0a2 22:18:15 INFO - mozversion platform_buildid: 20160604190644 22:18:15 INFO - mozversion platform_changeset: dc972d877cd697c2c8f6248534f21bb5e1dba9ae 22:18:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:18:15 INFO - mozversion platform_version: 48.0a2 22:18:15 INFO - using testdate: 1465103895 22:18:15 INFO - actual date: 1465103895 22:18:15 INFO - starting webserver on 'localhost:35607' 22:18:15 INFO - SUITE-START | Running 2 tests 22:18:15 INFO - TEST-START | dromaeo_css 22:18:15 INFO - operating with platform_type : linux_ 22:18:15 INFO - Initialising browser for dromaeo_css test... 22:18:15 INFO - TEST-INFO | started process 3259 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHPX1Pw/profile http://localhost:35607/getInfo.html) 22:18:19 INFO - PROCESS | 3259 | 22:18:19 INFO - PROCESS | 3259 | (process:3337): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:18:20 INFO - PROCESS | 3259 | __metrics Screen width/height:1600/1200 22:18:20 INFO - PROCESS | 3259 | colorDepth:24 22:18:20 INFO - PROCESS | 3259 | Browser inner width/height: 1024/697 22:18:20 INFO - PROCESS | 3259 | __metrics 22:18:21 ERROR - PROCESS | 3259 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 22:18:21 INFO - TEST-INFO | 3259: exit 0 22:18:21 INFO - Browser initialized. 22:18:21 INFO - Running cycle 1/1 for dromaeo_css test... 22:18:21 INFO - TEST-INFO | started process 3388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHPX1Pw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:18:23 INFO - PROCESS | 3388 | 22:18:23 INFO - PROCESS | 3388 | (process:3457): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:18:33 INFO - PROCESS | 3388 | RSS: Main: 152285184 22:18:33 INFO - PROCESS | 3388 | 22:19:23 INFO - PROCESS | 3388 | 1465103963244 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:21:30 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35607/tests/dromaeo/cssquery-ext.html) 22:21:31 INFO - PROCESS | 3388 | RSS: Main: 155475968 22:21:31 INFO - PROCESS | 3388 | 22:24:33 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-ext.html (next: http://localhost:35607/tests/dromaeo/cssquery-jquery.html) 22:24:34 INFO - PROCESS | 3388 | RSS: Main: 153608192 22:24:34 INFO - PROCESS | 3388 | 22:27:35 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35607/tests/dromaeo/cssquery-mootools.html) 22:27:36 INFO - PROCESS | 3388 | RSS: Main: 153292800 22:27:36 INFO - PROCESS | 3388 | 22:30:38 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35607/tests/dromaeo/cssquery-prototype.html) 22:30:38 INFO - PROCESS | 3388 | RSS: Main: 153354240 22:30:38 INFO - PROCESS | 3388 | 22:33:41 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35607/tests/dromaeo/cssquery-yui.html) 22:33:41 INFO - PROCESS | 3388 | RSS: Main: 154640384 22:33:41 INFO - PROCESS | 3388 | 22:36:43 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-yui.html (next: http://localhost:35607/tests/dromaeo/cssquery-dojo.html) 22:36:44 INFO - PROCESS | 3388 | RSS: Main: 156020736 22:36:44 INFO - PROCESS | 3388 | 22:36:44 INFO - PROCESS | 3388 | __start_tp_report 22:36:44 INFO - PROCESS | 3388 | _x_x_mozilla_page_load 22:36:44 INFO - PROCESS | 3388 | _x_x_mozilla_page_load_details 22:36:44 INFO - PROCESS | 3388 | |i|pagename|runs| 22:36:44 INFO - PROCESS | 3388 | |0;dojo.html;3354.983225083874;3392.966070339296;3430.8284585770707;3467.133216695826;3468.9306213875707;5745.712714364281;5746.798862039829;5754.683492407916;5770.538356931441;5797.333306669724;5704.572157088214;5758.913616295759;5788.392218817016;5797.130430435437;5798.362180160179;5141.717205553693;5199.376074871021;5241.318628578299;5250.868728281805;5289.973550132263;5154.69071855688;5268.1834315680935;5355.357357117151;5363.865903352408;5370.677759334452;5192;5210.478952104778;5227.712475813829;5292;5313.840584782443;5262.868428289305;5281.970015846911;5284.207368894658;5294.232336311252;5301.522862942354;5076.720780357079;5134.948650513485;5193.350831146106;5249.8425047248265;5260;5498.147787093007;5548.14003829403;5582.413846546095;5616.5787565932715;5641.266635337421;6014.30835453924;6033.5173186145;6048.727807248684;6064.332923378425;6074.696265186767;6083.969580152071;6094.177286066369;6097.3292937776805;6114.297368146132;6114.419025732089;5781.103928891029;5798.362180160179;5811.244538210006;5825.33008703996;5828.0966450200285;6285.779997700059;6310.810675679645;6328.620282782956;6339.366063393623;6341.207349081365;5827.24245848037;5950.464458198783;6038.788642397583;6091.329953705089;6102.267727872683;6096.512279017668;6119.632822030692;6120.44915957566;6133.478654314344;6139.4781443577795;5989.970050149808;6016.969915150484;6078.908816367758;6091.756329746849;6102.481289090389;5939.643621382731;6067.2112625359305;6069.514438844882;6109.28320792043;6122.510199184055;4620.607248383925;4624.907501850012;4626.791794369262;4633.791479383369;4682.555157260055;4175.519815221289;4178.770167640748;4182.393552934807;4185.623293903503;4192.454980852531;5798.898209340212;5923.763049478059;5992.400759923973;6029.155918171459;6043.848903777441;6044.365341639153;6048.425399587032;6054.8183554493435;6141.570090093651;6187.721552530057;5883.735231914574;5920.111983202468;5923.259592550931;5925.200097986803;5937.732802023919;5185.377754669464;5257.921131183035;5270.97216042868;5274.446183150791;5338.599605029638;6351.904721429182;6398.712057957403;6408.743650254031;6413.326600706859;6415.454686351619;6135.815925522063;6146.5390095742105;6152.661603611755;6194.38056194377;6196.628202307876;6153.353897840662;6299.244090709144;6397.968010160105;6410.1987251593555;6467.708953097216;6041.69791510414;6066.90795656786;6078.118672792332;6084.787032453843;6095.35998720128;6137.890418342636;6164.137020816999;6170.290416602148;6171.783987560414;6173.228346456693;5938.376470470711;6020.608660437058;6075.605085669417;6086.360932102066;6121.0206366981065;5958.404159584007;5961.701914904324;5962.046072628359;5991.520678345809;6017.398260174123;5762.1068734346245;5862.091375836752;5874.765009399576;5878.412158784087;5881;6099.268087829489;6119.289302195794;6127.5866022601795;6134.9079763804475;6141.975154597598;5897.174395584708;5904.911426328695;5910.911336330045;5921.5558833086825;5932.406759324033 22:36:44 INFO - PROCESS | 3388 | |1;ext.html;675.6520391998121;693.3101563943874;706.8586282743449;746.3991486853083;780.9496227573911;38974.41538376923;40287.79856100719;40410.79794601023;40448.393274100876;40714.7964260179;41573.168536629324;41981.160376792446;42172.36741448881;42245.155096898045;42253.15493690124;8613.569321533914;8794.12058794122;8815.823683526325;8819.470831750083;8820.20618144367;8544.829103417927;8689.869651955225;8894.688685895995;8904.243139333164;8919.108089191093;8894.555272236395;8943.016268210524;8958.059403762609;8958.552072396387;8994.86507702385;8788.780280492974;8902.643894244255;8917.242034427081;8933.196012358854;8947.105289471034;8869.778255543599;8895.421797583136;8969.686060987899;8971.730848074569;8976;34271.143221419414;41952.58047419517;42370.57629423697;42458.787706061274;42739.78630106829;3182.458981973047;3797.905052373685;3837.9808100959317;3934.6065393460713;4514.480834703985;28570.71429285722;28597;28796.71203287961;28800.135995920155;29180.270493237625;4281.764502952336;4303.784810759481;4396;4404.339349097629;4425.114977004612;247448;249113;250721;250834;251193.7440312805;36691.63308366935;36819.263614727555;36928.81535592359;36937.261254774756;39751;37516.249675006344;37828;37847.432288515694;37863.05342366463;37911.62088379136;17108.230129644195;17209.053502057508;17306.826931730775;17367;17386.739198911775;14129.152250864981;14139.50511732105;14184.290785460686;14203.928980355238;14269.42922283097;9165;9223.446593204295;9271.536423178813;9400.48297343639;9457.290703197288;3600.4059330210744;3631.2737452509605;3652.981735091361;3683.613220611851;3709.239605880788;1142.9541969098348;1145.2441388683428;1195.7010747313172;1259.8362212912446;1310.4627102887962;29763.702362976524;30083.398332033237;30497;31254;31711.682883171336;1191.5055252070467;1222.9510819567295;1250.493550112206;1299.4184455731688;1326.3633455941201;16821.2430440628;16896.46927552412;17136;17152.742708859376;17182.31270749156;532591;536803;539382;541759;545038;60917.69541152354;61049.38950610526;61372;61526.69236653788;61924.69037654873;1420.2472689474596;1443.9711205775825;1448.1992195870864;1450.5938337265582;1463.9020734449164;30881.376411085766;31802.204944876565;31971.520427193147;32160.356792863542;32799.18002049872;54958.17562736642;55475;55533.44466555283;55612.72193639006;55684.443155567926;458559;458886;459739;461035;462257;46614.76692616515;46722.53277467318;47224;48089.5191048085;48731.512684872694;51084.744576276884;51556.484435155166;52404.47595523996;52869.7356513215;53033.20450193173;42659;43539.56460435355;43546;43671.78164109159;44814;43508.56491435045;43826.78086609547;43936.780316098215;43983.340249895635;44261.336079959474;43336.349954750076;44090.338644920994;44307.77846110749;44489.777551112034;44494.777526112164;36554.451683224244;36691;36857;37196.07009824776;37964.24071518499;29098.27254318659;29226.269343266587;29489.410211796072;29532.261693457836;29800.552991705576 22:36:44 INFO - PROCESS | 3388 | |2;jquery.html;428.94423724915754;431.2022757897891;437.30043829204925;440.6959198153275;443.2730322271473;18586.16362263698;18818.717719234206;19305.806941930594;19360.70958935617;19532.70700939487;19004.524886877836;19089;19586.412407627795;19855.40433786985;20086.89956550219;5222.242774797652;5432.837014889559;5447.972760136194;5540.972295138528;5803.912941305884;5292.391374991887;5432.13085906255;5441;5447.264619276407;5457.972710136463;5658.773649054054;5668.234788303588;5676.432356764331;5697.515711164556;5718.542516598662;5603.803866864681;5607.635503692288;5612.382637909826;5616.410276920926;5630.0991841305195;5751.194832723422;5759.68321742304;5781.53747700183;5791.855203619901;5816.854578635525;14151.221682807442;14462.638434039129;18082.457526274233;24321.391965200834;24758.5048299033;2476.925692229236;2521.155412936668;2637.591173368131;2799.4820958122814;2828.632277803893;10327.58689652413;10599.258051936367;10621.097206737437;12693.809592856114;15112.09327440357;2010.4571765623346;2132.657692480342;2158.287765037534;2164.7077644517944;2194.3416974907623;84496.57751711263;85747.57126214392;86858.56570717106;87750.12249877419;89140;11786.646400607995;11847.585334513251;11965.102617303737;11984.161108722481;12155.878441215651;16480.0935948524;16705.498835034967;16712.665746684997;16721.74917376216;16735.1632418381;9275.58259878307;9367.203787677987;9377.906220937839;9397.248220142372;9516.19112375442;9104.772380690536;9178.449293042306;9254.398464099811;9292.117248861347;9296.95351523238;4996.4254110777;5060.240963855451;5107.770150343169;5246.554042906319;5260.105782017066;1990.7810140884492;2122.395117391536;2210.502636906683;2213.181122984506;2217.7671344508917;1001.0240015984357;1089.096050278267;1135.2450620337383;1162.7790719763218;1182.1252273317855;18889.338873139648;19256.422307330802;19453.51366215856;19787.505312367306;19994.800051999293;1110.9388983605818;1132.6957561267152;1133.0312582741637;1164.5807509296646;1201.897838683704;10834.837477437844;11330.8615572189;11340.319580825177;11348.432578371214;11428.942855285837;542761;557995;566319;569938;572663;99837;100052.49973750085;100182.4990875041;100718;104140;1188.1860925266221;1188.9048876089892;1195.3664557784389;1198.7482628647908;1221.8106193540016;17491.912540437217;17832;17865.642687146443;18179;18330.266789328278;37668.62331376651;38293.617063829006;41702.79148604238;42449.151016980104;42748;51587.74206128946;52027.47972520226;52057.47942520678;52525.212121817436;52713;8509.957450212709;8510.659573617233;8649.3512986525;8699.08659590759;8801.07588703177;7978.082520509984;10387.532561034923;13574.321283935962;13980.161190328612;14179.14925104497;11383.487743051748;11773.411329433666;11847.229930054518;12496.000319974562;12646.30445325516;5154.474307589329;8993.28053755685;11482.081433485468;11668.358240296526;12011.879881201075;11042.558297668003;11764.529418823493;11869.347185904859;12101.576444824388;12540.686482838002;8478.618462169341;8649.740507784776;8700.17348351931;8989.640414383352;9164.358494905558;11369.2609980351;12193.878061219275;12217;12247.203931744409;12473.126881118598 22:36:44 INFO - PROCESS | 3388 | |3;mootools.html;1632.4776071657068;1642.6961012212737;1852.833245007949;1933.8452923766108;1977.7033444983242;7837.21627837216;8357.582120893947;8736.956315218424;8937.64249430022;8953;8557;8630.827383452328;8638.395312328139;8703.303735701145;8734.082921293251;2454.7667971542674;2480.107161421896;2482.354587807174;2482.366996415911;2490.3276115448816;2458.8770561471943;2485.130204428445;2488.278399264211;2488.5147396257735;2489.5643262429094;2453.828232023759;2455.2511483997373;2571.2928944540213;2579.071534247669;2580.780633646142;2459.483508463225;2498.475320182764;2513.0450428837107;2516.5973444248925;2523.3439305780444;2430.1980346485616;2443.572374834415;2443.7556244375596;2448.43685952232;2497.013679596549;8911.866322005175;8971.865422018675;9014.819703605892;9024.413413128126;9031.412958157698;5488.286522752057;5587.888242235133;5644.491995720364;5650.660960342351;5659.094544872801;3510.104923244556;3572.464130380438;3584.9641503585153;3719.4420836874415;3753.7935413552245;1157.1784033336342;1171.806651902435;1181.102362204722;1183.8520184976878;1224.4306397525193;12646.557370491988;12672.556460523929;12695;12777.616671499869;12798;4839.128956787812;4963.8262660806695;4970.627202959792;5063.772130254148;5063.797448102108;10591.152707783513;10623.946880265703;10655.893441065646;10664.466776661136;10686.73283167927;5668.801591944262;5745.540356771448;5746.051901436216;5756.309242890796;5765.394633563499;4438.533953934855;4811.78346974387;4845.903081938412;4888.388951381077;5190.065788158092;4239.385289133058;4252.29837076885;4335.804888780012;4356;4387.144506821203;1381.530279704905;1385.4596707284168;1407.5706909392734;1420.1266221273843;1424.430227908824;1035.5495359518634;1037.5642230263309;1062.3997441445558;1075.5375188668947;1076.5962763963514;6040.395960403925;6112.266528016667;6159.168512250879;6160.383961603804;6307.684615769193;486.4600293674019;489.9118158731463;491.17482629183314;1109.378747901162;1123.017359810166;4115.135821477524;4216.430781844411;4229.619334259932;4276.657867370603;4290.785460727014;10627.468626568641;10853.728656783644;10995.670129896116;11016.834747478793;11054.944725276322;10898.509567069501;10988.230823842414;11009.779804403866;11029.338239705643;11066.059384952368;7830.295273425305;8075.111737708848;8480.279176269964;8482.151784821468;8537.786555336043;5668.149777533403;5669.744861481161;5683.60214784961;5727.942720572741;5830.067189249699;9505.239580833379;9584.856227156459;9648.517574121406;9658.275629327969;9678.75803104928;9534.380265282734;9680.612775488902;9815.705528834147;9848.261380396356;9872.308938374244;7083.362497375261;7255.383292420203;7274.781756547312;7364.815879603053;7392.852142957003;8460.661573537236;8461;8491.702790402307;8535.231829135158;8557.529335886586;7584.886226706493;7750.689972401041;7762.495437796525;7774.067111946602;7835.177306382748;7527.661255243636;7676.040494938133;7695.076590808915;7718.343940764873;7756.8448631028195;7207.387372073433;7222.494425390172;7223.349898509159;7263.201047884623;7272.672729727069;5161.7677204525135;5167.870803229799;5256.185291279858;5285.68285902847;5381.327334083239;5292.285541451854;5373.167159090347;5396.541293989898;5398.163284690879;5499.477549632778 22:36:44 INFO - PROCESS | 3388 | |4;prototype.html;367.65624141427764;370.44618295648;374.0368550981223;380.1598467387074;383.4210342383001;6455.257645370783;6619.238787539434;6619.30497297784;6670.199576050874;6711.496637752164;6595.7691480798185;6599.043138744891;6606.636634985086;6611.809874222638;6623.039659249418;5005.074061298671;5015.774290156952;5019.749012549376;5030.622703297258;5054.519820617035;5054.064997975368;5065.113605119108;5070.670406423589;5076.03555324489;5078.847634570969;5023.598112151038;5024.799008039692;5026.246063090529;5028.371453568304;5029;4786.880327991793;4920.778564964585;5025.195968645017;5028.296038554606;5034.068697290994;4278.144371125787;4302.483701955785;4304.784760761981;4308.9353659695125;4317.870463886088;6301.046675530554;6310.810675679737;6318.241810982666;6326.525510586678;6332.366763323676;5781.277340332458;5784.305883294031;5785.161151633031;5792.68140252286;5813.796517121923;6888.207856096513;6905.930940690579;6915;6916.170059592881;6957.9652101739675;4661.953380466186;4813.422389313269;4814.951850481485;4831.975840120812;4833.540813622701;7238.384737297336;7279.490435669505;7304.853902921965;7330.8900366494545;7335.743248986313;4894.412670479565;4909.067277217388;4920.434150072716;4922.950770492321;4933.3586633738105;4984.925226121611;4989.301497790312;4991.850244492671;4993.251012348104;4999.475055119233;5788.594798364158;5793.2179155814265;5798.072308430631;5801.187833703285;5808.30300363959;6780.288069752703;7036.577805331696;7037.507374483839;7077.5753454792875;7087.397571206402;1742.1115231232163;1742.7560141580188;1761.3130878957218;1762.1013283225648;1767.734839774044;1097.0894157848966;1126.695792136135;1127.402476687357;1136.8522092128135;1138.9373584452935;6736.831579210559;6810.080639113756;6812.591244525344;6816.113905192294;6816.352446517573;7161.746694328472;7168.2831716827895;7189.424846012411;7232.74685386009;7243.0946131733535;6711.228208756055;6750.831229219309;6754.527183097234;6762.729490820312;6781.728730850809;3869.342211823997;3896.688264938798;3903.8243279052513;3911.2764138634443;3911.9608803912165;7425.1832298447125;7617.27635874591;7628.427867909929;7681.539107653559;7701.075870895416;7396.260373962668;7409.629518524052;7420.740274090381;7425.888611670721;7462.291082347168;7643.770686879402;7652.923470765332;7656.846863062819;7669.348105410991;7672.616369181407;7113.0753002109395;7256.600886951268;7290.854182916418;7302.853942921006;7318.817029574303;7190.245024272585;7205.567665940061;7209.3872020876715;7223.494355395072;7257.854842903218;7556.508826926232;7581.54510729358;7607.771766847003;7640.923590764021;7656.081270247606;4911.656184067081;4951.900961980812;4954.108260513142;4964.478729733326;4965.528274813887;7052.612106334201;7240.565566066053;7289.781306560812;7299.817504562216;7305.488615796843;7031.1211098612675;7076.398506126831;7079.469039821931;7080.504364694628;7082.256363081802;7002.474814388839;7017.157941046903;7021.789346319619;7024.103492057218;7027.508074434945;6901.654917254217;6906.689198985957;6911.93088069133;6935.479839011993;6944.965275173794;6989.720411183699;7117.6085315308155;7123.608201548757;7124.287571242834;7133.286671332825;6796.388325050769;6882.965585172243;6888.276730943379;6982.476314276551;6987.161540615159 22:36:44 INFO - PROCESS | 3388 | |5;yui.html;929.6467342409883;936.939098958568;952.2572393533043;960.7261930349852;962.7015625156198;15093.169875656835;15277.923610381933;15330.923345383271;15413.69172616547;15972.520824375259;14846.331915063822;14849;14854.777178342334;14911.552653420415;14948.925255373735;912.2154946745793;938.3712912348709;940.6190492850407;958.0611001218809;965.478641533571;954.7517645412208;955.4362925873733;961.8605302231207;964.7298756348234;964.8118616869679;993.438707130469;994.4530508220486;994.9702058369589;995.1590905684685;996.2727209137335;975;978.9216862651007;980.460746589373;982.9655962041294;985.2118305355687;721.7077083781077;723.9167495738005;724.2757242757243;724.351705223827;724.7318492157935;15817;15825.841741582668;15897.682046359123;15913.522594322189;15987.280572374271;14666.853331466762;15027.699446011127;15051.397944082224;15225.086494810346;15237.314320855588;12200.328981905992;12526.185797923197;12526.248425094433;12666.43001064945;12746.171498852545;4043.4137050127565;4053.9594604053877;4055.188962207541;4111.403846442249;4129.855455059059;56929.43070569283;57290.713546432;58508.70745646287;59085.70457147687;59478;11875.109366797526;11886.212706908584;11985.34080625574;12212.450439730055;12270.570530031406;21182.47043823886;21273.255436059662;21297.68053479199;21448.678269825967;21515.8924205378;8020.238077382639;8042.075161356519;8062.637181326854;8146.144654811278;8328.541930193898;5079.4412614612365;5088;5111.437741848394;5174.818881339211;5199.688018718889;3667.7065834733157;3712.350338690718;3718.4608231806237;3733.962660373416;3748.9062773430883;636.9108324834527;654.0385633119307;655.6000839487908;662.1326062857672;678.2302087131142;822.6257053040852;822.7120507822215;846.7671390367698;850.7235148576738;854.4104567848165;9848.21214302854;10425.322354046963;10503.369797812156;10562.683119506428;10884.782304353868;757.6552668535803;830.3056581393417;845.725139329708;847.2544161138159;865.1910463716446;4907.754612269372;4912.95087049125;4925.605951523941;4980.576650984707;5039.848804535796;67482.66258668773;67636.6618166906;67912;68764.31235687579;68956;55194;55601.16598250948;56190.15714764282;56534;57676.42323576794;49822.75088624606;49837;49854;50193.24710129351;52928.73535632374;9833;9980.328195423135;9999.55127661801;10137.239707021856;10290.228232882713;39374.80312598419;39710;39813;40689.59310406858;40745.79627101846;49786.75106624444;51012.489875102256;51265.23102153396;51798.22302665538;52154.21768673549;12604.577781980768;12832.29422381778;12914.935425322814;13183.868161318263;13249.006324525506;14579.271036448346;14599.562013139623;14622.268886555417;14818.481353152589;14917.328720207399;13281.535146269835;13536;13540.390682419511;13831.170129792245;14037.438502459787;13088.214707117604;13116.606501804961;13261.801072984106;13445.394957227143;14219;12806;13380.799288010883;13707.588772336845;13736;13810.516631917835;11714.941425292818;11768.470418831344;11918.52325906954;11943.880561194277;12105.757884842078;11434.25677331004;11787.72706624692;11927.82108268394;11979.940100299791;11991.22057066288 22:36:44 INFO - PROCESS | 3388 | __end_tp_report 22:36:44 INFO - PROCESS | 3388 | __start_cc_report 22:36:44 INFO - PROCESS | 3388 | _x_x_mozilla_cycle_collect,321 22:36:44 INFO - PROCESS | 3388 | __end_cc_report 22:36:44 INFO - PROCESS | 3388 | __startTimestamp1465105004026__endTimestamp 22:36:44 INFO - PROCESS | 3388 | 22:36:44 INFO - PROCESS | 3388 | ------- Summary: start ------- 22:36:44 INFO - PROCESS | 3388 | Number of tests: 6 22:36:44 INFO - PROCESS | 3388 | 22:36:44 INFO - PROCESS | 3388 | [#0] dojo.html Cycles:165 Average:5709.50 Median:5937.73 stddev:637.43 (10.7%) stddev-sans-first:612.04 22:36:44 INFO - PROCESS | 3388 | Values: 3355.0 3393.0 3430.8 3467.1 3468.9 5745.7 5746.8 5754.7 5770.5 5797.3 5704.6 5758.9 5788.4 5797.1 5798.4 5141.7 5199.4 5241.3 5250.9 5290.0 5154.7 5268.2 5355.4 5363.9 5370.7 5192.0 5210.5 5227.7 5292.0 5313.8 5262.9 5282.0 5284.2 5294.2 5301.5 5076.7 5134.9 5193.4 5249.8 5260.0 5498.1 5548.1 5582.4 5616.6 5641.3 6014.3 6033.5 6048.7 6064.3 6074.7 6084.0 6094.2 6097.3 6114.3 6114.4 5781.1 5798.4 5811.2 5825.3 5828.1 6285.8 6310.8 6328.6 6339.4 6341.2 5827.2 5950.5 6038.8 6091.3 6102.3 6096.5 6119.6 6120.4 6133.5 6139.5 5990.0 6017.0 6078.9 6091.8 6102.5 5939.6 6067.2 6069.5 6109.3 6122.5 4620.6 4624.9 4626.8 4633.8 4682.6 4175.5 4178.8 4182.4 4185.6 4192.5 5798.9 5923.8 5992.4 6029.2 6043.8 6044.4 6048.4 6054.8 6141.6 6187.7 5883.7 5920.1 5923.3 5925.2 5937.7 5185.4 5257.9 5271.0 5274.4 5338.6 6351.9 6398.7 6408.7 6413.3 6415.5 6135.8 6146.5 6152.7 6194.4 6196.6 6153.4 6299.2 6398.0 6410.2 6467.7 6041.7 6066.9 6078.1 6084.8 6095.4 6137.9 6164.1 6170.3 6171.8 6173.2 5938.4 6020.6 6075.6 6086.4 6121.0 5958.4 5961.7 5962.0 5991.5 6017.4 5762.1 5862.1 5874.8 5878.4 5881.0 6099.3 6119.3 6127.6 6134.9 6142.0 5897.2 5904.9 5910.9 5921.6 5932.4 22:36:44 INFO - PROCESS | 3388 | 22:36:44 INFO - PROCESS | 3388 | [#1] ext.html Cycles:180 Average:57553.98 Median:42311.87 stddev:115474.80 (272.9%) stddev-sans-first:115719.77 22:36:44 INFO - PROCESS | 3388 | Values: 675.7 693.3 706.9 746.4 780.9 38974.4 40287.8 40410.8 40448.4 40714.8 41573.2 41981.2 42172.4 42245.2 42253.2 8613.6 8794.1 8815.8 8819.5 8820.2 8544.8 8689.9 8894.7 8904.2 8919.1 8894.6 8943.0 8958.1 8958.6 8994.9 8788.8 8902.6 8917.2 8933.2 8947.1 8869.8 8895.4 8969.7 8971.7 8976.0 34271.1 41952.6 42370.6 42458.8 42739.8 3182.5 3797.9 3838.0 3934.6 4514.5 28570.7 28597.0 28796.7 28800.1 29180.3 4281.8 4303.8 4396.0 4404.3 4425.1 247448.0 249113.0 250721.0 250834.0 251193.7 36691.6 36819.3 36928.8 36937.3 39751.0 37516.2 37828.0 37847.4 37863.1 37911.6 17108.2 17209.1 17306.8 17367.0 17386.7 14129.2 14139.5 14184.3 14203.9 14269.4 9165.0 9223.4 9271.5 9400.5 9457.3 3600.4 3631.3 3653.0 3683.6 3709.2 1143.0 1145.2 1195.7 1259.8 1310.5 29763.7 30083.4 30497.0 31254.0 31711.7 1191.5 1223.0 1250.5 1299.4 1326.4 16821.2 16896.5 17136.0 17152.7 17182.3 532591.0 536803.0 539382.0 541759.0 545038.0 60917.7 61049.4 61372.0 61526.7 61924.7 1420.2 1444.0 1448.2 1450.6 1463.9 30881.4 31802.2 31971.5 32160.4 32799.2 54958.2 55475.0 55533.4 55612.7 55684.4 458559.0 458886.0 459739.0 461035.0 462257.0 46614.8 46722.5 47224.0 48089.5 48731.5 51084.7 51556.5 52404.5 52869.7 53033.2 42659.0 43539.6 43546.0 43671.8 44814.0 43508.6 43826.8 43936.8 43983.3 44261.3 43336.3 44090.3 44307.8 44489.8 44494.8 36554.5 36691.0 36857.0 37196.1 37964.2 29098.3 29226.3 29489.4 29532.3 29800.6 22:36:44 INFO - PROCESS | 3388 | 22:36:44 INFO - PROCESS | 3388 | [#2] jquery.html Cycles:180 Average:31314.01 Median:2202.42 stddev:92519.41 (4200.8%) stddev-sans-first:92749.88 22:36:44 INFO - PROCESS | 3388 | Values: 428.9 431.2 437.3 440.7 443.3 18586.2 18818.7 19305.8 19360.7 19532.7 19004.5 19089.0 19586.4 19855.4 20086.9 5222.2 5432.8 5448.0 5541.0 5803.9 5292.4 5432.1 5441.0 5447.3 5458.0 5658.8 5668.2 5676.4 5697.5 5718.5 5603.8 5607.6 5612.4 5616.4 5630.1 5751.2 5759.7 5781.5 5791.9 5816.9 14151.2 14462.6 18082.5 24321.4 24758.5 2476.9 2521.2 2637.6 2799.5 2828.6 10327.6 10599.3 10621.1 12693.8 15112.1 2010.5 2132.7 2158.3 2164.7 2194.3 84496.6 85747.6 86858.6 87750.1 89140.0 11786.6 11847.6 11965.1 11984.2 12155.9 16480.1 16705.5 16712.7 16721.7 16735.2 9275.6 9367.2 9377.9 9397.2 9516.2 9104.8 9178.4 9254.4 9292.1 9297.0 4996.4 5060.2 5107.8 5246.6 5260.1 1990.8 2122.4 2210.5 2213.2 2217.8 1001.0 1089.1 1135.2 1162.8 1182.1 18889.3 19256.4 19453.5 19787.5 19994.8 1110.9 1132.7 1133.0 1164.6 1201.9 10834.8 11330.9 11340.3 11348.4 11428.9 542761.0 557995.0 566319.0 569938.0 572663.0 99837.0 100052.5 100182.5 100718.0 104140.0 1188.2 1188.9 1195.4 1198.7 1221.8 17491.9 17832.0 17865.6 18179.0 18330.3 37668.6 38293.6 41702.8 42449.2 42748.0 51587.7 52027.5 52057.5 52525.2 52713.0 8510.0 8510.7 8649.4 8699.1 8801.1 7978.1 10387.5 13574.3 13980.2 14179.1 11383.5 11773.4 11847.2 12496.0 12646.3 5154.5 8993.3 11482.1 11668.4 12011.9 11042.6 11764.5 11869.3 12101.6 12540.7 8478.6 8649.7 8700.2 8989.6 9164.4 11369.3 12193.9 12217.0 12247.2 12473.1 22:36:44 INFO - PROCESS | 3388 | 22:36:44 INFO - PROCESS | 3388 | [#3] mootools.html Cycles:180 Average:5889.74 Median:4967.23 stddev:3245.06 (65.3%) stddev-sans-first:3238.39 22:36:44 INFO - PROCESS | 3388 | Values: 1632.5 1642.7 1852.8 1933.8 1977.7 7837.2 8357.6 8737.0 8937.6 8953.0 8557.0 8630.8 8638.4 8703.3 8734.1 2454.8 2480.1 2482.4 2482.4 2490.3 2458.9 2485.1 2488.3 2488.5 2489.6 2453.8 2455.3 2571.3 2579.1 2580.8 2459.5 2498.5 2513.0 2516.6 2523.3 2430.2 2443.6 2443.8 2448.4 2497.0 8911.9 8971.9 9014.8 9024.4 9031.4 5488.3 5587.9 5644.5 5650.7 5659.1 3510.1 3572.5 3585.0 3719.4 3753.8 1157.2 1171.8 1181.1 1183.9 1224.4 12646.6 12672.6 12695.0 12777.6 12798.0 4839.1 4963.8 4970.6 5063.8 5063.8 10591.2 10623.9 10655.9 10664.5 10686.7 5668.8 5745.5 5746.1 5756.3 5765.4 4438.5 4811.8 4845.9 4888.4 5190.1 4239.4 4252.3 4335.8 4356.0 4387.1 1381.5 1385.5 1407.6 1420.1 1424.4 1035.5 1037.6 1062.4 1075.5 1076.6 6040.4 6112.3 6159.2 6160.4 6307.7 486.5 489.9 491.2 1109.4 1123.0 4115.1 4216.4 4229.6 4276.7 4290.8 10627.5 10853.7 10995.7 11016.8 11054.9 10898.5 10988.2 11009.8 11029.3 11066.1 7830.3 8075.1 8480.3 8482.2 8537.8 5668.1 5669.7 5683.6 5727.9 5830.1 9505.2 9584.9 9648.5 9658.3 9678.8 9534.4 9680.6 9815.7 9848.3 9872.3 7083.4 7255.4 7274.8 7364.8 7392.9 8460.7 8461.0 8491.7 8535.2 8557.5 7584.9 7750.7 7762.5 7774.1 7835.2 7527.7 7676.0 7695.1 7718.3 7756.8 7207.4 7222.5 7223.3 7263.2 7272.7 5161.8 5167.9 5256.2 5285.7 5381.3 5292.3 5373.2 5396.5 5398.2 5499.5 22:36:44 INFO - PROCESS | 3388 | 22:36:44 INFO - PROCESS | 3388 | [#4] prototype.html Cycles:180 Average:5869.69 Median:6724.16 stddev:1796.19 (26.7%) stddev-sans-first:1753.11 22:36:44 INFO - PROCESS | 3388 | Values: 367.7 370.4 374.0 380.2 383.4 6455.3 6619.2 6619.3 6670.2 6711.5 6595.8 6599.0 6606.6 6611.8 6623.0 5005.1 5015.8 5019.7 5030.6 5054.5 5054.1 5065.1 5070.7 5076.0 5078.8 5023.6 5024.8 5026.2 5028.4 5029.0 4786.9 4920.8 5025.2 5028.3 5034.1 4278.1 4302.5 4304.8 4308.9 4317.9 6301.0 6310.8 6318.2 6326.5 6332.4 5781.3 5784.3 5785.2 5792.7 5813.8 6888.2 6905.9 6915.0 6916.2 6958.0 4662.0 4813.4 4815.0 4832.0 4833.5 7238.4 7279.5 7304.9 7330.9 7335.7 4894.4 4909.1 4920.4 4923.0 4933.4 4984.9 4989.3 4991.9 4993.3 4999.5 5788.6 5793.2 5798.1 5801.2 5808.3 6780.3 7036.6 7037.5 7077.6 7087.4 1742.1 1742.8 1761.3 1762.1 1767.7 1097.1 1126.7 1127.4 1136.9 1138.9 6736.8 6810.1 6812.6 6816.1 6816.4 7161.7 7168.3 7189.4 7232.7 7243.1 6711.2 6750.8 6754.5 6762.7 6781.7 3869.3 3896.7 3903.8 3911.3 3912.0 7425.2 7617.3 7628.4 7681.5 7701.1 7396.3 7409.6 7420.7 7425.9 7462.3 7643.8 7652.9 7656.8 7669.3 7672.6 7113.1 7256.6 7290.9 7302.9 7318.8 7190.2 7205.6 7209.4 7223.5 7257.9 7556.5 7581.5 7607.8 7640.9 7656.1 4911.7 4951.9 4954.1 4964.5 4965.5 7052.6 7240.6 7289.8 7299.8 7305.5 7031.1 7076.4 7079.5 7080.5 7082.3 7002.5 7017.2 7021.8 7024.1 7027.5 6901.7 6906.7 6911.9 6935.5 6945.0 6989.7 7117.6 7123.6 7124.3 7133.3 6796.4 6883.0 6888.3 6982.5 6987.2 22:36:44 INFO - PROCESS | 3388 | 22:36:44 INFO - PROCESS | 3388 | [#5] yui.html Cycles:180 Average:16052.91 Median:4518.81 stddev:18295.75 (404.9%) stddev-sans-first:18311.82 22:36:44 INFO - PROCESS | 3388 | Values: 929.6 936.9 952.3 960.7 962.7 15093.2 15277.9 15330.9 15413.7 15972.5 14846.3 14849.0 14854.8 14911.6 14948.9 912.2 938.4 940.6 958.1 965.5 954.8 955.4 961.9 964.7 964.8 993.4 994.5 995.0 995.2 996.3 975.0 978.9 980.5 983.0 985.2 721.7 723.9 724.3 724.4 724.7 15817.0 15825.8 15897.7 15913.5 15987.3 14666.9 15027.7 15051.4 15225.1 15237.3 12200.3 12526.2 12526.2 12666.4 12746.2 4043.4 4054.0 4055.2 4111.4 4129.9 56929.4 57290.7 58508.7 59085.7 59478.0 11875.1 11886.2 11985.3 12212.5 12270.6 21182.5 21273.3 21297.7 21448.7 21515.9 8020.2 8042.1 8062.6 8146.1 8328.5 5079.4 5088.0 5111.4 5174.8 5199.7 3667.7 3712.4 3718.5 3734.0 3748.9 636.9 654.0 655.6 662.1 678.2 822.6 822.7 846.8 850.7 854.4 9848.2 10425.3 10503.4 10562.7 10884.8 757.7 830.3 845.7 847.3 865.2 4907.8 4913.0 4925.6 4980.6 5039.8 67482.7 67636.7 67912.0 68764.3 68956.0 55194.0 55601.2 56190.2 56534.0 57676.4 49822.8 49837.0 49854.0 50193.2 52928.7 9833.0 9980.3 9999.6 10137.2 10290.2 39374.8 39710.0 39813.0 40689.6 40745.8 49786.8 51012.5 51265.2 51798.2 52154.2 12604.6 12832.3 12914.9 13183.9 13249.0 14579.3 14599.6 14622.3 14818.5 14917.3 13281.5 13536.0 13540.4 13831.2 14037.4 13088.2 13116.6 13261.8 13445.4 14219.0 12806.0 13380.8 13707.6 13736.0 13810.5 11714.9 11768.5 11918.5 11943.9 12105.8 11434.3 11787.7 11927.8 11979.9 11991.2 22:36:44 INFO - PROCESS | 3388 | -------- Summary: end -------- 22:36:44 INFO - PROCESS | 3388 | 22:36:44 INFO - TEST-INFO | 3388: exit 0 22:36:44 INFO - TEST-OK | dromaeo_css | took 1109123ms 22:36:44 INFO - TEST-START | kraken 22:36:44 INFO - operating with platform_type : linux_ 22:36:44 INFO - Initialising browser for kraken test... 22:36:44 INFO - TEST-INFO | started process 3630 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2rmfCL/profile http://localhost:35607/getInfo.html) 22:36:48 INFO - PROCESS | 3630 | 22:36:48 INFO - PROCESS | 3630 | (process:3712): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:36:49 INFO - PROCESS | 3630 | __metrics Screen width/height:1600/1200 22:36:49 INFO - PROCESS | 3630 | colorDepth:24 22:36:49 INFO - PROCESS | 3630 | Browser inner width/height: 1024/697 22:36:49 INFO - PROCESS | 3630 | __metrics 22:36:49 ERROR - PROCESS | 3630 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 22:36:50 INFO - TEST-INFO | 3630: exit 0 22:36:50 INFO - Browser initialized. 22:36:50 INFO - Running cycle 1/1 for kraken test... 22:36:50 INFO - TEST-INFO | started process 3763 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2rmfCL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:36:52 INFO - PROCESS | 3763 | 22:36:52 INFO - PROCESS | 3763 | (process:3831): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:37:02 INFO - PROCESS | 3763 | RSS: Main: 152113152 22:37:02 INFO - PROCESS | 3763 | 22:37:04 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35607/tests/kraken/driver.html?testName=audio-beat-detection) 22:37:05 INFO - PROCESS | 3763 | RSS: Main: 157179904 22:37:05 INFO - PROCESS | 3763 | 22:37:08 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35607/tests/kraken/driver.html?testName=audio-dft) 22:37:08 INFO - PROCESS | 3763 | RSS: Main: 157003776 22:37:08 INFO - PROCESS | 3763 | 22:37:11 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35607/tests/kraken/driver.html?testName=audio-fft) 22:37:12 INFO - PROCESS | 3763 | RSS: Main: 157208576 22:37:12 INFO - PROCESS | 3763 | 22:37:14 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35607/tests/kraken/driver.html?testName=audio-oscillator) 22:37:15 INFO - PROCESS | 3763 | RSS: Main: 156839936 22:37:15 INFO - PROCESS | 3763 | 22:37:17 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35607/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:37:17 INFO - PROCESS | 3763 | RSS: Main: 156864512 22:37:17 INFO - PROCESS | 3763 | 22:37:20 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35607/tests/kraken/driver.html?testName=imaging-darkroom) 22:37:21 INFO - PROCESS | 3763 | RSS: Main: 157601792 22:37:21 INFO - PROCESS | 3763 | 22:37:24 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35607/tests/kraken/driver.html?testName=imaging-desaturate) 22:37:25 INFO - PROCESS | 3763 | RSS: Main: 157265920 22:37:25 INFO - PROCESS | 3763 | 22:37:28 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35607/tests/kraken/driver.html?testName=json-parse-financial) 22:37:28 INFO - PROCESS | 3763 | RSS: Main: 157241344 22:37:28 INFO - PROCESS | 3763 | 22:37:30 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35607/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:37:31 INFO - PROCESS | 3763 | RSS: Main: 156782592 22:37:31 INFO - PROCESS | 3763 | 22:37:32 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:37:33 INFO - PROCESS | 3763 | RSS: Main: 156995584 22:37:33 INFO - PROCESS | 3763 | 22:37:35 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:37:36 INFO - PROCESS | 3763 | RSS: Main: 157048832 22:37:36 INFO - PROCESS | 3763 | 22:37:38 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:37:39 INFO - PROCESS | 3763 | RSS: Main: 158142464 22:37:39 INFO - PROCESS | 3763 | 22:37:41 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:37:42 INFO - PROCESS | 3763 | RSS: Main: 158265344 22:37:42 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35607/tests/kraken/driver.html?testName=ai-astar) 22:37:44 INFO - PROCESS | 3763 | RSS: Main: 159318016 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | __start_tp_report 22:37:44 INFO - PROCESS | 3763 | _x_x_mozilla_page_load 22:37:44 INFO - PROCESS | 3763 | _x_x_mozilla_page_load_details 22:37:44 INFO - PROCESS | 3763 | |i|pagename|runs| 22:37:44 INFO - PROCESS | 3763 | |0;ai-astar;108;112;103;111;112;112;104;111;112;110 22:37:44 INFO - PROCESS | 3763 | |1;audio-beat-detection;157;127;158;171;131;156;150;127;187;128 22:37:44 INFO - PROCESS | 3763 | |2;audio-dft;162;162;161;162;164;163;162;162;164;162 22:37:44 INFO - PROCESS | 3763 | |3;audio-fft;75;74;77;180;77;73;72;74;166;78 22:37:44 INFO - PROCESS | 3763 | |4;audio-oscillator;92;92;151;98;93;92;152;93;92;144 22:37:44 INFO - PROCESS | 3763 | |5;imaging-gaussian-blur;117;118;117;117;118;121;117;118;116;117 22:37:44 INFO - PROCESS | 3763 | |6;imaging-darkroom;147;146;146;146;146;148;147;146;149;146 22:37:44 INFO - PROCESS | 3763 | |7;imaging-desaturate;94;95;95;96;95;95;98;95;95;96 22:37:44 INFO - PROCESS | 3763 | |8;json-parse-financial;55;57;57;56;57;60;61;73;55;57 22:37:44 INFO - PROCESS | 3763 | |9;json-stringify-tinderbox;58;57;58;57;57;57;60;59;58;57 22:37:44 INFO - PROCESS | 3763 | |10;stanford-crypto-aes;77;81;75;74;75;74;74;74;77;77 22:37:44 INFO - PROCESS | 3763 | |11;stanford-crypto-ccm;129;116;117;120;120;115;117;119;134;129 22:37:44 INFO - PROCESS | 3763 | |12;stanford-crypto-pbkdf2;163;165;162;162;163;159;162;159;158;159 22:37:44 INFO - PROCESS | 3763 | |13;stanford-crypto-sha256-iterative;72;67;68;73;68;67;67;69;66;70 22:37:44 INFO - PROCESS | 3763 | __end_tp_report 22:37:44 INFO - PROCESS | 3763 | __start_cc_report 22:37:44 INFO - PROCESS | 3763 | _x_x_mozilla_cycle_collect,843 22:37:44 INFO - PROCESS | 3763 | __end_cc_report 22:37:44 INFO - PROCESS | 3763 | __startTimestamp1465105064487__endTimestamp 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | ------- Summary: start ------- 22:37:44 INFO - PROCESS | 3763 | Number of tests: 14 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.50 stddev:3.41 (3.1%) stddev-sans-first:3.57 22:37:44 INFO - PROCESS | 3763 | Values: 108.0 112.0 103.0 111.0 112.0 112.0 104.0 111.0 112.0 110.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#1] audio-beat-detection Cycles:10 Average:149.20 Median:156.50 stddev:20.70 (13.2%) stddev-sans-first:21.76 22:37:44 INFO - PROCESS | 3763 | Values: 157.0 127.0 158.0 171.0 131.0 156.0 150.0 127.0 187.0 128.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 22:37:44 INFO - PROCESS | 3763 | Values: 162.0 162.0 161.0 162.0 164.0 163.0 162.0 162.0 164.0 162.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#3] audio-fft Cycles:10 Average:94.60 Median:74.50 stddev:41.49 (55.7%) stddev-sans-first:43.40 22:37:44 INFO - PROCESS | 3763 | Values: 75.0 74.0 77.0 180.0 77.0 73.0 72.0 74.0 166.0 78.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#4] audio-oscillator Cycles:10 Average:109.90 Median:92.00 stddev:27.12 (29.5%) stddev-sans-first:27.98 22:37:44 INFO - PROCESS | 3763 | Values: 92.0 92.0 151.0 98.0 93.0 92.0 152.0 93.0 92.0 144.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:117.50 stddev:1.35 (1.1%) stddev-sans-first:1.41 22:37:44 INFO - PROCESS | 3763 | Values: 117.0 118.0 117.0 117.0 118.0 121.0 117.0 118.0 116.0 117.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:146.50 stddev:1.06 (0.7%) stddev-sans-first:1.12 22:37:44 INFO - PROCESS | 3763 | Values: 147.0 146.0 146.0 146.0 146.0 148.0 147.0 146.0 149.0 146.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:1.07 (1.1%) stddev-sans-first:1.01 22:37:44 INFO - PROCESS | 3763 | Values: 94.0 95.0 95.0 96.0 95.0 95.0 98.0 95.0 95.0 96.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:57.00 stddev:5.35 (9.4%) stddev-sans-first:5.49 22:37:44 INFO - PROCESS | 3763 | Values: 55.0 57.0 57.0 56.0 57.0 60.0 61.0 73.0 55.0 57.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#9] json-stringify-tinderbox Cycles:10 Average:57.80 Median:58.00 stddev:1.03 (1.8%) stddev-sans-first:1.09 22:37:44 INFO - PROCESS | 3763 | Values: 58.0 57.0 58.0 57.0 57.0 57.0 60.0 59.0 58.0 57.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.00 stddev:2.25 (3.0%) stddev-sans-first:2.35 22:37:44 INFO - PROCESS | 3763 | Values: 77.0 81.0 75.0 74.0 75.0 74.0 74.0 74.0 77.0 77.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#11] stanford-crypto-ccm Cycles:10 Average:121.60 Median:120.00 stddev:6.60 (5.5%) stddev-sans-first:6.44 22:37:44 INFO - PROCESS | 3763 | Values: 129.0 116.0 117.0 120.0 120.0 115.0 117.0 119.0 134.0 129.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.20 Median:162.00 stddev:2.30 (1.4%) stddev-sans-first:2.35 22:37:44 INFO - PROCESS | 3763 | Values: 163.0 165.0 162.0 162.0 163.0 159.0 162.0 159.0 158.0 159.0 22:37:44 INFO - PROCESS | 3763 | 22:37:44 INFO - PROCESS | 3763 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.50 stddev:2.31 (3.4%) stddev-sans-first:2.12 22:37:44 INFO - PROCESS | 3763 | Values: 72.0 67.0 68.0 73.0 68.0 67.0 67.0 69.0 66.0 70.0 22:37:44 INFO - PROCESS | 3763 | -------- Summary: end -------- 22:37:44 INFO - PROCESS | 3763 | 22:37:45 INFO - TEST-INFO | 3763: exit 0 22:37:45 INFO - TEST-OK | kraken | took 60739ms 22:37:45 INFO - SUITE-END | took 1169s 22:37:45 INFO - Completed test suite (00:19:30) 22:37:45 INFO - javascript benchmark 22:37:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3354.983225083874, 3392.966070339296, 3430.8284585770707, 3467.133216695826, 3468.9306213875707, 5745.712714364281, 5746.798862039829, 5754.683492407916, 5770.538356931441, 5797.333306669724, 5704.572157088214, 5758.913616295759, 5788.392218817016, 5797.130430435437, 5798.362180160179, 5141.717205553693, 5199.376074871021, 5241.318628578299, 5250.868728281805, 5289.973550132263, 5154.69071855688, 5268.1834315680935, 5355.357357117151, 5363.865903352408, 5370.677759334452, 5192.0, 5210.478952104778, 5227.712475813829, 5292.0, 5313.840584782443, 5262.868428289305, 5281.970015846911, 5284.207368894658, 5294.232336311252, 5301.522862942354, 5076.720780357079, 5134.948650513485, 5193.350831146106, 5249.8425047248265, 5260.0, 5498.147787093007, 5548.14003829403, 5582.413846546095, 5616.5787565932715, 5641.266635337421, 6014.30835453924, 6033.5173186145, 6048.727807248684, 6064.332923378425, 6074.696265186767, 6083.969580152071, 6094.177286066369, 6097.3292937776805, 6114.297368146132, 6114.419025732089, 5781.103928891029, 5798.362180160179, 5811.244538210006, 5825.33008703996, 5828.0966450200285, 6285.779997700059, 6310.810675679645, 6328.620282782956, 6339.366063393623, 6341.207349081365, 5827.24245848037, 5950.464458198783, 6038.788642397583, 6091.329953705089, 6102.267727872683, 6096.512279017668, 6119.632822030692, 6120.44915957566, 6133.478654314344, 6139.4781443577795, 5989.970050149808, 6016.969915150484, 6078.908816367758, 6091.756329746849, 6102.481289090389, 5939.643621382731, 6067.2112625359305, 6069.514438844882, 6109.28320792043, 6122.510199184055, 4620.607248383925, 4624.907501850012, 4626.791794369262, 4633.791479383369, 4682.555157260055, 4175.519815221289, 4178.770167640748, 4182.393552934807, 4185.623293903503, 4192.454980852531, 5798.898209340212, 5923.763049478059, 5992.400759923973, 6029.155918171459, 6043.848903777441, 6044.365341639153, 6048.425399587032, 6054.8183554493435, 6141.570090093651, 6187.721552530057, 5883.735231914574, 5920.111983202468, 5923.259592550931, 5925.200097986803, 5937.732802023919, 5185.377754669464, 5257.921131183035, 5270.97216042868, 5274.446183150791, 5338.599605029638, 6351.904721429182, 6398.712057957403, 6408.743650254031, 6413.326600706859, 6415.454686351619, 6135.815925522063, 6146.5390095742105, 6152.661603611755, 6194.38056194377, 6196.628202307876, 6153.353897840662, 6299.244090709144, 6397.968010160105, 6410.1987251593555, 6467.708953097216, 6041.69791510414, 6066.90795656786, 6078.118672792332, 6084.787032453843, 6095.35998720128, 6137.890418342636, 6164.137020816999, 6170.290416602148, 6171.783987560414, 6173.228346456693, 5938.376470470711, 6020.608660437058, 6075.605085669417, 6086.360932102066, 6121.0206366981065, 5958.404159584007, 5961.701914904324, 5962.046072628359, 5991.520678345809, 6017.398260174123, 5762.1068734346245, 5862.091375836752, 5874.765009399576, 5878.412158784087, 5881.0, 6099.268087829489, 6119.289302195794, 6127.5866022601795, 6134.9079763804475, 6141.975154597598, 5897.174395584708, 5904.911426328695, 5910.911336330045, 5921.5558833086825, 5932.406759324033], "value": 5667.568004913493, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.6520391998121, 693.3101563943874, 706.8586282743449, 746.3991486853083, 780.9496227573911, 38974.41538376923, 40287.79856100719, 40410.79794601023, 40448.393274100876, 40714.7964260179, 41573.168536629324, 41981.160376792446, 42172.36741448881, 42245.155096898045, 42253.15493690124, 8613.569321533914, 8794.12058794122, 8815.823683526325, 8819.470831750083, 8820.20618144367, 8544.829103417927, 8689.869651955225, 8894.688685895995, 8904.243139333164, 8919.108089191093, 8894.555272236395, 8943.016268210524, 8958.059403762609, 8958.552072396387, 8994.86507702385, 8788.780280492974, 8902.643894244255, 8917.242034427081, 8933.196012358854, 8947.105289471034, 8869.778255543599, 8895.421797583136, 8969.686060987899, 8971.730848074569, 8976.0, 34271.143221419414, 41952.58047419517, 42370.57629423697, 42458.787706061274, 42739.78630106829, 3182.458981973047, 3797.905052373685, 3837.9808100959317, 3934.6065393460713, 4514.480834703985, 28570.71429285722, 28597.0, 28796.71203287961, 28800.135995920155, 29180.270493237625, 4281.764502952336, 4303.784810759481, 4396.0, 4404.339349097629, 4425.114977004612, 247448.0, 249113.0, 250721.0, 250834.0, 251193.7440312805, 36691.63308366935, 36819.263614727555, 36928.81535592359, 36937.261254774756, 39751.0, 37516.249675006344, 37828.0, 37847.432288515694, 37863.05342366463, 37911.62088379136, 17108.230129644195, 17209.053502057508, 17306.826931730775, 17367.0, 17386.739198911775, 14129.152250864981, 14139.50511732105, 14184.290785460686, 14203.928980355238, 14269.42922283097, 9165.0, 9223.446593204295, 9271.536423178813, 9400.48297343639, 9457.290703197288, 3600.4059330210744, 3631.2737452509605, 3652.981735091361, 3683.613220611851, 3709.239605880788, 1142.9541969098348, 1145.2441388683428, 1195.7010747313172, 1259.8362212912446, 1310.4627102887962, 29763.702362976524, 30083.398332033237, 30497.0, 31254.0, 31711.682883171336, 1191.5055252070467, 1222.9510819567295, 1250.493550112206, 1299.4184455731688, 1326.3633455941201, 16821.2430440628, 16896.46927552412, 17136.0, 17152.742708859376, 17182.31270749156, 532591.0, 536803.0, 539382.0, 541759.0, 545038.0, 60917.69541152354, 61049.38950610526, 61372.0, 61526.69236653788, 61924.69037654873, 1420.2472689474596, 1443.9711205775825, 1448.1992195870864, 1450.5938337265582, 1463.9020734449164, 30881.376411085766, 31802.204944876565, 31971.520427193147, 32160.356792863542, 32799.18002049872, 54958.17562736642, 55475.0, 55533.44466555283, 55612.72193639006, 55684.443155567926, 458559.0, 458886.0, 459739.0, 461035.0, 462257.0, 46614.76692616515, 46722.53277467318, 47224.0, 48089.5191048085, 48731.512684872694, 51084.744576276884, 51556.484435155166, 52404.47595523996, 52869.7356513215, 53033.20450193173, 42659.0, 43539.56460435355, 43546.0, 43671.78164109159, 44814.0, 43508.56491435045, 43826.78086609547, 43936.780316098215, 43983.340249895635, 44261.336079959474, 43336.349954750076, 44090.338644920994, 44307.77846110749, 44489.777551112034, 44494.777526112164, 36554.451683224244, 36691.0, 36857.0, 37196.07009824776, 37964.24071518499, 29098.27254318659, 29226.269343266587, 29489.410211796072, 29532.261693457836, 29800.552991705576], "value": 19499.415873926864, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.94423724915754, 431.2022757897891, 437.30043829204925, 440.6959198153275, 443.2730322271473, 18586.16362263698, 18818.717719234206, 19305.806941930594, 19360.70958935617, 19532.70700939487, 19004.524886877836, 19089.0, 19586.412407627795, 19855.40433786985, 20086.89956550219, 5222.242774797652, 5432.837014889559, 5447.972760136194, 5540.972295138528, 5803.912941305884, 5292.391374991887, 5432.13085906255, 5441.0, 5447.264619276407, 5457.972710136463, 5658.773649054054, 5668.234788303588, 5676.432356764331, 5697.515711164556, 5718.542516598662, 5603.803866864681, 5607.635503692288, 5612.382637909826, 5616.410276920926, 5630.0991841305195, 5751.194832723422, 5759.68321742304, 5781.53747700183, 5791.855203619901, 5816.854578635525, 14151.221682807442, 14462.638434039129, 18082.457526274233, 24321.391965200834, 24758.5048299033, 2476.925692229236, 2521.155412936668, 2637.591173368131, 2799.4820958122814, 2828.632277803893, 10327.58689652413, 10599.258051936367, 10621.097206737437, 12693.809592856114, 15112.09327440357, 2010.4571765623346, 2132.657692480342, 2158.287765037534, 2164.7077644517944, 2194.3416974907623, 84496.57751711263, 85747.57126214392, 86858.56570717106, 87750.12249877419, 89140.0, 11786.646400607995, 11847.585334513251, 11965.102617303737, 11984.161108722481, 12155.878441215651, 16480.0935948524, 16705.498835034967, 16712.665746684997, 16721.74917376216, 16735.1632418381, 9275.58259878307, 9367.203787677987, 9377.906220937839, 9397.248220142372, 9516.19112375442, 9104.772380690536, 9178.449293042306, 9254.398464099811, 9292.117248861347, 9296.95351523238, 4996.4254110777, 5060.240963855451, 5107.770150343169, 5246.554042906319, 5260.105782017066, 1990.7810140884492, 2122.395117391536, 2210.502636906683, 2213.181122984506, 2217.7671344508917, 1001.0240015984357, 1089.096050278267, 1135.2450620337383, 1162.7790719763218, 1182.1252273317855, 18889.338873139648, 19256.422307330802, 19453.51366215856, 19787.505312367306, 19994.800051999293, 1110.9388983605818, 1132.6957561267152, 1133.0312582741637, 1164.5807509296646, 1201.897838683704, 10834.837477437844, 11330.8615572189, 11340.319580825177, 11348.432578371214, 11428.942855285837, 542761.0, 557995.0, 566319.0, 569938.0, 572663.0, 99837.0, 100052.49973750085, 100182.4990875041, 100718.0, 104140.0, 1188.1860925266221, 1188.9048876089892, 1195.3664557784389, 1198.7482628647908, 1221.8106193540016, 17491.912540437217, 17832.0, 17865.642687146443, 18179.0, 18330.266789328278, 37668.62331376651, 38293.617063829006, 41702.79148604238, 42449.151016980104, 42748.0, 51587.74206128946, 52027.47972520226, 52057.47942520678, 52525.212121817436, 52713.0, 8509.957450212709, 8510.659573617233, 8649.3512986525, 8699.08659590759, 8801.07588703177, 7978.082520509984, 10387.532561034923, 13574.321283935962, 13980.161190328612, 14179.14925104497, 11383.487743051748, 11773.411329433666, 11847.229930054518, 12496.000319974562, 12646.30445325516, 5154.474307589329, 8993.28053755685, 11482.081433485468, 11668.358240296526, 12011.879881201075, 11042.558297668003, 11764.529418823493, 11869.347185904859, 12101.576444824388, 12540.686482838002, 8478.618462169341, 8649.740507784776, 8700.17348351931, 8989.640414383352, 9164.358494905558, 11369.2609980351, 12193.878061219275, 12217.0, 12247.203931744409, 12473.126881118598], "value": 9582.023509565077, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1632.4776071657068, 1642.6961012212737, 1852.833245007949, 1933.8452923766108, 1977.7033444983242, 7837.21627837216, 8357.582120893947, 8736.956315218424, 8937.64249430022, 8953.0, 8557.0, 8630.827383452328, 8638.395312328139, 8703.303735701145, 8734.082921293251, 2454.7667971542674, 2480.107161421896, 2482.354587807174, 2482.366996415911, 2490.3276115448816, 2458.8770561471943, 2485.130204428445, 2488.278399264211, 2488.5147396257735, 2489.5643262429094, 2453.828232023759, 2455.2511483997373, 2571.2928944540213, 2579.071534247669, 2580.780633646142, 2459.483508463225, 2498.475320182764, 2513.0450428837107, 2516.5973444248925, 2523.3439305780444, 2430.1980346485616, 2443.572374834415, 2443.7556244375596, 2448.43685952232, 2497.013679596549, 8911.866322005175, 8971.865422018675, 9014.819703605892, 9024.413413128126, 9031.412958157698, 5488.286522752057, 5587.888242235133, 5644.491995720364, 5650.660960342351, 5659.094544872801, 3510.104923244556, 3572.464130380438, 3584.9641503585153, 3719.4420836874415, 3753.7935413552245, 1157.1784033336342, 1171.806651902435, 1181.102362204722, 1183.8520184976878, 1224.4306397525193, 12646.557370491988, 12672.556460523929, 12695.0, 12777.616671499869, 12798.0, 4839.128956787812, 4963.8262660806695, 4970.627202959792, 5063.772130254148, 5063.797448102108, 10591.152707783513, 10623.946880265703, 10655.893441065646, 10664.466776661136, 10686.73283167927, 5668.801591944262, 5745.540356771448, 5746.051901436216, 5756.309242890796, 5765.394633563499, 4438.533953934855, 4811.78346974387, 4845.903081938412, 4888.388951381077, 5190.065788158092, 4239.385289133058, 4252.29837076885, 4335.804888780012, 4356.0, 4387.144506821203, 1381.530279704905, 1385.4596707284168, 1407.5706909392734, 1420.1266221273843, 1424.430227908824, 1035.5495359518634, 1037.5642230263309, 1062.3997441445558, 1075.5375188668947, 1076.5962763963514, 6040.395960403925, 6112.266528016667, 6159.168512250879, 6160.383961603804, 6307.684615769193, 486.4600293674019, 489.9118158731463, 491.17482629183314, 1109.378747901162, 1123.017359810166, 4115.135821477524, 4216.430781844411, 4229.619334259932, 4276.657867370603, 4290.785460727014, 10627.468626568641, 10853.728656783644, 10995.670129896116, 11016.834747478793, 11054.944725276322, 10898.509567069501, 10988.230823842414, 11009.779804403866, 11029.338239705643, 11066.059384952368, 7830.295273425305, 8075.111737708848, 8480.279176269964, 8482.151784821468, 8537.786555336043, 5668.149777533403, 5669.744861481161, 5683.60214784961, 5727.942720572741, 5830.067189249699, 9505.239580833379, 9584.856227156459, 9648.517574121406, 9658.275629327969, 9678.75803104928, 9534.380265282734, 9680.612775488902, 9815.705528834147, 9848.261380396356, 9872.308938374244, 7083.362497375261, 7255.383292420203, 7274.781756547312, 7364.815879603053, 7392.852142957003, 8460.661573537236, 8461.0, 8491.702790402307, 8535.231829135158, 8557.529335886586, 7584.886226706493, 7750.689972401041, 7762.495437796525, 7774.067111946602, 7835.177306382748, 7527.661255243636, 7676.040494938133, 7695.076590808915, 7718.343940764873, 7756.8448631028195, 7207.387372073433, 7222.494425390172, 7223.349898509159, 7263.201047884623, 7272.672729727069, 5161.7677204525135, 5167.870803229799, 5256.185291279858, 5285.68285902847, 5381.327334083239, 5292.285541451854, 5373.167159090347, 5396.541293989898, 5398.163284690879, 5499.477549632778], "value": 4764.613912745238, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.65624141427764, 370.44618295648, 374.0368550981223, 380.1598467387074, 383.4210342383001, 6455.257645370783, 6619.238787539434, 6619.30497297784, 6670.199576050874, 6711.496637752164, 6595.7691480798185, 6599.043138744891, 6606.636634985086, 6611.809874222638, 6623.039659249418, 5005.074061298671, 5015.774290156952, 5019.749012549376, 5030.622703297258, 5054.519820617035, 5054.064997975368, 5065.113605119108, 5070.670406423589, 5076.03555324489, 5078.847634570969, 5023.598112151038, 5024.799008039692, 5026.246063090529, 5028.371453568304, 5029.0, 4786.880327991793, 4920.778564964585, 5025.195968645017, 5028.296038554606, 5034.068697290994, 4278.144371125787, 4302.483701955785, 4304.784760761981, 4308.9353659695125, 4317.870463886088, 6301.046675530554, 6310.810675679737, 6318.241810982666, 6326.525510586678, 6332.366763323676, 5781.277340332458, 5784.305883294031, 5785.161151633031, 5792.68140252286, 5813.796517121923, 6888.207856096513, 6905.930940690579, 6915.0, 6916.170059592881, 6957.9652101739675, 4661.953380466186, 4813.422389313269, 4814.951850481485, 4831.975840120812, 4833.540813622701, 7238.384737297336, 7279.490435669505, 7304.853902921965, 7330.8900366494545, 7335.743248986313, 4894.412670479565, 4909.067277217388, 4920.434150072716, 4922.950770492321, 4933.3586633738105, 4984.925226121611, 4989.301497790312, 4991.850244492671, 4993.251012348104, 4999.475055119233, 5788.594798364158, 5793.2179155814265, 5798.072308430631, 5801.187833703285, 5808.30300363959, 6780.288069752703, 7036.577805331696, 7037.507374483839, 7077.5753454792875, 7087.397571206402, 1742.1115231232163, 1742.7560141580188, 1761.3130878957218, 1762.1013283225648, 1767.734839774044, 1097.0894157848966, 1126.695792136135, 1127.402476687357, 1136.8522092128135, 1138.9373584452935, 6736.831579210559, 6810.080639113756, 6812.591244525344, 6816.113905192294, 6816.352446517573, 7161.746694328472, 7168.2831716827895, 7189.424846012411, 7232.74685386009, 7243.0946131733535, 6711.228208756055, 6750.831229219309, 6754.527183097234, 6762.729490820312, 6781.728730850809, 3869.342211823997, 3896.688264938798, 3903.8243279052513, 3911.2764138634443, 3911.9608803912165, 7425.1832298447125, 7617.27635874591, 7628.427867909929, 7681.539107653559, 7701.075870895416, 7396.260373962668, 7409.629518524052, 7420.740274090381, 7425.888611670721, 7462.291082347168, 7643.770686879402, 7652.923470765332, 7656.846863062819, 7669.348105410991, 7672.616369181407, 7113.0753002109395, 7256.600886951268, 7290.854182916418, 7302.853942921006, 7318.817029574303, 7190.245024272585, 7205.567665940061, 7209.3872020876715, 7223.494355395072, 7257.854842903218, 7556.508826926232, 7581.54510729358, 7607.771766847003, 7640.923590764021, 7656.081270247606, 4911.656184067081, 4951.900961980812, 4954.108260513142, 4964.478729733326, 4965.528274813887, 7052.612106334201, 7240.565566066053, 7289.781306560812, 7299.817504562216, 7305.488615796843, 7031.1211098612675, 7076.398506126831, 7079.469039821931, 7080.504364694628, 7082.256363081802, 7002.474814388839, 7017.157941046903, 7021.789346319619, 7024.103492057218, 7027.508074434945, 6901.654917254217, 6906.689198985957, 6911.93088069133, 6935.479839011993, 6944.965275173794, 6989.720411183699, 7117.6085315308155, 7123.608201548757, 7124.287571242834, 7133.286671332825, 6796.388325050769, 6882.965585172243, 6888.276730943379, 6982.476314276551, 6987.161540615159], "value": 5281.875084282598, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [929.6467342409883, 936.939098958568, 952.2572393533043, 960.7261930349852, 962.7015625156198, 15093.169875656835, 15277.923610381933, 15330.923345383271, 15413.69172616547, 15972.520824375259, 14846.331915063822, 14849.0, 14854.777178342334, 14911.552653420415, 14948.925255373735, 912.2154946745793, 938.3712912348709, 940.6190492850407, 958.0611001218809, 965.478641533571, 954.7517645412208, 955.4362925873733, 961.8605302231207, 964.7298756348234, 964.8118616869679, 993.438707130469, 994.4530508220486, 994.9702058369589, 995.1590905684685, 996.2727209137335, 975.0, 978.9216862651007, 980.460746589373, 982.9655962041294, 985.2118305355687, 721.7077083781077, 723.9167495738005, 724.2757242757243, 724.351705223827, 724.7318492157935, 15817.0, 15825.841741582668, 15897.682046359123, 15913.522594322189, 15987.280572374271, 14666.853331466762, 15027.699446011127, 15051.397944082224, 15225.086494810346, 15237.314320855588, 12200.328981905992, 12526.185797923197, 12526.248425094433, 12666.43001064945, 12746.171498852545, 4043.4137050127565, 4053.9594604053877, 4055.188962207541, 4111.403846442249, 4129.855455059059, 56929.43070569283, 57290.713546432, 58508.70745646287, 59085.70457147687, 59478.0, 11875.109366797526, 11886.212706908584, 11985.34080625574, 12212.450439730055, 12270.570530031406, 21182.47043823886, 21273.255436059662, 21297.68053479199, 21448.678269825967, 21515.8924205378, 8020.238077382639, 8042.075161356519, 8062.637181326854, 8146.144654811278, 8328.541930193898, 5079.4412614612365, 5088.0, 5111.437741848394, 5174.818881339211, 5199.688018718889, 3667.7065834733157, 3712.350338690718, 3718.4608231806237, 3733.962660373416, 3748.9062773430883, 636.9108324834527, 654.0385633119307, 655.6000839487908, 662.1326062857672, 678.2302087131142, 822.6257053040852, 822.7120507822215, 846.7671390367698, 850.7235148576738, 854.4104567848165, 9848.21214302854, 10425.322354046963, 10503.369797812156, 10562.683119506428, 10884.782304353868, 757.6552668535803, 830.3056581393417, 845.725139329708, 847.2544161138159, 865.1910463716446, 4907.754612269372, 4912.95087049125, 4925.605951523941, 4980.576650984707, 5039.848804535796, 67482.66258668773, 67636.6618166906, 67912.0, 68764.31235687579, 68956.0, 55194.0, 55601.16598250948, 56190.15714764282, 56534.0, 57676.42323576794, 49822.75088624606, 49837.0, 49854.0, 50193.24710129351, 52928.73535632374, 9833.0, 9980.328195423135, 9999.55127661801, 10137.239707021856, 10290.228232882713, 39374.80312598419, 39710.0, 39813.0, 40689.59310406858, 40745.79627101846, 49786.75106624444, 51012.489875102256, 51265.23102153396, 51798.22302665538, 52154.21768673549, 12604.577781980768, 12832.29422381778, 12914.935425322814, 13183.868161318263, 13249.006324525506, 14579.271036448346, 14599.562013139623, 14622.268886555417, 14818.481353152589, 14917.328720207399, 13281.535146269835, 13536.0, 13540.390682419511, 13831.170129792245, 14037.438502459787, 13088.214707117604, 13116.606501804961, 13261.801072984106, 13445.394957227143, 14219.0, 12806.0, 13380.799288010883, 13707.588772336845, 13736.0, 13810.516631917835, 11714.941425292818, 11768.470418831344, 11918.52325906954, 11943.880561194277, 12105.757884842078, 11434.25677331004, 11787.72706624692, 11927.82108268394, 11979.940100299791, 11991.22057066288], "value": 7449.006528849467, "unit": "score"}], "name": "dromaeo_css", "value": 7637.843391160508, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 103.0, 111.0, 112.0, 112.0, 104.0, 111.0, 112.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 127.0, 158.0, 171.0, 131.0, 156.0, 150.0, 127.0, 187.0, 128.0], "value": 149.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 162.0, 164.0, 163.0, 162.0, 162.0, 164.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 77.0, 180.0, 77.0, 73.0, 72.0, 74.0, 166.0, 78.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 151.0, 98.0, 93.0, 92.0, 152.0, 93.0, 92.0, 144.0], "value": 109.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 118.0, 121.0, 117.0, 118.0, 116.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 148.0, 147.0, 146.0, 149.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 96.0, 95.0, 95.0, 98.0, 95.0, 95.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 57.0, 56.0, 57.0, 60.0, 61.0, 73.0, 55.0, 57.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 57.0, 57.0, 57.0, 60.0, 59.0, 58.0, 57.0], "value": 57.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 81.0, 75.0, 74.0, 75.0, 74.0, 74.0, 74.0, 77.0, 77.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 116.0, 117.0, 120.0, 120.0, 115.0, 117.0, 119.0, 134.0, 129.0], "value": 121.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 162.0, 162.0, 163.0, 159.0, 162.0, 159.0, 158.0, 159.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 67.0, 68.0, 73.0, 68.0, 67.0, 67.0, 69.0, 66.0, 70.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1529.2, "alertThreshold": 2.0}]} 22:37:45 INFO - Return code: 0 22:37:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:37:45 ERROR - # TBPL SUCCESS # 22:37:45 INFO - Running post-action listener: _resource_record_post_action 22:37:45 INFO - Running post-run listener: _resource_record_post_run 22:37:46 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 266240; Write bytes: 500301824; Read time: 32; Write time: 348192 22:37:46 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 249376768; Read time: 0; Write time: 224588 22:37:46 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 266240; Write bytes: 250925056; Read time: 32; Write time: 123604 22:37:46 INFO - Running post-run listener: _upload_blobber_files 22:37:46 INFO - Blob upload gear active. 22:37:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:37:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:37:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:37:47 INFO - (blobuploader) - INFO - Open directory for files ... 22:37:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:37:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:37:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:48 INFO - (blobuploader) - INFO - Done attempting. 22:37:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:37:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:37:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:48 INFO - (blobuploader) - INFO - Done attempting. 22:37:48 INFO - (blobuploader) - INFO - Iteration through files over. 22:37:48 INFO - Return code: 0 22:37:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:37:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:37:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d48b37769df70d2c9994942d14b557deb72fdc966a1eb4b4d20c4d1f4fc637c09f71f455f75a60680f2ccf67c87908c3e290b760dcbfbbcb6fd81375a7247cce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5ac75e0b74fd371d1a43e9afefa3b79c75cf68a063dc5f1ddba17b89908fb19cc67c458f7b492b60b442a04887b0528e115a5b5574036a8c8d1f622a4605df6"} 22:37:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:37:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:37:48 INFO - Contents: 22:37:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d48b37769df70d2c9994942d14b557deb72fdc966a1eb4b4d20c4d1f4fc637c09f71f455f75a60680f2ccf67c87908c3e290b760dcbfbbcb6fd81375a7247cce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5ac75e0b74fd371d1a43e9afefa3b79c75cf68a063dc5f1ddba17b89908fb19cc67c458f7b492b60b442a04887b0528e115a5b5574036a8c8d1f622a4605df6"} 22:37:48 INFO - Running post-run listener: copy_logs_to_upload_dir 22:37:48 INFO - Copying logs to upload dir... 22:37:48 INFO - mkdir: /builds/slave/test/build/upload/logs 22:37:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.562423 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-06-04 22:37:48.542676) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 22:37:48.545966) ========= 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=7de08804244eb36560b44971000001c3-1465103527.889053-2065970043 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-Aurora/sha512/d48b37769df70d2c9994942d14b557deb72fdc966a1eb4b4d20c4d1f4fc637c09f71f455f75a60680f2ccf67c87908c3e290b760dcbfbbcb6fd81375a7247cce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5ac75e0b74fd371d1a43e9afefa3b79c75cf68a063dc5f1ddba17b89908fb19cc67c458f7b492b60b442a04887b0528e115a5b5574036a8c8d1f622a4605df6"} build_url:https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011381 build_url: 'https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d48b37769df70d2c9994942d14b557deb72fdc966a1eb4b4d20c4d1f4fc637c09f71f455f75a60680f2ccf67c87908c3e290b760dcbfbbcb6fd81375a7247cce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5ac75e0b74fd371d1a43e9afefa3b79c75cf68a063dc5f1ddba17b89908fb19cc67c458f7b492b60b442a04887b0528e115a5b5574036a8c8d1f622a4605df6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 22:37:48.572003) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:37:48.572347) ========= 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=7de08804244eb36560b44971000001c3-1465103527.889053-2065970043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004431 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:37:48.657337) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 22:37:48.657669) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 22:37:48.657970) ========= ========= Total master_lag: 0.18 =========