builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-043 starttime: 1468459081.46 results: success (0) buildid: 20160713152943 builduid: 9d35a485de594b2897a64d1d4b1556c1 revision: f8051a3ef4ec5842689306cc3d7b22a6ec439c9a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:01.463368) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:01.463928) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:01.464204) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:01.494500) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:01.494858) ========= 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=d2a8c569965ab8367867b893000001a1-1468458546.949499-965453286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-13 18:18:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.8M=0s 2016-07-13 18:18:01 (70.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075167 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:01.585060) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:01.585696) ========= 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=d2a8c569965ab8367867b893000001a1-1468458546.949499-965453286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015100 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:01.635101) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:01.635465) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f8051a3ef4ec5842689306cc3d7b22a6ec439c9a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f8051a3ef4ec5842689306cc3d7b22a6ec439c9a --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=d2a8c569965ab8367867b893000001a1-1468458546.949499-965453286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-13 18:18:01,718 truncating revision to first 12 chars 2016-07-13 18:18:01,718 Setting DEBUG logging. 2016-07-13 18:18:01,718 attempt 1/10 2016-07-13 18:18:01,718 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f8051a3ef4ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 18:18:02,063 unpacking tar archive at: mozilla-beta-f8051a3ef4ec/testing/mozharness/ program finished with exit code 0 elapsedTime=0.756828 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:02.408931) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:02.409213) ========= script_repo_revision: f8051a3ef4ec5842689306cc3d7b22a6ec439c9a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:02.409698) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:02.410032) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 18:18:02.421134) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2016-07-13 18:18:02.421413) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1468458546.949499-965453286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:18:02 INFO - MultiFileLogger online at 20160713 18:18:02 in /builds/slave/test 18:18:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 18:18:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:18:02 INFO - {'append_to_log': False, 18:18:02 INFO - 'base_work_dir': '/builds/slave/test', 18:18:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:18:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:18:02 INFO - 'branch': 'Mozilla-Beta', 18:18:02 INFO - 'buildbot_json_path': 'buildprops.json', 18:18:02 INFO - 'config_files': ('talos/linux_config.py',), 18:18:02 INFO - 'default_actions': ('clobber', 18:18:02 INFO - 'read-buildbot-config', 18:18:02 INFO - 'download-and-extract', 18:18:02 INFO - 'populate-webroot', 18:18:02 INFO - 'create-virtualenv', 18:18:02 INFO - 'install', 18:18:02 INFO - 'run-tests'), 18:18:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:18:02 INFO - 'download_minidump_stackwalk': True, 18:18:02 INFO - 'download_symbols': 'ondemand', 18:18:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:18:02 INFO - 'tooltool.py': '/tools/tooltool.py', 18:18:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:18:02 INFO - '/tools/misc-python/virtualenv.py')}, 18:18:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:18:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:18:02 INFO - 'installer_path': 'installer.exe', 18:18:02 INFO - 'log_level': 'info', 18:18:02 INFO - 'log_name': 'talos', 18:18:02 INFO - 'log_to_console': True, 18:18:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:18:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:18:02 INFO - 'opt_config_files': (), 18:18:02 INFO - 'pip_index': False, 18:18:02 INFO - 'sps_profile': False, 18:18:02 INFO - 'sps_profile_interval': 0, 18:18:02 INFO - 'suite': 'dromaeojs-e10s', 18:18:02 INFO - 'system_bits': '32', 18:18:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:18:02 INFO - 'title': 'talos-linux64-ix-043', 18:18:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:18:02 INFO - 'use_talos_json': True, 18:18:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:18:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:18:02 INFO - 'work_dir': 'build'} 18:18:02 INFO - ##### 18:18:02 INFO - ##### Running clobber step. 18:18:02 INFO - ##### 18:18:02 INFO - Running pre-action listener: _resource_record_pre_action 18:18:02 INFO - Running main action method: clobber 18:18:02 INFO - rmtree: /builds/slave/test/build 18:18:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:18:03 INFO - Running post-action listener: _resource_record_post_action 18:18:03 INFO - ##### 18:18:03 INFO - ##### Running read-buildbot-config step. 18:18:03 INFO - ##### 18:18:03 INFO - Running pre-action listener: _resource_record_pre_action 18:18:03 INFO - Running main action method: read_buildbot_config 18:18:03 INFO - Using buildbot properties: 18:18:03 INFO - { 18:18:03 INFO - "project": "", 18:18:03 INFO - "product": "firefox", 18:18:03 INFO - "script_repo_revision": "production", 18:18:03 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 18:18:03 INFO - "repository": "", 18:18:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 18:18:03 INFO - "buildid": "20160713152943", 18:18:03 INFO - "pgo_build": "True", 18:18:03 INFO - "basedir": "/builds/slave/test", 18:18:03 INFO - "buildnumber": 94, 18:18:03 INFO - "slavename": "talos-linux64-ix-043", 18:18:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:18:03 INFO - "platform": "ubuntu64_hw", 18:18:03 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:18:03 INFO - "branch": "mozilla-beta", 18:18:03 INFO - "repo_path": "releases/mozilla-beta", 18:18:03 INFO - "revision": "f8051a3ef4ec5842689306cc3d7b22a6ec439c9a", 18:18:03 INFO - "stage_platform": "linux64-pgo", 18:18:03 INFO - "builduid": "9d35a485de594b2897a64d1d4b1556c1", 18:18:03 INFO - "slavebuilddir": "test" 18:18:03 INFO - } 18:18:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2. 18:18:03 INFO - Running post-action listener: _resource_record_post_action 18:18:03 INFO - ##### 18:18:03 INFO - ##### Running download-and-extract step. 18:18:03 INFO - ##### 18:18:03 INFO - Running pre-action listener: _resource_record_pre_action 18:18:03 INFO - Running main action method: download_and_extract 18:18:03 INFO - mkdir: /builds/slave/test/build/tests 18:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:03 INFO - https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:18:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 18:18:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 18:18:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 18:18:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:18:04 INFO - Downloaded 1327 bytes. 18:18:04 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 18:18:04 INFO - Using the following test package requirements: 18:18:04 INFO - {u'common': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 18:18:04 INFO - u'cppunittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 18:18:04 INFO - u'firefox-48.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:18:04 INFO - u'gtest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 18:18:04 INFO - u'firefox-48.0.en-US.linux-x86_64.gtest.tests.zip'], 18:18:04 INFO - u'jittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 18:18:04 INFO - u'jsshell-linux-x86_64.zip'], 18:18:04 INFO - u'mochitest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 18:18:04 INFO - u'firefox-48.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:18:04 INFO - u'mozbase': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 18:18:04 INFO - u'reftest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 18:18:04 INFO - u'firefox-48.0.en-US.linux-x86_64.reftest.tests.zip'], 18:18:04 INFO - u'talos': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 18:18:04 INFO - u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'], 18:18:04 INFO - u'web-platform': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 18:18:04 INFO - u'firefox-48.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:18:04 INFO - u'xpcshell': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 18:18:04 INFO - u'firefox-48.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:18:04 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:04 INFO - https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:18:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 18:18:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 18:18:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 18:18:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:18:06 INFO - Downloaded 24238417 bytes. 18:18:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:18:08 INFO - Return code: 0 18:18:08 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:08 INFO - https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:18:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 18:18:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 18:18:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 18:18:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:18:13 INFO - Downloaded 24238417 bytes. 18:18:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:18:14 INFO - Return code: 0 18: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')]} 18:18:14 INFO - https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:18:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 18:18:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 18:18:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 18:18:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:18:15 INFO - Downloaded 10840145 bytes. 18:18:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:18:16 INFO - Return code: 0 18:18:16 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')]} 18:18:16 INFO - https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:18:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 18:18:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 18:18:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 18:18:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:18:17 INFO - Downloaded 56018073 bytes. 18:18:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 18:18:17 INFO - mkdir: /builds/slave/test/properties 18:18:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:18:17 INFO - Writing to file /builds/slave/test/properties/build_url 18:18:17 INFO - Contents: 18:18:17 INFO - build_url:https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 18:18:18 INFO - Running post-action listener: _resource_record_post_action 18:18:18 INFO - Running post-action listener: set_extra_try_arguments 18:18:18 INFO - ##### 18:18:18 INFO - ##### Running populate-webroot step. 18:18:18 INFO - ##### 18:18:18 INFO - Running pre-action listener: _resource_record_pre_action 18:18:18 INFO - Running main action method: populate_webroot 18:18:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:18:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:18:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:18:18 INFO - '--tppagecycles', 18:18:18 INFO - '7'], 18:18:18 INFO - 'tests': ['tsvgm']}}, 18:18:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:18:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:18:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:18:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:18:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:18:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:18:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:18 INFO - 'talos_options': ['--disable-e10s'], 18:18:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:18 INFO - 'talos_options': ['--disable-e10s'], 18:18:18 INFO - 'tests': ['damp', 'tps']}, 18:18:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:18 INFO - 'tests': ['damp', 'tps']}, 18:18:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:18:18 INFO - 'tests': ['dromaeo_dom']}, 18:18:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:18:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:18:18 INFO - 'tests': ['a11yr', 18:18:18 INFO - 'ts_paint', 18:18:18 INFO - 'tpaint', 18:18:18 INFO - 'sessionrestore', 18:18:18 INFO - 'sessionrestore_no_auto_restore', 18:18:18 INFO - 'tabpaint']}, 18:18:18 INFO - 'other-e10s': {'tests': ['a11yr', 18:18:18 INFO - 'ts_paint', 18:18:18 INFO - 'tpaint', 18:18:18 INFO - 'sessionrestore', 18:18:18 INFO - 'sessionrestore_no_auto_restore', 18:18:18 INFO - 'tabpaint']}, 18:18:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:18:18 INFO - 'ts_paint', 18:18:18 INFO - 'tpaint', 18:18:18 INFO - 'sessionrestore', 18:18:18 INFO - 'sessionrestore_no_auto_restore', 18:18:18 INFO - 'tabpaint']}, 18:18:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:18:18 INFO - 'ts_paint', 18:18:18 INFO - 'tpaint', 18:18:18 INFO - 'sessionrestore', 18:18:18 INFO - 'sessionrestore_no_auto_restore', 18:18:18 INFO - 'tabpaint']}, 18:18:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:18:18 INFO - 'tests': ['a11yr', 18:18:18 INFO - 'ts_paint', 18:18:18 INFO - 'tpaint', 18:18:18 INFO - 'sessionrestore', 18:18:18 INFO - 'sessionrestore_no_auto_restore', 18:18:18 INFO - 'tabpaint']}, 18:18:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:18:18 INFO - 'tests': ['a11yr', 18:18:18 INFO - 'ts_paint', 18:18:18 INFO - 'tpaint', 18:18:18 INFO - 'sessionrestore', 18:18:18 INFO - 'sessionrestore_no_auto_restore', 18:18:18 INFO - 'tabpaint']}, 18:18:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:18:18 INFO - 'tests': ['tsvgx', 18:18:18 INFO - 'tsvgr_opacity', 18:18:18 INFO - 'tart', 18:18:18 INFO - 'tscrollx', 18:18:18 INFO - 'cart']}, 18:18:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:18:18 INFO - 'tsvgr_opacity', 18:18:18 INFO - 'tart', 18:18:18 INFO - 'tscrollx', 18:18:18 INFO - 'cart']}, 18:18:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:18 INFO - 'talos_options': ['--disable-e10s'], 18:18:18 INFO - 'tests': ['tp5o']}, 18:18:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:18 INFO - 'tests': ['tp5o']}, 18:18:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:18 INFO - 'talos_options': ['--disable-e10s', 18:18:18 INFO - '--xperf_path', 18:18:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:18 INFO - 'tests': ['tp5n']}, 18:18:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:18 INFO - 'talos_options': ['--xperf_path', 18:18:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:18 INFO - 'tests': ['tp5n']}}, 18:18:18 INFO - 'talos.zip': {'path': '', 18:18:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:18:18 INFO - Running post-action listener: _resource_record_post_action 18:18:18 INFO - ##### 18:18:18 INFO - ##### Running create-virtualenv step. 18:18:18 INFO - ##### 18:18:18 INFO - Running pre-action listener: _resource_record_pre_action 18:18:18 INFO - Running main action method: create_virtualenv 18:18:18 INFO - Creating virtualenv /builds/slave/test/build/venv 18:18:18 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 18:18:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:18:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:18:18 INFO - Using real prefix '/usr' 18:18:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:18:18 INFO - Installing distribute.............................................................................................................................................................................................done. 18:18:20 INFO - Installing pip.................done. 18:18:20 INFO - Return code: 0 18:18:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa8cf02f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x119c410>, '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 0x119d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x119d7a0>, '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': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', '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 18:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:18:20 INFO - Using env: {'DISPLAY': ':0', 18:18:20 INFO - 'HOME': '/home/cltbld', 18:18:20 INFO - 'LANG': 'en_US.UTF-8', 18:18:20 INFO - 'LANGUAGE': 'en_US:en', 18:18:20 INFO - 'LOGNAME': 'cltbld', 18:18:20 INFO - 'MAIL': '/var/mail/cltbld', 18:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:20 INFO - 'MOZ_NO_REMOTE': '1', 18:18:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:20 INFO - 'NO_EM_RESTART': '1', 18:18:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:20 INFO - 'PWD': '/builds/slave/test', 18:18:20 INFO - 'SHELL': '/bin/bash', 18:18:20 INFO - 'SHLVL': '1', 18:18:20 INFO - 'TERM': 'linux', 18:18:20 INFO - 'TMOUT': '86400', 18:18:20 INFO - 'USER': 'cltbld', 18:18:20 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', 18:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:20 INFO - '_': '/tools/buildbot/bin/python'} 18:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:21 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 18:18:21 INFO - Cleaning up... 18:18:21 INFO - Return code: 0 18:18:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:18:21 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')]} 18:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:21 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')]} 18:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:21 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 0x7ffa8cf02f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x119c410>, '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 0x119d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x119d7a0>, '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': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', '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 18:18:21 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 18:18:21 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 18:18:21 INFO - Using env: {'DISPLAY': ':0', 18:18:21 INFO - 'HOME': '/home/cltbld', 18:18:21 INFO - 'LANG': 'en_US.UTF-8', 18:18:21 INFO - 'LANGUAGE': 'en_US:en', 18:18:21 INFO - 'LOGNAME': 'cltbld', 18:18:21 INFO - 'MAIL': '/var/mail/cltbld', 18:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:21 INFO - 'MOZ_NO_REMOTE': '1', 18:18:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:21 INFO - 'NO_EM_RESTART': '1', 18:18:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:21 INFO - 'PWD': '/builds/slave/test', 18:18:21 INFO - 'SHELL': '/bin/bash', 18:18:21 INFO - 'SHLVL': '1', 18:18:21 INFO - 'TERM': 'linux', 18:18:21 INFO - 'TMOUT': '86400', 18:18:21 INFO - 'USER': 'cltbld', 18:18:21 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', 18:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:21 INFO - '_': '/tools/buildbot/bin/python'} 18:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:21 INFO - Downloading/unpacking psutil>=0.7.1 18:18:21 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 18:18:21 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 18:18:21 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 18:18:21 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 18:18:21 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 18:18:21 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 18:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:18:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:23 INFO - Installing collected packages: psutil 18:18:23 INFO - Running setup.py install for psutil 18:18:23 INFO - building 'psutil._psutil_linux' extension 18:18:23 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 18:18:23 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 18:18:23 INFO - building 'psutil._psutil_posix' extension 18:18:23 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 18:18:23 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 18:18:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:23 INFO - Successfully installed psutil 18:18:23 INFO - Cleaning up... 18:18:23 INFO - Return code: 0 18:18:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:18:23 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')]} 18:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:23 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')]} 18:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:23 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 0x7ffa8cf02f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x119c410>, '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 0x119d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x119d7a0>, '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': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', '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 18:18:23 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 18:18:23 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 18:18:23 INFO - Using env: {'DISPLAY': ':0', 18:18:23 INFO - 'HOME': '/home/cltbld', 18:18:23 INFO - 'LANG': 'en_US.UTF-8', 18:18:23 INFO - 'LANGUAGE': 'en_US:en', 18:18:23 INFO - 'LOGNAME': 'cltbld', 18:18:23 INFO - 'MAIL': '/var/mail/cltbld', 18:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:23 INFO - 'MOZ_NO_REMOTE': '1', 18:18:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:23 INFO - 'NO_EM_RESTART': '1', 18:18:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:23 INFO - 'PWD': '/builds/slave/test', 18:18:23 INFO - 'SHELL': '/bin/bash', 18:18:23 INFO - 'SHLVL': '1', 18:18:23 INFO - 'TERM': 'linux', 18:18:23 INFO - 'TMOUT': '86400', 18:18:23 INFO - 'USER': 'cltbld', 18:18:23 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', 18:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:23 INFO - '_': '/tools/buildbot/bin/python'} 18:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:18:24 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 18:18:24 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 18:18:24 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 18:18:24 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 18:18:24 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 18:18:24 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 18:18:25 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:18:26 INFO - Installing collected packages: mozsystemmonitor 18:18:26 INFO - Running setup.py install for mozsystemmonitor 18:18:26 INFO - Successfully installed mozsystemmonitor 18:18:26 INFO - Cleaning up... 18:18:26 INFO - Return code: 0 18:18:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:26 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 0x7ffa8cf02f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x119c410>, '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 0x119d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x119d7a0>, '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': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', '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 18:18:26 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 18:18:26 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 18:18:26 INFO - Using env: {'DISPLAY': ':0', 18:18:26 INFO - 'HOME': '/home/cltbld', 18:18:26 INFO - 'LANG': 'en_US.UTF-8', 18:18:26 INFO - 'LANGUAGE': 'en_US:en', 18:18:26 INFO - 'LOGNAME': 'cltbld', 18:18:26 INFO - 'MAIL': '/var/mail/cltbld', 18:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:26 INFO - 'MOZ_NO_REMOTE': '1', 18:18:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:26 INFO - 'NO_EM_RESTART': '1', 18:18:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:26 INFO - 'PWD': '/builds/slave/test', 18:18:26 INFO - 'SHELL': '/bin/bash', 18:18:26 INFO - 'SHLVL': '1', 18:18:26 INFO - 'TERM': 'linux', 18:18:26 INFO - 'TMOUT': '86400', 18:18:26 INFO - 'USER': 'cltbld', 18:18:26 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', 18:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:26 INFO - '_': '/tools/buildbot/bin/python'} 18:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:26 INFO - Downloading/unpacking blobuploader==1.2.4 18:18:26 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 18:18:26 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 18:18:26 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 18:18:26 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 18:18:26 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 18:18:26 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 18:18:28 INFO - Downloading blobuploader-1.2.4.tar.gz 18:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:18:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:18:28 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 18:18:28 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 18:18:28 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 18:18:28 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 18:18:28 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 18:18:28 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 18:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:18:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:18:28 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 18:18:28 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 18:18:28 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 18:18:28 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 18:18:28 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 18:18:28 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 18:18:29 INFO - Downloading docopt-0.6.1.tar.gz 18:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:18:29 INFO - Installing collected packages: blobuploader, requests, docopt 18:18:29 INFO - Running setup.py install for blobuploader 18:18:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:18:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:18:29 INFO - Running setup.py install for requests 18:18:29 INFO - Running setup.py install for docopt 18:18:29 INFO - Successfully installed blobuploader requests docopt 18:18:29 INFO - Cleaning up... 18:18:29 INFO - Return code: 0 18:18:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:29 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')]} 18:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:29 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')]} 18:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:29 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 0x7ffa8cf02f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x119c410>, '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 0x119d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x119d7a0>, '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': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', '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 18:18:29 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 18:18:29 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 18:18:29 INFO - Using env: {'DISPLAY': ':0', 18:18:29 INFO - 'HOME': '/home/cltbld', 18:18:29 INFO - 'LANG': 'en_US.UTF-8', 18:18:29 INFO - 'LANGUAGE': 'en_US:en', 18:18:29 INFO - 'LOGNAME': 'cltbld', 18:18:29 INFO - 'MAIL': '/var/mail/cltbld', 18:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:29 INFO - 'MOZ_NO_REMOTE': '1', 18:18:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:29 INFO - 'NO_EM_RESTART': '1', 18:18:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:29 INFO - 'PWD': '/builds/slave/test', 18:18:29 INFO - 'SHELL': '/bin/bash', 18:18:29 INFO - 'SHLVL': '1', 18:18:29 INFO - 'TERM': 'linux', 18:18:29 INFO - 'TMOUT': '86400', 18:18:29 INFO - 'USER': 'cltbld', 18:18:29 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', 18:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:29 INFO - '_': '/tools/buildbot/bin/python'} 18:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:29 INFO - Running setup.py (path:/tmp/pip-90__lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:30 INFO - Running setup.py (path:/tmp/pip-6TstYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:30 INFO - Running setup.py (path:/tmp/pip-JCSXUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:30 INFO - Running setup.py (path:/tmp/pip-EAo6gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:30 INFO - Running setup.py (path:/tmp/pip-myq7m5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:30 INFO - Running setup.py (path:/tmp/pip-S9Y5fL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:30 INFO - Running setup.py (path:/tmp/pip-T16fbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:30 INFO - Running setup.py (path:/tmp/pip-mWuvAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:30 INFO - Running setup.py (path:/tmp/pip-_svRID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:30 INFO - Running setup.py (path:/tmp/pip-TTKTmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:30 INFO - Running setup.py (path:/tmp/pip-GwfnH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:30 INFO - Running setup.py (path:/tmp/pip-NsljdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:30 INFO - Running setup.py (path:/tmp/pip-ftLiNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:30 INFO - Running setup.py (path:/tmp/pip-TO33m6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:30 INFO - Running setup.py (path:/tmp/pip-jRv2ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:31 INFO - Running setup.py (path:/tmp/pip-A3RMU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:31 INFO - Running setup.py (path:/tmp/pip-b7U3Sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:18:31 INFO - Running setup.py install for manifestparser 18:18:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:18:31 INFO - Running setup.py install for mozcrash 18:18:31 INFO - Running setup.py install for mozdebug 18:18:31 INFO - Running setup.py install for mozdevice 18:18:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:18:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:18:31 INFO - Running setup.py install for mozfile 18:18:31 INFO - Running setup.py install for mozhttpd 18:18:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:18:31 INFO - Running setup.py install for mozinfo 18:18:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:18:31 INFO - Running setup.py install for mozInstall 18:18:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:18:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:18:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:18:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:18:31 INFO - Running setup.py install for mozleak 18:18:32 INFO - Running setup.py install for mozlog 18:18:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:18:32 INFO - Running setup.py install for moznetwork 18:18:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:18:32 INFO - Running setup.py install for mozprocess 18:18:32 INFO - Running setup.py install for mozprofile 18:18:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:18:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:18:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:18:32 INFO - Running setup.py install for mozrunner 18:18:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:18:32 INFO - Running setup.py install for mozscreenshot 18:18:32 INFO - Running setup.py install for moztest 18:18:32 INFO - Running setup.py install for mozversion 18:18:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:18:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:18:32 INFO - Cleaning up... 18:18:32 INFO - Return code: 0 18:18:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:32 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 0x7ffa8cf02f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x119c410>, '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 0x119d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x119d7a0>, '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': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', '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 18:18:32 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 18:18:32 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 18:18:32 INFO - Using env: {'DISPLAY': ':0', 18:18:32 INFO - 'HOME': '/home/cltbld', 18:18:32 INFO - 'LANG': 'en_US.UTF-8', 18:18:32 INFO - 'LANGUAGE': 'en_US:en', 18:18:32 INFO - 'LOGNAME': 'cltbld', 18:18:32 INFO - 'MAIL': '/var/mail/cltbld', 18:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:32 INFO - 'MOZ_NO_REMOTE': '1', 18:18:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:32 INFO - 'NO_EM_RESTART': '1', 18:18:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:32 INFO - 'PWD': '/builds/slave/test', 18:18:32 INFO - 'SHELL': '/bin/bash', 18:18:32 INFO - 'SHLVL': '1', 18:18:32 INFO - 'TERM': 'linux', 18:18:32 INFO - 'TMOUT': '86400', 18:18:32 INFO - 'USER': 'cltbld', 18:18:32 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', 18:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:32 INFO - '_': '/tools/buildbot/bin/python'} 18:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:33 INFO - Running setup.py (path:/tmp/pip-C8wduO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:33 INFO - Running setup.py (path:/tmp/pip-4Il4dR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:33 INFO - Running setup.py (path:/tmp/pip-Y8D7OH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:33 INFO - Running setup.py (path:/tmp/pip-ttY_VS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:33 INFO - Running setup.py (path:/tmp/pip-CQC5ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:33 INFO - Running setup.py (path:/tmp/pip-jYOZGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:33 INFO - Running setup.py (path:/tmp/pip-dqZe0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:33 INFO - Running setup.py (path:/tmp/pip-YK6y6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:33 INFO - Running setup.py (path:/tmp/pip-xplLYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:33 INFO - Running setup.py (path:/tmp/pip-GNeMpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:33 INFO - Running setup.py (path:/tmp/pip-1WvPkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:33 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)) 18:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:33 INFO - Running setup.py (path:/tmp/pip-Ct6h4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:34 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)) 18:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:34 INFO - Running setup.py (path:/tmp/pip-JFxW_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:34 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)) 18:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:34 INFO - Running setup.py (path:/tmp/pip-C0rq2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:34 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)) 18:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:34 INFO - Running setup.py (path:/tmp/pip-DFOGEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:34 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)) 18:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:34 INFO - Running setup.py (path:/tmp/pip-vbSpaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:34 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)) 18:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:34 INFO - Running setup.py (path:/tmp/pip-LM19wY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:34 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)) 18:18:34 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)) 18:18:34 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)) 18:18:34 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)) 18:18:34 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)) 18:18:34 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)) 18:18:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:36 INFO - Downloading blessings-1.6.tar.gz 18:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:18:36 INFO - Installing collected packages: blessings 18:18:36 INFO - Running setup.py install for blessings 18:18:36 INFO - Successfully installed blessings 18:18:36 INFO - Cleaning up... 18:18:36 INFO - Return code: 0 18:18:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:18:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:36 INFO - Reading from file tmpfile_stdout 18:18:36 INFO - Current package versions: 18:18:36 INFO - argparse == 1.2.1 18:18:36 INFO - blessings == 1.6 18:18:36 INFO - blobuploader == 1.2.4 18:18:36 INFO - docopt == 0.6.1 18:18:36 INFO - manifestparser == 1.1 18:18:36 INFO - mozInstall == 1.12 18:18:36 INFO - mozcrash == 0.17 18:18:36 INFO - mozdebug == 0.1 18:18:36 INFO - mozdevice == 0.48 18:18:36 INFO - mozfile == 1.2 18:18:36 INFO - mozhttpd == 0.7 18:18:36 INFO - mozinfo == 0.9 18:18:36 INFO - mozleak == 0.1 18:18:36 INFO - mozlog == 3.1 18:18:36 INFO - moznetwork == 0.27 18:18:36 INFO - mozprocess == 0.22 18:18:36 INFO - mozprofile == 0.28 18:18:36 INFO - mozrunner == 6.11 18:18:36 INFO - mozscreenshot == 0.1 18:18:36 INFO - mozsystemmonitor == 0.0 18:18:36 INFO - moztest == 0.7 18:18:36 INFO - mozversion == 1.4 18:18:36 INFO - psutil == 3.1.1 18:18:36 INFO - requests == 1.2.3 18:18:36 INFO - wsgiref == 0.1.2 18:18:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:36 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 0x7ffa8cf02f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x119c410>, '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 0x119d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x119d7a0>, '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': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', '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 18:18:36 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 18:18:36 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 18:18:36 INFO - Using env: {'DISPLAY': ':0', 18:18:36 INFO - 'HOME': '/home/cltbld', 18:18:36 INFO - 'LANG': 'en_US.UTF-8', 18:18:36 INFO - 'LANGUAGE': 'en_US:en', 18:18:36 INFO - 'LOGNAME': 'cltbld', 18:18:36 INFO - 'MAIL': '/var/mail/cltbld', 18:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:36 INFO - 'MOZ_NO_REMOTE': '1', 18:18:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:36 INFO - 'NO_EM_RESTART': '1', 18:18:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:36 INFO - 'PWD': '/builds/slave/test', 18:18:36 INFO - 'SHELL': '/bin/bash', 18:18:36 INFO - 'SHLVL': '1', 18:18:36 INFO - 'TERM': 'linux', 18:18:36 INFO - 'TMOUT': '86400', 18:18:36 INFO - 'USER': 'cltbld', 18:18:36 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', 18:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:36 INFO - '_': '/tools/buildbot/bin/python'} 18:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:37 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)) 18:18:37 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)) 18:18:37 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)) 18:18:37 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)) 18:18:37 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)) 18:18:37 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)) 18:18:37 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)) 18:18:37 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)) 18:18:37 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)) 18:18:37 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)) 18:18:37 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)) 18:18:37 INFO - Cleaning up... 18:18:37 INFO - Return code: 0 18:18:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa8cf02f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x119c410>, '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 0x119d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x119d7a0>, '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': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', '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 18:18: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', 'jsonschema'] in /builds/slave/test/build 18:18: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 jsonschema 18:18:37 INFO - Using env: {'DISPLAY': ':0', 18:18:37 INFO - 'HOME': '/home/cltbld', 18:18:37 INFO - 'LANG': 'en_US.UTF-8', 18:18:37 INFO - 'LANGUAGE': 'en_US:en', 18:18:37 INFO - 'LOGNAME': 'cltbld', 18:18:37 INFO - 'MAIL': '/var/mail/cltbld', 18:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:37 INFO - 'MOZ_NO_REMOTE': '1', 18:18:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:37 INFO - 'NO_EM_RESTART': '1', 18:18:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:37 INFO - 'PWD': '/builds/slave/test', 18:18:37 INFO - 'SHELL': '/bin/bash', 18:18:37 INFO - 'SHLVL': '1', 18:18:37 INFO - 'TERM': 'linux', 18:18:37 INFO - 'TMOUT': '86400', 18:18:37 INFO - 'USER': 'cltbld', 18:18:37 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', 18:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:37 INFO - '_': '/tools/buildbot/bin/python'} 18:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:37 INFO - Downloading/unpacking jsonschema 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:18:39 INFO - Downloading/unpacking functools32 (from jsonschema) 18:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:39 INFO - Downloading functools32-3.2.3-2.tar.gz 18:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:18:39 INFO - warning: no files found matching '*.txt' 18:18:39 INFO - no previously-included directories found matching 'build' 18:18:39 INFO - no previously-included directories found matching 'dist' 18:18:39 INFO - no previously-included directories found matching '.git*' 18:18:39 INFO - Installing collected packages: jsonschema, functools32 18:18:39 INFO - Running setup.py install for functools32 18:18:39 INFO - warning: no files found matching '*.txt' 18:18:39 INFO - no previously-included directories found matching 'build' 18:18:39 INFO - no previously-included directories found matching 'dist' 18:18:39 INFO - no previously-included directories found matching '.git*' 18:18:39 INFO - Successfully installed jsonschema functools32 18:18:39 INFO - Cleaning up... 18:18:39 INFO - Return code: 0 18:18:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa8cf02f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x119c410>, '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 0x119d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x119d7a0>, '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': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', '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 18:18: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', 'flake8'] in /builds/slave/test/build 18:18: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 flake8 18:18:39 INFO - Using env: {'DISPLAY': ':0', 18:18:39 INFO - 'HOME': '/home/cltbld', 18:18:39 INFO - 'LANG': 'en_US.UTF-8', 18:18:39 INFO - 'LANGUAGE': 'en_US:en', 18:18:39 INFO - 'LOGNAME': 'cltbld', 18:18:39 INFO - 'MAIL': '/var/mail/cltbld', 18:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:39 INFO - 'MOZ_NO_REMOTE': '1', 18:18:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:39 INFO - 'NO_EM_RESTART': '1', 18:18:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:39 INFO - 'PWD': '/builds/slave/test', 18:18:39 INFO - 'SHELL': '/bin/bash', 18:18:39 INFO - 'SHLVL': '1', 18:18:39 INFO - 'TERM': 'linux', 18:18:39 INFO - 'TMOUT': '86400', 18:18:39 INFO - 'USER': 'cltbld', 18:18:39 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', 18:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:39 INFO - '_': '/tools/buildbot/bin/python'} 18:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:39 INFO - Downloading/unpacking flake8 18:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:18:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 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 18:18:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:18:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:18:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:18:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:18:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:18:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:18:42 INFO - no previously-included directories found matching 'docs/_build' 18:18:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:18:42 INFO - Running setup.py install for pep8 18:18:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:18:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:18:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:18:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:18:42 INFO - no previously-included directories found matching 'docs/_build' 18:18:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:18:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:18:42 INFO - Cleaning up... 18:18:42 INFO - Return code: 0 18:18:42 INFO - Running post-action listener: _resource_record_post_action 18:18:42 INFO - Running post-action listener: _start_resource_monitoring 18:18:42 INFO - Starting resource monitoring. 18:18:42 INFO - ##### 18:18:42 INFO - ##### Running install step. 18:18:42 INFO - ##### 18:18:42 INFO - Running pre-action listener: _resource_record_pre_action 18:18:42 INFO - Running main action method: install 18:18:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:43 INFO - Reading from file tmpfile_stdout 18:18:43 INFO - Detecting whether we're running mozinstall >=1.0... 18:18:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:18:43 INFO - Reading from file tmpfile_stdout 18:18:43 INFO - Output received: 18:18:43 INFO - Usage: mozinstall [options] installer 18:18:43 INFO - Options: 18:18:43 INFO - -h, --help show this help message and exit 18:18:43 INFO - -d DEST, --destination=DEST 18:18:43 INFO - Directory to install application into. [default: 18:18:43 INFO - "/builds/slave/test"] 18:18:43 INFO - --app=APP Application being installed. [default: firefox] 18:18:43 INFO - mkdir: /builds/slave/test/build/application 18:18:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:18:56 INFO - Reading from file tmpfile_stdout 18:18:56 INFO - Output received: 18:18:56 INFO - /builds/slave/test/build/application/firefox/firefox 18:18:56 INFO - Running post-action listener: _resource_record_post_action 18:18:56 INFO - ##### 18:18:56 INFO - ##### Running run-tests step. 18:18:56 INFO - ##### 18:18:56 INFO - Running pre-action listener: _resource_record_pre_action 18:18:56 INFO - Running main action method: run_tests 18:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:18:56 INFO - Python 2.7.3 18:18:56 INFO - Return code: 0 18:18:56 INFO - grabbing minidump binary from tooltool 18:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:56 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 0x119c410>, '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 0x119d310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x119d7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:18:56 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 18:18:56 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 18:18:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:18:56 INFO - Return code: 0 18:18:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:18:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:18:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:18:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:18:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:18:57 INFO - Return code: 0 18:18:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 18:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 18:18:57 INFO - Using env: {'DISPLAY': ':0', 18:18:57 INFO - 'HOME': '/home/cltbld', 18:18:57 INFO - 'LANG': 'en_US.UTF-8', 18:18:57 INFO - 'LANGUAGE': 'en_US:en', 18:18:57 INFO - 'LOGNAME': 'cltbld', 18:18:57 INFO - 'MAIL': '/var/mail/cltbld', 18:18:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:18:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:57 INFO - 'MOZ_NO_REMOTE': '1', 18:18:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:18:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:57 INFO - 'NO_EM_RESTART': '1', 18:18:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:57 INFO - 'PWD': '/builds/slave/test', 18:18:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:18:57 INFO - 'SHELL': '/bin/bash', 18:18:57 INFO - 'SHLVL': '1', 18:18:57 INFO - 'TERM': 'linux', 18:18:57 INFO - 'TMOUT': '86400', 18:18:57 INFO - 'USER': 'cltbld', 18:18:57 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1468458546.949499-965453286', 18:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:57 INFO - '_': '/tools/buildbot/bin/python'} 18:18:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 18:18:57 INFO - mozversion application_buildid: 20160713152943 18:18:57 INFO - mozversion application_changeset: f8051a3ef4ec5842689306cc3d7b22a6ec439c9a 18:18:57 INFO - mozversion application_display_name: Firefox 18:18:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:18:57 INFO - mozversion application_name: Firefox 18:18:57 INFO - mozversion application_remotingname: firefox 18:18:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:18:57 INFO - mozversion application_vendor: Mozilla 18:18:57 INFO - mozversion application_version: 48.0 18:18:57 INFO - mozversion platform_buildid: 20160713152943 18:18:57 INFO - mozversion platform_changeset: f8051a3ef4ec5842689306cc3d7b22a6ec439c9a 18:18:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:18:57 INFO - mozversion platform_version: 48.0 18:18:57 INFO - using testdate: 1468459137 18:18:57 INFO - actual date: 1468459137 18:18:57 INFO - starting webserver on 'localhost:48463' 18:18:57 INFO - SUITE-START | Running 2 tests 18:18:57 INFO - TEST-START | dromaeo_css 18:18:57 INFO - operating with platform_type : linux_ 18:18:57 INFO - Initialising browser for dromaeo_css test... 18:18:57 INFO - TEST-INFO | started process 9123 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcZvzsI/profile http://localhost:48463/getInfo.html) 18:19:01 INFO - PROCESS | 9123 | 18:19:01 INFO - PROCESS | 9123 | (process:9201): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:19:02 INFO - PROCESS | 9123 | __metrics Screen width/height:1600/1200 18:19:02 INFO - PROCESS | 9123 | colorDepth:24 18:19:02 INFO - PROCESS | 9123 | Browser inner width/height: 1024/697 18:19:02 INFO - PROCESS | 9123 | __metrics 18:19:02 ERROR - PROCESS | 9123 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 18:19:03 INFO - TEST-INFO | 9123: exit 0 18:19:03 INFO - Browser initialized. 18:19:03 INFO - Running cycle 1/1 for dromaeo_css test... 18:19:03 INFO - TEST-INFO | started process 9251 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcZvzsI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:19:05 INFO - PROCESS | 9251 | 18:19:05 INFO - PROCESS | 9251 | (process:9320): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:19:15 INFO - PROCESS | 9251 | RSS: Main: 150822912 18:19:15 INFO - PROCESS | 9251 | 18:20:05 INFO - PROCESS | 9251 | 1468459205020 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] 18:22:12 INFO - PROCESS | 9251 | Cycle 1(1): loaded http://localhost:48463/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48463/tests/dromaeo/cssquery-ext.html) 18:22:13 INFO - PROCESS | 9251 | RSS: Main: 155865088 18:22:13 INFO - PROCESS | 9251 | 18:25:15 INFO - PROCESS | 9251 | Cycle 1(1): loaded http://localhost:48463/tests/dromaeo/cssquery-ext.html (next: http://localhost:48463/tests/dromaeo/cssquery-jquery.html) 18:25:15 INFO - PROCESS | 9251 | RSS: Main: 152887296 18:25:15 INFO - PROCESS | 9251 | 18:28:17 INFO - PROCESS | 9251 | Cycle 1(1): loaded http://localhost:48463/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48463/tests/dromaeo/cssquery-mootools.html) 18:28:18 INFO - PROCESS | 9251 | RSS: Main: 152829952 18:28:18 INFO - PROCESS | 9251 | 18:31:19 INFO - PROCESS | 9251 | Cycle 1(1): loaded http://localhost:48463/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48463/tests/dromaeo/cssquery-prototype.html) 18:31:20 INFO - PROCESS | 9251 | RSS: Main: 153608192 18:31:20 INFO - PROCESS | 9251 | 18:34:22 INFO - PROCESS | 9251 | Cycle 1(1): loaded http://localhost:48463/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48463/tests/dromaeo/cssquery-yui.html) 18:34:23 INFO - PROCESS | 9251 | RSS: Main: 152563712 18:34:23 INFO - PROCESS | 9251 | 18:37:25 INFO - PROCESS | 9251 | Cycle 1(1): loaded http://localhost:48463/tests/dromaeo/cssquery-yui.html (next: http://localhost:48463/tests/dromaeo/cssquery-dojo.html) 18:37:25 INFO - PROCESS | 9251 | RSS: Main: 156446720 18:37:25 INFO - PROCESS | 9251 | 18:37:25 INFO - PROCESS | 9251 | __start_tp_report 18:37:25 INFO - PROCESS | 9251 | _x_x_mozilla_page_load 18:37:25 INFO - PROCESS | 9251 | _x_x_mozilla_page_load_details 18:37:25 INFO - PROCESS | 9251 | |i|pagename|runs| 18:37:25 INFO - PROCESS | 9251 | |0;dojo.html;3353.3125709229603;3398.2353970356667;3404.9489257661153;3422.965770342296;3467.687908088273;5593.692346920918;5615.719214039302;5620.859478513039;5642.661440313584;5700.800471983482;5628.183913332565;5645.294338207724;5678.205051292812;5678.48893599577;5679.261695979517;5097.184450487923;5146.459621739719;5180.04169228692;5201.765920533566;5231.869203269911;5088.363954505687;5136.101182293084;5136.28092067109;5298.761555730001;5299.046171689094;5143.588512918957;5175.24958880962;5181.559567436772;5183.3002544656365;5185.611079169059;5145.588352931775;5155.484451554833;5160.122779127557;5170.5346518813485;5191.922121168185;4978.77595508203;4980.153373926441;5032.194848824171;5032.924506132411;5059.392872855281;5452.672839629635;5454.209139674765;5459.645123067027;5481.808136715196;5496.862578435531;5937.109433584997;5946;5949.940500594982;5951.107333899907;5952.9107063394085;5979.133025711248;5992.79025234119;5994.8501287467725;6118.436443820604;6123.724432400552;5571.693556854331;5573.498385145316;5578.30271216098;5579.581531385163;5586.13414920688;6021.036634138517;6050.3949605040025;6050.57645964787;6061.333253342129;6072.240969878765;5754.367019627837;5885.676287804212;5942.1978032965;5967.46292833647;5981.431763982415;6033.125196846433;6055.1220073090035;6059.454649081604;6061.605995610271;6067.848303792352;5782.219400380979;6004.909926351108;6014.849628759316;6016.42843929826;6024.066269728111;5914.704264786744;5947.167396564484;5949.345571987078;5955.493783028491;5981.102834574848;4509.323601459741;4569.611583015414;4575.359449677047;4580.610648094883;4603.746793926366;4055.41196526502;4090.8977275567754;4118.423420721101;4124.463819703419;4144.150449157915;5612.35457922336;5762.971185144047;5810.564207684441;5891.882162356729;5932.495737862244;5956.136360227742;6018.368071352533;6019.879602407927;6023.879522409527;6029.517638588903;5585.609007369445;5795.420457954255;5816.302043754777;5828.125781132864;5836.358000619928;5098.4646612105935;5133.358330208724;5133.614978876524;5157.381114266312;5160.200168973815;6309.400606942333;6313.463355614732;6314;6330.177076979963;6333.936660633427;5839.445252700986;5919.733611987557;5944.108383742473;6045.032794752907;6066.787662431793;6031.5476339273855;6145.371476558805;6254.405831446005;6272.937270627235;6322.241331040304;5909.881802364014;5961.970190149022;5964.5526585505395;5968.16445697594;5996.88006239864;5846;5991.161237426851;6053.515718742402;6085.087236914498;6091.238595175603;5812.593118481665;5868.471837534642;5879.176915231786;5882.352941176381;5904.232449781501;5784.248047753765;5850.063989761617;5852.619579727304;5853.268341457318;5857.209276747584;5605.4114317997655;5678.77284908599;5770.538356931352;5772.422757724194;5782.335031471266;5866.6186697864505;5902.7343769531335;5908.970455147697;5965;5994.490468310242;5805.245318108618;5819.388964158872;5822.650640961556;5827.650340979555;5827.79602713903 18:37:25 INFO - PROCESS | 9251 | |1;ext.html;608.0453687710294;658.9703463344149;668.775960053382;672.7275453441358;678.7352932356379;39862.80068599657;40578.18843623129;40749.185016299656;41108.79445602772;41212.38181427281;43403;44203.33694994578;44205.11589768203;44227.3365899511;44543.77728111363;8745.34409919257;9000.189982901538;9010.18908298253;9012.504312262823;9027.14242146995;8791.164839340252;8909.95545022274;8937.150970657809;8958.37291389603;8964.8655270171;9124.31567632427;9167.991520932725;9181.081891810833;9197.770055748626;9232.907670923305;9130.634774609007;9195.540222988824;9197.448153110801;9198.95400522993;9223.999999999967;9109.726708198765;9201.217896478807;9203.171714545657;9217.631294748202;9223.769405764842;43723;44147.337789933175;44203;44242.557574424485;44440;3543.273628906068;3856.7878766667823;3904.063024874038;3973.284808734427;4571.497135315112;29748;29855.701442985508;30059.549106763418;30066;30246.24384390386;4358.455193100863;4443.178012067778;4527.253003254459;4531;4544.5228251033495;289969;292772;293134;294223.52888235636;295766;39365.212695745926;39786.2042759149;39876.80061599673;39896.40155397671;61108.38891611116;38241.2351752969;38357.232855342736;38364.8081759595;38440;38458.42312365317;17020.829791701923;17104.57238569046;17150.31398744061;17220.741688874677;17254.568635784206;14447.133172009713;14451;14498;14547.127172369692;14621.78067328991;9354.391964522285;9374.67188648394;9383.718488445358;9386.85919711205;9444.244460443286;3548.254866478017;3588.515550400715;3663.7618554793853;3688.243909998444;3704.1294146456407;1078.1698092468912;1104.8342748587777;1107.6566264457883;1169.818678104895;1196.7307355844866;31838;31932.521012184836;32293.677063229068;33042.17394565107;33368.66631333704;1132.018966808086;1148.787474317254;1194.7192409783695;1209.7580483903255;1347.9595612131652;15353.923230384;16120.27458764335;16240.67518649644;16341.91829040871;16582.585435364213;513761;603249;614002;615411;618971;58534.414655852896;59148.11277830836;59166.112508312406;59375;59570;840.0255703384045;1455.294182321573;1590.6421055262704;1594.2507021699782;1605.7511085781957;32463;33525;34378;34408;34541.82729086339;55496.72251638716;55706.16440753473;56214.718926405105;56241.71879140742;56254.71872640611;452175;452276;456049;457343;457652;47139.05721885612;47623.52376476191;48302.75848620734;48813;49270.26094608656;45501.54498454973;46039.539604603524;46562;46773.2984005247;47710.284345734144;38839.80580097081;39152.21695566129;39786.403203951384;39843.402348964206;40051.198976020896;41930.55055775449;43055.569444306406;43074.569254307055;43466.78266608647;43657.56342436662;42663;43210.78394608007;44593;45551.77224113991;46310.073798524514;37594.06014849651;37646.24707505889;37667.2466550662;38300.233995320494;38872.6112738869;27768.583471248352;27927.581086283317;27953.161405157876;28356.149315520568;28432.289192770346 18:37:25 INFO - PROCESS | 9251 | |2;jquery.html;422.6428667775729;427.82459191731397;432.70792215254716;437.7614200260858;441.2454702458795;18732.62534749306;19231.423057308268;19374.70937935932;19402;19914.203431862745;18439.631207375845;18774.06129693513;19301.420957371265;19317.71023434646;19354.90322548389;5525.309336332958;5782.971085144568;5785.537157027448;5790.884182316351;5934.228550288456;4043.4743483347124;5586.972065139689;5615.719214039302;5679.488846003859;5940.227770389865;5911.113333000063;5922.674252916088;5934.792282270121;5946.821595352147;5953.613015154001;5866.384029676887;5874.1776151338845;6482.546221764478;6488.318726533693;6493.6103833769885;5707.971460142714;5726.112452569859;5729.140628905698;5736.74184661687;5768.596198266123;22401.77598224013;23013.88493057541;23741.406464838343;23932.760672393226;24111.397215069588;2401.543706695722;2667.6398686177313;2896.8986085487118;2920.678725340211;3031.166429231957;10555.68332950005;10571.947140264327;10723.62467313648;10854.24020318578;11008.559657613685;1937.4187743676982;1955.139738515063;2082.79172082792;2097.5804839032253;2104.1793700456833;80226.59886700587;80652.59673701593;80844.59577702133;81769.59115204385;82202.17797822064;10518.158547316196;11148.888511114947;11392.145589080686;11658.6502404928;11721.706957326134;15723.921380393025;16481.25834337458;16639.5856352209;16657.583560411087;16709.91645041767;8415.45299555527;8444.915550844536;8450.830983380298;8461.746147615582;8570.4000719949;8409.579521023925;8429.157084291523;8452.57737113142;8503.277221436247;8611.6985905493;4925.605951523869;5001.774920128603;5018.774155163026;5050.545450909436;5062.670926389773;1871.3263225238902;1926.9132889020027;1932.8357089647538;1963.9803601964084;1994.8304394126371;1006.7835415385728;1037.818381783185;1044.8589440425446;1070.4808168038496;1156.19066653343;17808.554786130193;18110.81889181091;18591.34930277434;18799.624007519775;18944.431667050012;1086.163653986426;1095.5124969388546;1114.0047728915654;1130.3444002478543;1140.8802075781923;10500.054995050483;10691.786164276671;10701.89298107009;10743.51654175564;10786.35281883074;523795;533885;537949;538385;548531;86646.56676716576;97192.51403742936;99010;99827.50086249522;103519.48240258901;1083.869935607732;1104.094642393246;1124.9325040497597;1125.1448898836943;1126.3129491010477;17402.912985435498;17444.563885902953;17646.20592073334;17779.644407112042;17787.288508460035;40148;40707.796461017504;40947.590524094376;40948;41709.58290417057;57957.71021144867;62080;62086;62241.377586223556;62390;8642.43824151428;8976.730698079067;9102.36283460178;9244.306676999118;9318.347715659838;12536.623901282612;12651.114421990373;12949.870501295243;13150.802737958747;13563.59309220725;11857.940710296394;12054.939725301318;12300.753984920431;12427.378631068592;12427.813582796085;12092.818607721067;12239.632811015685;12304.084229435464;12359.938200308941;12488.625341239778;12283.877161228631;12474.313912734886;12661.556845510362;12685.175463594836;12799.872001279868;9080.091990801131;9192.770180745534;9240.075992400707;9278.211352035152;9354.625814967598;12308.692282693004;12534.811977820522;12634.178778379375;12635.557755478514;12639.494420223456 18:37:25 INFO - PROCESS | 9251 | |3;mootools.html;1573.6301969037277;1594.0515393340959;1779.4928445393066;1888.4523488188418;1938.8739731917415;7481.850362992737;8243.67025318988;8548.871766923494;8763.07987661296;8773.956130219349;8325.084240733515;8426.073131955496;8517.744467665965;8578.099299573549;8611.61247743853;2471.9752802472017;2484.416162201881;2502.36189771608;2511.698596168453;2519.143491212987;2499.462615537668;2552.1450314144786;2552.2343297010916;2558.3476213565514;2558.667373241476;2402.171250918439;2406.0496104038893;2439.524292762912;2456.152627343564;2458.2256589174444;2476.529459402726;2498.113169824704;2500.9749902500926;2509.648649189115;2516.069054449847;2409.361519197414;2534.6958364996135;2538.2004668529353;2559.9360015999564;2571.1772232885482;8709;8712.259457946082;8740.3444741644;8756.912430875736;8771.298296136358;5283.894322113575;5454.127339625641;5462.153366228241;5469.644473109275;5475.123980163195;3629.6188900165366;3649.6532829381167;3657.9451308230396;3710.4805327254207;3747.868824591126;1264.8988080953502;1281.6539534325773;1298.9090763646548;1307.3397934043248;1316.3549860568348;12235.510579576807;12406.255624662459;12422.254664720145;12432.564860229848;12436.316002619853;4689.742064186503;4715.646326525524;4747.7626118693925;4764.595009424238;4872.585830204402;10683.03852653265;10689.518971646294;10702.73243168927;10707.196960228013;10707.892921070845;5485.725713714378;5525.226468294361;5534.695591742412;5546.77812887488;5562.360328562187;4434.512203657595;4876.146674331978;4977.552020318117;5000.099982003201;5056.747162641853;4221.240176768211;4230;4245.299525578245;4248.4477017987465;4342;1368.6441525203518;1410.2807568140322;1412.7386433509835;1414.5402744108;1416.6954104867298;917.3945196170496;1077.3697387028676;1078.4661592511757;1085.180688580117;1105.2705214558353;6002.69986500682;6077.908831367533;6205.410486003822;6209.192804935329;6287.622742635456;997.1125698128672;997.6607953295915;1037.9429131397847;1064.1061508333037;1102.3165637304921;4306.763128027926;4373.387725718402;4377.956220437819;4405.185040767404;4412.42638457005;10637.095846853104;10914.345139291509;10964.122870170368;11001.22991390611;11054.668359949215;10100.393976361294;10306;10354.741131471774;10371.222158338003;10390.48047597617;8079.717209897625;8132.186781321939;8165;8182.959085204655;8206.8358632827;6098.664573448503;6160.075988601746;6211.720472578654;6229.345918678655;6244.845642518468;9663.855042174233;9669.951650241705;9723.562439690373;9809.656662016796;9824.557894894604;9688.75778105553;9946.651867184613;9957.800843982935;9965.601375944882;10082.647107351208;7465.664045117874;7695.268949449794;7772.45592808498;7802.336801371947;7809.219078092373;8499.660013599387;8505.617247223767;8509.361797865062;8553.144685531397;8557.700480483154;7544.09470863478;7728.536287822749;7761.534307941542;7805;7847.5683837387205;7505.774826755206;7510.136334321623;7600.619969001417;7643.426742994409;7694.884576731241;6660.400563949268;6681;6707.765228216989;6710.563813352116;6724.831879203059;5035.295058691713;5055.924161137659;5100.923486147636;5173.430922598436;5197.948020519746;5107.310513080835;5179.801755763812;5240.606954478506;5260.73696315175;5278.551323137576 18:37:25 INFO - PROCESS | 9251 | |4;prototype.html;334.15625545497613;334.40475952804;352.0670223906647;357.85685725709715;357.9194681196733;6511.186101737283;6525.249596296428;6529.347065293468;6556.901646475307;6605;6494.577852439588;6498.577592456487;6500.349965003497;6503.8374040649005;6511;4737.928931066028;4877.3903262092235;4911.631627627924;4911.705297682151;4914.459409464956;4875.414950205963;4890.437599676029;4900.362952816146;4904.4114706235305;4906.877328066791;4835.734034628094;4837.516248375169;4840.733759643218;4852.902941941159;4853.320535125085;4812.446568644616;4836.588889944342;4837.975810120962;4844;4864.708117512961;4220.198162349144;4222.387753775685;4222.5566315536735;4223.873283801491;4224.345226489868;6153.876922461571;6235.844103897393;6270.9372906271265;6290.276618188921;6292.716827742717;5613.66318020916;5636.887262254773;5648.068068768648;5661.688607126606;5662.49037586615;6808;6810.62541560214;6815.148106486689;6817.488688348344;6836.4530837532875;4652.5114862939245;4652.74409907455;4659.324397962277;4666.090112428078;4667;7106.827373483369;7115.1461824580865;7184.784456466315;7187.964060179718;7194.064771579713;4575.473820510684;4661.603763680057;4668.673192876534;4682.086993036325;4692.601128904013;4694.272387779832;4726.172919739032;4728.929066064012;4733;4744.905101898012;5558.027345214571;5558.944410555923;5564.610477266552;5565.443455654402;5569.27599412082;6861.4853885957755;6885.931140688629;6893.517453778188;6900.068490753784;6933.93066069343;1678.2867281405354;1698.694235037705;1699.7960244770459;1701.0644145719805;1702.8126906040325;1057.5082586597268;1096.2435919215875;1098.8846171152074;1100.5872797700863;1103.0623969625753;6766.6278354689985;6778.152730908637;6790.0493930849725;6795.932040679629;6796.966015169992;7125.857482850418;7134.643267836587;7149.714011439484;7160.57036577807;7172.713091476387;6638.103855979478;6833.111695479556;6833.3166683331265;6853.554518956252;6858.554193977376;3864.381698928158;3892.902677433087;3896.922061558753;3900.258950799339;3903.512060992376;7520.21037791035;7535.849283014309;7544.547327160388;7550.244975502515;7554.735584254525;7174.1032370953635;7314.561126332437;7317.926820731725;7328.450366222554;7348.375388092072;7537.8115547111765;7549.886751698729;7550.886736698954;7556.055493063367;7557.206493318233;7168.89246661311;7183.138023437221;7201.891971620325;7210.495265331377;7222.09723784527;7049.612271325126;7062.39969602569;7101.893471597827;7114.964425177841;7125.608091555015;7363.926360736538;7454.217307182885;7510.361619262206;7525.473216874766;7532.811679708051;4805.423349198119;4831.251156070814;4847.80608775645;4859.8579333855805;4863.392075990501;7144.928550714426;7163.785086447415;7169.7132114716915;7178;7185;6854.34883686049;6957.582545047313;6964.129483814523;6969;6977.79066628002;6794.218664853605;6923.584584924921;6934.895976560457;6947.340002699735;6949.339812717783;6797.1843378794865;6826.317368263134;6832.658367081526;6846.486513511407;6850.0752398425575;6973.860522789414;6975.651217439209;6979.092717946634;6981.511294209559;6990.580565166107;6809.591424514545;6826.249112597511;6830.112085428863;6832.555883867532;6836.28219036994 18:37:25 INFO - PROCESS | 9251 | |5;yui.html;841.045413455728;857.1043259793518;867.9739607811764;870.8345414371273;937.8499440089579;14856.25718714064;15199.77200341993;15282.235888205587;15285.31216095277;15321.616959576017;13829.654258643539;13849.169049857015;13851.376688049037;13858.861411385884;13888.4444622215;872.6378552900538;899.1862364560051;902.8149229407956;906.6735975048977;912.9726108216731;917.3670167584379;917.6283605139929;922.9630814767402;925.4401087342162;926.9768255793591;951.490952340498;951.6241084771511;953.9618415263382;954.4654993203793;954.5847556312991;946.2335508238315;949.1599934058339;952.8665986761858;953.4517652349857;954.627695198873;698.2005603583868;699.8740226759181;700.6741865032784;700.8913618389158;703.6516924122541;14558.4904528341;14701.411943522246;14707.117572945657;14707.411703531845;14801.925990370088;14627.122372657568;14719.411223551153;14753.409863605442;14764.630884227872;14808.185549794756;11781.540663009782;11839;11907.912409340117;12032.27806331623;12123.393830308449;3795.981020094882;3813.809309534512;3838.2323535292776;3945.6448919597374;3961.485006949078;58827.11759323614;59437.702811486095;59773.402265977216;60402.697986510226;60495.09257361099;10033.297669163088;10230.079292863678;10244.385336879812;10445.268831181897;11625.128115391379;19672.213111475667;19710.21159153618;19722.211111555665;19792.40622781319;19944;8392.370572207108;8527.829443411098;8544.273736732448;8632.697855575025;8767.605457754416;4795.304680821261;4853.19922212838;4885.022995400863;4910.729909854992;4967.05625931072;3614.7831130132276;3657.030886814996;3661.4690869823726;3667.0465678923138;3679;623.2116372788427;696.5020010692278;702.8875379939185;703.809971307744;704.3308856586264;772.4631381190019;808.7775861638097;809.6478032056075;818.60706860706;827.9503229806111;8949.776255593531;9024;9028.774280642905;9067.863982040275;9090.727278181665;683.0335075867691;724.3517052238323;754.1252147508864;779.9142094369615;809.514291425152;4276.615104640598;4287.6355509782015;4396.912061758747;4446.82212711488;4528.50186479487;60927;61096.69451652713;61601;61652.69173654193;61985.38014619796;53962;55241.723791380784;55779.442205578234;55971.72014139903;55976.72011639997;49145.26282105771;50227;50380;50461.73845653888;50797.49202507928;8489.363297752816;8587.570621469027;8626.61180246903;8635.222829945335;8690.130986901258;36049.63950360463;36078.8196059018;37129.25741485101;37181.25637487181;37210.6278937218;42831.785841070596;44338.3349249768;45449.545504544534;45850.54149458463;46493.302600460345;11546.307221566733;13501.729965400833;13571.796423053465;13674.521391751241;13758.31208439594;14013;14252.786208206679;14253.786193207317;14488;15005.849941500883;12657.367131643197;13290.933545332211;13320.93339533296;13398.598042058753;13525.93237033848;12923.15999460032;13091.214527128403;13155;13183.472661093449;13237.801432978706;13343.799843002169;13348.733025339634;13829.446822127002;13856.099353541988;13862.02965792385;10895.91040895904;11099.445027748741;11144.777104458028;11197.384143872165;11736.413179340829;10732.248742587944;11146.665600032013;11202.271852329572;11452.427378630868;11521.308721476738 18:37:25 INFO - PROCESS | 9251 | __end_tp_report 18:37:25 INFO - PROCESS | 9251 | __start_cc_report 18:37:25 INFO - PROCESS | 9251 | _x_x_mozilla_cycle_collect,319 18:37:25 INFO - PROCESS | 9251 | __end_cc_report 18:37:25 INFO - PROCESS | 9251 | __startTimestamp1468460245627__endTimestamp 18:37:25 INFO - PROCESS | 9251 | 18:37:25 INFO - PROCESS | 9251 | ------- Summary: start ------- 18:37:25 INFO - PROCESS | 9251 | Number of tests: 6 18:37:25 INFO - PROCESS | 9251 | 18:37:25 INFO - PROCESS | 9251 | [#0] dojo.html Cycles:165 Average:5588.71 Median:5822.65 stddev:615.36 (10.6%) stddev-sans-first:591.73 18:37:25 INFO - PROCESS | 9251 | Values: 3353.3 3398.2 3404.9 3423.0 3467.7 5593.7 5615.7 5620.9 5642.7 5700.8 5628.2 5645.3 5678.2 5678.5 5679.3 5097.2 5146.5 5180.0 5201.8 5231.9 5088.4 5136.1 5136.3 5298.8 5299.0 5143.6 5175.2 5181.6 5183.3 5185.6 5145.6 5155.5 5160.1 5170.5 5191.9 4978.8 4980.2 5032.2 5032.9 5059.4 5452.7 5454.2 5459.6 5481.8 5496.9 5937.1 5946.0 5949.9 5951.1 5952.9 5979.1 5992.8 5994.9 6118.4 6123.7 5571.7 5573.5 5578.3 5579.6 5586.1 6021.0 6050.4 6050.6 6061.3 6072.2 5754.4 5885.7 5942.2 5967.5 5981.4 6033.1 6055.1 6059.5 6061.6 6067.8 5782.2 6004.9 6014.8 6016.4 6024.1 5914.7 5947.2 5949.3 5955.5 5981.1 4509.3 4569.6 4575.4 4580.6 4603.7 4055.4 4090.9 4118.4 4124.5 4144.2 5612.4 5763.0 5810.6 5891.9 5932.5 5956.1 6018.4 6019.9 6023.9 6029.5 5585.6 5795.4 5816.3 5828.1 5836.4 5098.5 5133.4 5133.6 5157.4 5160.2 6309.4 6313.5 6314.0 6330.2 6333.9 5839.4 5919.7 5944.1 6045.0 6066.8 6031.5 6145.4 6254.4 6272.9 6322.2 5909.9 5962.0 5964.6 5968.2 5996.9 5846.0 5991.2 6053.5 6085.1 6091.2 5812.6 5868.5 5879.2 5882.4 5904.2 5784.2 5850.1 5852.6 5853.3 5857.2 5605.4 5678.8 5770.5 5772.4 5782.3 5866.6 5902.7 5909.0 5965.0 5994.5 5805.2 5819.4 5822.7 5827.7 5827.8 18:37:25 INFO - PROCESS | 9251 | 18:37:25 INFO - PROCESS | 9251 | [#1] ext.html Cycles:180 Average:60311.02 Median:43142.68 stddev:123686.83 (286.7%) stddev-sans-first:123952.58 18:37:25 INFO - PROCESS | 9251 | Values: 608.0 659.0 668.8 672.7 678.7 39862.8 40578.2 40749.2 41108.8 41212.4 43403.0 44203.3 44205.1 44227.3 44543.8 8745.3 9000.2 9010.2 9012.5 9027.1 8791.2 8910.0 8937.2 8958.4 8964.9 9124.3 9168.0 9181.1 9197.8 9232.9 9130.6 9195.5 9197.4 9199.0 9224.0 9109.7 9201.2 9203.2 9217.6 9223.8 43723.0 44147.3 44203.0 44242.6 44440.0 3543.3 3856.8 3904.1 3973.3 4571.5 29748.0 29855.7 30059.5 30066.0 30246.2 4358.5 4443.2 4527.3 4531.0 4544.5 289969.0 292772.0 293134.0 294223.5 295766.0 39365.2 39786.2 39876.8 39896.4 61108.4 38241.2 38357.2 38364.8 38440.0 38458.4 17020.8 17104.6 17150.3 17220.7 17254.6 14447.1 14451.0 14498.0 14547.1 14621.8 9354.4 9374.7 9383.7 9386.9 9444.2 3548.3 3588.5 3663.8 3688.2 3704.1 1078.2 1104.8 1107.7 1169.8 1196.7 31838.0 31932.5 32293.7 33042.2 33368.7 1132.0 1148.8 1194.7 1209.8 1348.0 15353.9 16120.3 16240.7 16341.9 16582.6 513761.0 603249.0 614002.0 615411.0 618971.0 58534.4 59148.1 59166.1 59375.0 59570.0 840.0 1455.3 1590.6 1594.3 1605.8 32463.0 33525.0 34378.0 34408.0 34541.8 55496.7 55706.2 56214.7 56241.7 56254.7 452175.0 452276.0 456049.0 457343.0 457652.0 47139.1 47623.5 48302.8 48813.0 49270.3 45501.5 46039.5 46562.0 46773.3 47710.3 38839.8 39152.2 39786.4 39843.4 40051.2 41930.6 43055.6 43074.6 43466.8 43657.6 42663.0 43210.8 44593.0 45551.8 46310.1 37594.1 37646.2 37667.2 38300.2 38872.6 27768.6 27927.6 27953.2 28356.1 28432.3 18:37:25 INFO - PROCESS | 9251 | 18:37:25 INFO - PROCESS | 9251 | [#2] jquery.html Cycles:180 Average:30737.37 Median:12252.98 stddev:88323.80 (720.8%) stddev-sans-first:88542.24 18:37:25 INFO - PROCESS | 9251 | Values: 422.6 427.8 432.7 437.8 441.2 18732.6 19231.4 19374.7 19402.0 19914.2 18439.6 18774.1 19301.4 19317.7 19354.9 5525.3 5783.0 5785.5 5790.9 5934.2 4043.5 5587.0 5615.7 5679.5 5940.2 5911.1 5922.7 5934.8 5946.8 5953.6 5866.4 5874.2 6482.5 6488.3 6493.6 5708.0 5726.1 5729.1 5736.7 5768.6 22401.8 23013.9 23741.4 23932.8 24111.4 2401.5 2667.6 2896.9 2920.7 3031.2 10555.7 10571.9 10723.6 10854.2 11008.6 1937.4 1955.1 2082.8 2097.6 2104.2 80226.6 80652.6 80844.6 81769.6 82202.2 10518.2 11148.9 11392.1 11658.7 11721.7 15723.9 16481.3 16639.6 16657.6 16709.9 8415.5 8444.9 8450.8 8461.7 8570.4 8409.6 8429.2 8452.6 8503.3 8611.7 4925.6 5001.8 5018.8 5050.5 5062.7 1871.3 1926.9 1932.8 1964.0 1994.8 1006.8 1037.8 1044.9 1070.5 1156.2 17808.6 18110.8 18591.3 18799.6 18944.4 1086.2 1095.5 1114.0 1130.3 1140.9 10500.1 10691.8 10701.9 10743.5 10786.4 523795.0 533885.0 537949.0 538385.0 548531.0 86646.6 97192.5 99010.0 99827.5 103519.5 1083.9 1104.1 1124.9 1125.1 1126.3 17402.9 17444.6 17646.2 17779.6 17787.3 40148.0 40707.8 40947.6 40948.0 41709.6 57957.7 62080.0 62086.0 62241.4 62390.0 8642.4 8976.7 9102.4 9244.3 9318.3 12536.6 12651.1 12949.9 13150.8 13563.6 11857.9 12054.9 12300.8 12427.4 12427.8 12092.8 12239.6 12304.1 12359.9 12488.6 12283.9 12474.3 12661.6 12685.2 12799.9 9080.1 9192.8 9240.1 9278.2 9354.6 12308.7 12534.8 12634.2 12635.6 12639.5 18:37:25 INFO - PROCESS | 9251 | 18:37:25 INFO - PROCESS | 9251 | [#3] mootools.html Cycles:180 Average:5849.68 Median:4988.83 stddev:3177.19 (63.7%) stddev-sans-first:3169.85 18:37:25 INFO - PROCESS | 9251 | Values: 1573.6 1594.1 1779.5 1888.5 1938.9 7481.9 8243.7 8548.9 8763.1 8774.0 8325.1 8426.1 8517.7 8578.1 8611.6 2472.0 2484.4 2502.4 2511.7 2519.1 2499.5 2552.1 2552.2 2558.3 2558.7 2402.2 2406.0 2439.5 2456.2 2458.2 2476.5 2498.1 2501.0 2509.6 2516.1 2409.4 2534.7 2538.2 2559.9 2571.2 8709.0 8712.3 8740.3 8756.9 8771.3 5283.9 5454.1 5462.2 5469.6 5475.1 3629.6 3649.7 3657.9 3710.5 3747.9 1264.9 1281.7 1298.9 1307.3 1316.4 12235.5 12406.3 12422.3 12432.6 12436.3 4689.7 4715.6 4747.8 4764.6 4872.6 10683.0 10689.5 10702.7 10707.2 10707.9 5485.7 5525.2 5534.7 5546.8 5562.4 4434.5 4876.1 4977.6 5000.1 5056.7 4221.2 4230.0 4245.3 4248.4 4342.0 1368.6 1410.3 1412.7 1414.5 1416.7 917.4 1077.4 1078.5 1085.2 1105.3 6002.7 6077.9 6205.4 6209.2 6287.6 997.1 997.7 1037.9 1064.1 1102.3 4306.8 4373.4 4378.0 4405.2 4412.4 10637.1 10914.3 10964.1 11001.2 11054.7 10100.4 10306.0 10354.7 10371.2 10390.5 8079.7 8132.2 8165.0 8183.0 8206.8 6098.7 6160.1 6211.7 6229.3 6244.8 9663.9 9670.0 9723.6 9809.7 9824.6 9688.8 9946.7 9957.8 9965.6 10082.6 7465.7 7695.3 7772.5 7802.3 7809.2 8499.7 8505.6 8509.4 8553.1 8557.7 7544.1 7728.5 7761.5 7805.0 7847.6 7505.8 7510.1 7600.6 7643.4 7694.9 6660.4 6681.0 6707.8 6710.6 6724.8 5035.3 5055.9 5100.9 5173.4 5197.9 5107.3 5179.8 5240.6 5260.7 5278.6 18:37:25 INFO - PROCESS | 9251 | 18:37:25 INFO - PROCESS | 9251 | [#4] prototype.html Cycles:180 Average:5764.07 Median:6702.37 stddev:1792.01 (26.7%) stddev-sans-first:1750.08 18:37:25 INFO - PROCESS | 9251 | Values: 334.2 334.4 352.1 357.9 357.9 6511.2 6525.2 6529.3 6556.9 6605.0 6494.6 6498.6 6500.3 6503.8 6511.0 4737.9 4877.4 4911.6 4911.7 4914.5 4875.4 4890.4 4900.4 4904.4 4906.9 4835.7 4837.5 4840.7 4852.9 4853.3 4812.4 4836.6 4838.0 4844.0 4864.7 4220.2 4222.4 4222.6 4223.9 4224.3 6153.9 6235.8 6270.9 6290.3 6292.7 5613.7 5636.9 5648.1 5661.7 5662.5 6808.0 6810.6 6815.1 6817.5 6836.5 4652.5 4652.7 4659.3 4666.1 4667.0 7106.8 7115.1 7184.8 7188.0 7194.1 4575.5 4661.6 4668.7 4682.1 4692.6 4694.3 4726.2 4728.9 4733.0 4744.9 5558.0 5558.9 5564.6 5565.4 5569.3 6861.5 6885.9 6893.5 6900.1 6933.9 1678.3 1698.7 1699.8 1701.1 1702.8 1057.5 1096.2 1098.9 1100.6 1103.1 6766.6 6778.2 6790.0 6795.9 6797.0 7125.9 7134.6 7149.7 7160.6 7172.7 6638.1 6833.1 6833.3 6853.6 6858.6 3864.4 3892.9 3896.9 3900.3 3903.5 7520.2 7535.8 7544.5 7550.2 7554.7 7174.1 7314.6 7317.9 7328.5 7348.4 7537.8 7549.9 7550.9 7556.1 7557.2 7168.9 7183.1 7201.9 7210.5 7222.1 7049.6 7062.4 7101.9 7115.0 7125.6 7363.9 7454.2 7510.4 7525.5 7532.8 4805.4 4831.3 4847.8 4859.9 4863.4 7144.9 7163.8 7169.7 7178.0 7185.0 6854.3 6957.6 6964.1 6969.0 6977.8 6794.2 6923.6 6934.9 6947.3 6949.3 6797.2 6826.3 6832.7 6846.5 6850.1 6973.9 6975.7 6979.1 6981.5 6990.6 6809.6 6826.2 6830.1 6832.6 6836.3 18:37:25 INFO - PROCESS | 9251 | 18:37:25 INFO - PROCESS | 9251 | [#5] yui.html Cycles:180 Average:15273.39 Median:4487.66 stddev:17443.34 (388.7%) stddev-sans-first:17458.60 18:37:25 INFO - PROCESS | 9251 | Values: 841.0 857.1 868.0 870.8 937.8 14856.3 15199.8 15282.2 15285.3 15321.6 13829.7 13849.2 13851.4 13858.9 13888.4 872.6 899.2 902.8 906.7 913.0 917.4 917.6 923.0 925.4 927.0 951.5 951.6 954.0 954.5 954.6 946.2 949.2 952.9 953.5 954.6 698.2 699.9 700.7 700.9 703.7 14558.5 14701.4 14707.1 14707.4 14801.9 14627.1 14719.4 14753.4 14764.6 14808.2 11781.5 11839.0 11907.9 12032.3 12123.4 3796.0 3813.8 3838.2 3945.6 3961.5 58827.1 59437.7 59773.4 60402.7 60495.1 10033.3 10230.1 10244.4 10445.3 11625.1 19672.2 19710.2 19722.2 19792.4 19944.0 8392.4 8527.8 8544.3 8632.7 8767.6 4795.3 4853.2 4885.0 4910.7 4967.1 3614.8 3657.0 3661.5 3667.0 3679.0 623.2 696.5 702.9 703.8 704.3 772.5 808.8 809.6 818.6 828.0 8949.8 9024.0 9028.8 9067.9 9090.7 683.0 724.4 754.1 779.9 809.5 4276.6 4287.6 4396.9 4446.8 4528.5 60927.0 61096.7 61601.0 61652.7 61985.4 53962.0 55241.7 55779.4 55971.7 55976.7 49145.3 50227.0 50380.0 50461.7 50797.5 8489.4 8587.6 8626.6 8635.2 8690.1 36049.6 36078.8 37129.3 37181.3 37210.6 42831.8 44338.3 45449.5 45850.5 46493.3 11546.3 13501.7 13571.8 13674.5 13758.3 14013.0 14252.8 14253.8 14488.0 15005.8 12657.4 13290.9 13320.9 13398.6 13525.9 12923.2 13091.2 13155.0 13183.5 13237.8 13343.8 13348.7 13829.4 13856.1 13862.0 10895.9 11099.4 11144.8 11197.4 11736.4 10732.2 11146.7 11202.3 11452.4 11521.3 18:37:25 INFO - PROCESS | 9251 | -------- Summary: end -------- 18:37:25 INFO - PROCESS | 9251 | 18:37:26 INFO - TEST-INFO | 9251: exit 0 18:37:26 INFO - TEST-OK | dromaeo_css | took 1108477ms 18:37:26 INFO - TEST-START | kraken 18:37:26 INFO - operating with platform_type : linux_ 18:37:26 INFO - Initialising browser for kraken test... 18:37:26 INFO - TEST-INFO | started process 9493 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBxAuh7/profile http://localhost:48463/getInfo.html) 18:37:29 INFO - PROCESS | 9493 | 18:37:29 INFO - PROCESS | 9493 | (process:9573): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:37:30 INFO - PROCESS | 9493 | __metrics Screen width/height:1600/1200 18:37:30 INFO - PROCESS | 9493 | colorDepth:24 18:37:30 INFO - PROCESS | 9493 | Browser inner width/height: 1024/697 18:37:30 INFO - PROCESS | 9493 | __metrics 18:37:31 ERROR - PROCESS | 9493 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 18:37:32 INFO - TEST-INFO | 9493: exit 0 18:37:32 INFO - Browser initialized. 18:37:32 INFO - Running cycle 1/1 for kraken test... 18:37:32 INFO - TEST-INFO | started process 9623 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBxAuh7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:37:33 INFO - PROCESS | 9623 | 18:37:33 INFO - PROCESS | 9623 | (process:9693): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:37:43 INFO - PROCESS | 9623 | RSS: Main: 151072768 18:37:43 INFO - PROCESS | 9623 | 18:37:46 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48463/tests/kraken/driver.html?testName=audio-beat-detection) 18:37:47 INFO - PROCESS | 9623 | RSS: Main: 156463104 18:37:47 INFO - PROCESS | 9623 | 18:37:49 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48463/tests/kraken/driver.html?testName=audio-dft) 18:37:50 INFO - PROCESS | 9623 | RSS: Main: 156311552 18:37:50 INFO - PROCESS | 9623 | 18:37:53 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48463/tests/kraken/driver.html?testName=audio-fft) 18:37:53 INFO - PROCESS | 9623 | RSS: Main: 156672000 18:37:53 INFO - PROCESS | 9623 | 18:37:55 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48463/tests/kraken/driver.html?testName=audio-oscillator) 18:37:56 INFO - PROCESS | 9623 | RSS: Main: 156098560 18:37:56 INFO - PROCESS | 9623 | 18:37:58 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48463/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:37:59 INFO - PROCESS | 9623 | RSS: Main: 156340224 18:37:59 INFO - PROCESS | 9623 | 18:38:02 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48463/tests/kraken/driver.html?testName=imaging-darkroom) 18:38:02 INFO - PROCESS | 9623 | RSS: Main: 156839936 18:38:02 INFO - PROCESS | 9623 | 18:38:06 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48463/tests/kraken/driver.html?testName=imaging-desaturate) 18:38:06 INFO - PROCESS | 9623 | RSS: Main: 156729344 18:38:06 INFO - PROCESS | 9623 | 18:38:09 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48463/tests/kraken/driver.html?testName=json-parse-financial) 18:38:10 INFO - PROCESS | 9623 | RSS: Main: 156889088 18:38:10 INFO - PROCESS | 9623 | 18:38:11 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48463/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:38:12 INFO - PROCESS | 9623 | RSS: Main: 156647424 18:38:12 INFO - PROCESS | 9623 | 18:38:14 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48463/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:38:14 INFO - PROCESS | 9623 | RSS: Main: 156872704 18:38:14 INFO - PROCESS | 9623 | 18:38:16 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48463/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:38:17 INFO - PROCESS | 9623 | RSS: Main: 156823552 18:38:17 INFO - PROCESS | 9623 | 18:38:19 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48463/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:38:20 INFO - PROCESS | 9623 | RSS: Main: 157401088 18:38:20 INFO - PROCESS | 9623 | 18:38:23 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48463/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:38:23 INFO - PROCESS | 9623 | RSS: Main: 157351936 18:38:23 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | Cycle 1(1): loaded http://localhost:48463/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48463/tests/kraken/driver.html?testName=ai-astar) 18:38:25 INFO - PROCESS | 9623 | RSS: Main: 158957568 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | __start_tp_report 18:38:25 INFO - PROCESS | 9623 | _x_x_mozilla_page_load 18:38:25 INFO - PROCESS | 9623 | _x_x_mozilla_page_load_details 18:38:25 INFO - PROCESS | 9623 | |i|pagename|runs| 18:38:25 INFO - PROCESS | 9623 | |0;ai-astar;107;111;104;109;110;112;103;109;111;110 18:38:25 INFO - PROCESS | 9623 | |1;audio-beat-detection;157;128;160;178;127;159;146;129;177;127 18:38:25 INFO - PROCESS | 9623 | |2;audio-dft;164;162;161;161;162;163;161;165;163;163 18:38:25 INFO - PROCESS | 9623 | |3;audio-fft;73;76;76;175;73;77;77;75;172;73 18:38:25 INFO - PROCESS | 9623 | |4;audio-oscillator;91;92;155;92;92;92;151;92;91;153 18:38:25 INFO - PROCESS | 9623 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;118;117;117 18:38:25 INFO - PROCESS | 9623 | |6;imaging-darkroom;147;146;147;146;145;146;146;146;147;147 18:38:25 INFO - PROCESS | 9623 | |7;imaging-desaturate;95;96;95;98;95;95;95;95;95;96 18:38:25 INFO - PROCESS | 9623 | |8;json-parse-financial;54;55;60;55;55;59;59;74;56;53 18:38:25 INFO - PROCESS | 9623 | |9;json-stringify-tinderbox;59;58;58;60;59;58;58;58;60;59 18:38:25 INFO - PROCESS | 9623 | |10;stanford-crypto-aes;73;75;73;82;73;74;75;74;74;74 18:38:25 INFO - PROCESS | 9623 | |11;stanford-crypto-ccm;114;114;114;115;116;127;113;116;126;129 18:38:25 INFO - PROCESS | 9623 | |12;stanford-crypto-pbkdf2;167;165;160;160;162;166;161;160;161;157 18:38:25 INFO - PROCESS | 9623 | |13;stanford-crypto-sha256-iterative;68;67;66;69;65;66;71;66;67;73 18:38:25 INFO - PROCESS | 9623 | __end_tp_report 18:38:25 INFO - PROCESS | 9623 | __start_cc_report 18:38:25 INFO - PROCESS | 9623 | _x_x_mozilla_cycle_collect,835 18:38:25 INFO - PROCESS | 9623 | __end_cc_report 18:38:25 INFO - PROCESS | 9623 | __startTimestamp1468460305733__endTimestamp 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | ------- Summary: start ------- 18:38:25 INFO - PROCESS | 9623 | Number of tests: 14 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#0] ai-astar Cycles:10 Average:108.60 Median:110.00 stddev:3.03 (2.8%) stddev-sans-first:3.15 18:38:25 INFO - PROCESS | 9623 | Values: 107.0 111.0 104.0 109.0 110.0 112.0 103.0 109.0 111.0 110.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#1] audio-beat-detection Cycles:10 Average:148.80 Median:158.00 stddev:20.35 (12.9%) stddev-sans-first:21.37 18:38:25 INFO - PROCESS | 9623 | Values: 157.0 128.0 160.0 178.0 127.0 159.0 146.0 129.0 177.0 127.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.35 (0.8%) stddev-sans-first:1.32 18:38:25 INFO - PROCESS | 9623 | Values: 164.0 162.0 161.0 161.0 162.0 163.0 161.0 165.0 163.0 163.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#3] audio-fft Cycles:10 Average:94.70 Median:75.50 stddev:41.57 (55.1%) stddev-sans-first:43.34 18:38:25 INFO - PROCESS | 9623 | Values: 73.0 76.0 76.0 175.0 73.0 77.0 77.0 75.0 172.0 73.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#4] audio-oscillator Cycles:10 Average:110.10 Median:92.00 stddev:29.62 (32.2%) stddev-sans-first:30.60 18:38:25 INFO - PROCESS | 9623 | Values: 91.0 92.0 155.0 92.0 92.0 92.0 151.0 92.0 91.0 153.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:38:25 INFO - PROCESS | 9623 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 18:38:25 INFO - PROCESS | 9623 | Values: 147.0 146.0 147.0 146.0 145.0 146.0 146.0 146.0 147.0 147.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 18:38:25 INFO - PROCESS | 9623 | Values: 95.0 96.0 95.0 98.0 95.0 95.0 95.0 95.0 95.0 96.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:57.50 stddev:6.09 (10.6%) stddev-sans-first:6.29 18:38:25 INFO - PROCESS | 9623 | Values: 54.0 55.0 60.0 55.0 55.0 59.0 59.0 74.0 56.0 53.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:0.82 (1.4%) stddev-sans-first:0.87 18:38:25 INFO - PROCESS | 9623 | Values: 59.0 58.0 58.0 60.0 59.0 58.0 58.0 58.0 60.0 59.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:74.00 stddev:2.67 (3.6%) stddev-sans-first:2.76 18:38:25 INFO - PROCESS | 9623 | Values: 73.0 75.0 73.0 82.0 73.0 74.0 75.0 74.0 74.0 74.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#11] stanford-crypto-ccm Cycles:10 Average:118.40 Median:116.00 stddev:6.28 (5.4%) stddev-sans-first:6.45 18:38:25 INFO - PROCESS | 9623 | Values: 114.0 114.0 114.0 115.0 116.0 127.0 113.0 116.0 126.0 129.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.90 Median:161.50 stddev:3.14 (1.9%) stddev-sans-first:2.74 18:38:25 INFO - PROCESS | 9623 | Values: 167.0 165.0 160.0 160.0 162.0 166.0 161.0 160.0 161.0 157.0 18:38:25 INFO - PROCESS | 9623 | 18:38:25 INFO - PROCESS | 9623 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:2.53 (3.7%) stddev-sans-first:2.68 18:38:25 INFO - PROCESS | 9623 | Values: 68.0 67.0 66.0 69.0 65.0 66.0 71.0 66.0 67.0 73.0 18:38:25 INFO - PROCESS | 9623 | -------- Summary: end -------- 18:38:25 INFO - PROCESS | 9623 | 18:38:26 INFO - TEST-INFO | 9623: exit 0 18:38:26 INFO - TEST-OK | kraken | took 60403ms 18:38:26 INFO - SUITE-END | took 1168s 18:38:26 INFO - Completed test suite (00:19:29) 18:38:26 INFO - javascript benchmark 18:38:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3353.3125709229603, 3398.2353970356667, 3404.9489257661153, 3422.965770342296, 3467.687908088273, 5593.692346920918, 5615.719214039302, 5620.859478513039, 5642.661440313584, 5700.800471983482, 5628.183913332565, 5645.294338207724, 5678.205051292812, 5678.48893599577, 5679.261695979517, 5097.184450487923, 5146.459621739719, 5180.04169228692, 5201.765920533566, 5231.869203269911, 5088.363954505687, 5136.101182293084, 5136.28092067109, 5298.761555730001, 5299.046171689094, 5143.588512918957, 5175.24958880962, 5181.559567436772, 5183.3002544656365, 5185.611079169059, 5145.588352931775, 5155.484451554833, 5160.122779127557, 5170.5346518813485, 5191.922121168185, 4978.77595508203, 4980.153373926441, 5032.194848824171, 5032.924506132411, 5059.392872855281, 5452.672839629635, 5454.209139674765, 5459.645123067027, 5481.808136715196, 5496.862578435531, 5937.109433584997, 5946.0, 5949.940500594982, 5951.107333899907, 5952.9107063394085, 5979.133025711248, 5992.79025234119, 5994.8501287467725, 6118.436443820604, 6123.724432400552, 5571.693556854331, 5573.498385145316, 5578.30271216098, 5579.581531385163, 5586.13414920688, 6021.036634138517, 6050.3949605040025, 6050.57645964787, 6061.333253342129, 6072.240969878765, 5754.367019627837, 5885.676287804212, 5942.1978032965, 5967.46292833647, 5981.431763982415, 6033.125196846433, 6055.1220073090035, 6059.454649081604, 6061.605995610271, 6067.848303792352, 5782.219400380979, 6004.909926351108, 6014.849628759316, 6016.42843929826, 6024.066269728111, 5914.704264786744, 5947.167396564484, 5949.345571987078, 5955.493783028491, 5981.102834574848, 4509.323601459741, 4569.611583015414, 4575.359449677047, 4580.610648094883, 4603.746793926366, 4055.41196526502, 4090.8977275567754, 4118.423420721101, 4124.463819703419, 4144.150449157915, 5612.35457922336, 5762.971185144047, 5810.564207684441, 5891.882162356729, 5932.495737862244, 5956.136360227742, 6018.368071352533, 6019.879602407927, 6023.879522409527, 6029.517638588903, 5585.609007369445, 5795.420457954255, 5816.302043754777, 5828.125781132864, 5836.358000619928, 5098.4646612105935, 5133.358330208724, 5133.614978876524, 5157.381114266312, 5160.200168973815, 6309.400606942333, 6313.463355614732, 6314.0, 6330.177076979963, 6333.936660633427, 5839.445252700986, 5919.733611987557, 5944.108383742473, 6045.032794752907, 6066.787662431793, 6031.5476339273855, 6145.371476558805, 6254.405831446005, 6272.937270627235, 6322.241331040304, 5909.881802364014, 5961.970190149022, 5964.5526585505395, 5968.16445697594, 5996.88006239864, 5846.0, 5991.161237426851, 6053.515718742402, 6085.087236914498, 6091.238595175603, 5812.593118481665, 5868.471837534642, 5879.176915231786, 5882.352941176381, 5904.232449781501, 5784.248047753765, 5850.063989761617, 5852.619579727304, 5853.268341457318, 5857.209276747584, 5605.4114317997655, 5678.77284908599, 5770.538356931352, 5772.422757724194, 5782.335031471266, 5866.6186697864505, 5902.7343769531335, 5908.970455147697, 5965.0, 5994.490468310242, 5805.245318108618, 5819.388964158872, 5822.650640961556, 5827.650340979555, 5827.79602713903], "value": 5549.19564557331, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.0453687710294, 658.9703463344149, 668.775960053382, 672.7275453441358, 678.7352932356379, 39862.80068599657, 40578.18843623129, 40749.185016299656, 41108.79445602772, 41212.38181427281, 43403.0, 44203.33694994578, 44205.11589768203, 44227.3365899511, 44543.77728111363, 8745.34409919257, 9000.189982901538, 9010.18908298253, 9012.504312262823, 9027.14242146995, 8791.164839340252, 8909.95545022274, 8937.150970657809, 8958.37291389603, 8964.8655270171, 9124.31567632427, 9167.991520932725, 9181.081891810833, 9197.770055748626, 9232.907670923305, 9130.634774609007, 9195.540222988824, 9197.448153110801, 9198.95400522993, 9223.999999999967, 9109.726708198765, 9201.217896478807, 9203.171714545657, 9217.631294748202, 9223.769405764842, 43723.0, 44147.337789933175, 44203.0, 44242.557574424485, 44440.0, 3543.273628906068, 3856.7878766667823, 3904.063024874038, 3973.284808734427, 4571.497135315112, 29748.0, 29855.701442985508, 30059.549106763418, 30066.0, 30246.24384390386, 4358.455193100863, 4443.178012067778, 4527.253003254459, 4531.0, 4544.5228251033495, 289969.0, 292772.0, 293134.0, 294223.52888235636, 295766.0, 39365.212695745926, 39786.2042759149, 39876.80061599673, 39896.40155397671, 61108.38891611116, 38241.2351752969, 38357.232855342736, 38364.8081759595, 38440.0, 38458.42312365317, 17020.829791701923, 17104.57238569046, 17150.31398744061, 17220.741688874677, 17254.568635784206, 14447.133172009713, 14451.0, 14498.0, 14547.127172369692, 14621.78067328991, 9354.391964522285, 9374.67188648394, 9383.718488445358, 9386.85919711205, 9444.244460443286, 3548.254866478017, 3588.515550400715, 3663.7618554793853, 3688.243909998444, 3704.1294146456407, 1078.1698092468912, 1104.8342748587777, 1107.6566264457883, 1169.818678104895, 1196.7307355844866, 31838.0, 31932.521012184836, 32293.677063229068, 33042.17394565107, 33368.66631333704, 1132.018966808086, 1148.787474317254, 1194.7192409783695, 1209.7580483903255, 1347.9595612131652, 15353.923230384, 16120.27458764335, 16240.67518649644, 16341.91829040871, 16582.585435364213, 513761.0, 603249.0, 614002.0, 615411.0, 618971.0, 58534.414655852896, 59148.11277830836, 59166.112508312406, 59375.0, 59570.0, 840.0255703384045, 1455.294182321573, 1590.6421055262704, 1594.2507021699782, 1605.7511085781957, 32463.0, 33525.0, 34378.0, 34408.0, 34541.82729086339, 55496.72251638716, 55706.16440753473, 56214.718926405105, 56241.71879140742, 56254.71872640611, 452175.0, 452276.0, 456049.0, 457343.0, 457652.0, 47139.05721885612, 47623.52376476191, 48302.75848620734, 48813.0, 49270.26094608656, 45501.54498454973, 46039.539604603524, 46562.0, 46773.2984005247, 47710.284345734144, 38839.80580097081, 39152.21695566129, 39786.403203951384, 39843.402348964206, 40051.198976020896, 41930.55055775449, 43055.569444306406, 43074.569254307055, 43466.78266608647, 43657.56342436662, 42663.0, 43210.78394608007, 44593.0, 45551.77224113991, 46310.073798524514, 37594.06014849651, 37646.24707505889, 37667.2466550662, 38300.233995320494, 38872.6112738869, 27768.583471248352, 27927.581086283317, 27953.161405157876, 28356.149315520568, 28432.289192770346], "value": 19708.24417494955, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.6428667775729, 427.82459191731397, 432.70792215254716, 437.7614200260858, 441.2454702458795, 18732.62534749306, 19231.423057308268, 19374.70937935932, 19402.0, 19914.203431862745, 18439.631207375845, 18774.06129693513, 19301.420957371265, 19317.71023434646, 19354.90322548389, 5525.309336332958, 5782.971085144568, 5785.537157027448, 5790.884182316351, 5934.228550288456, 4043.4743483347124, 5586.972065139689, 5615.719214039302, 5679.488846003859, 5940.227770389865, 5911.113333000063, 5922.674252916088, 5934.792282270121, 5946.821595352147, 5953.613015154001, 5866.384029676887, 5874.1776151338845, 6482.546221764478, 6488.318726533693, 6493.6103833769885, 5707.971460142714, 5726.112452569859, 5729.140628905698, 5736.74184661687, 5768.596198266123, 22401.77598224013, 23013.88493057541, 23741.406464838343, 23932.760672393226, 24111.397215069588, 2401.543706695722, 2667.6398686177313, 2896.8986085487118, 2920.678725340211, 3031.166429231957, 10555.68332950005, 10571.947140264327, 10723.62467313648, 10854.24020318578, 11008.559657613685, 1937.4187743676982, 1955.139738515063, 2082.79172082792, 2097.5804839032253, 2104.1793700456833, 80226.59886700587, 80652.59673701593, 80844.59577702133, 81769.59115204385, 82202.17797822064, 10518.158547316196, 11148.888511114947, 11392.145589080686, 11658.6502404928, 11721.706957326134, 15723.921380393025, 16481.25834337458, 16639.5856352209, 16657.583560411087, 16709.91645041767, 8415.45299555527, 8444.915550844536, 8450.830983380298, 8461.746147615582, 8570.4000719949, 8409.579521023925, 8429.157084291523, 8452.57737113142, 8503.277221436247, 8611.6985905493, 4925.605951523869, 5001.774920128603, 5018.774155163026, 5050.545450909436, 5062.670926389773, 1871.3263225238902, 1926.9132889020027, 1932.8357089647538, 1963.9803601964084, 1994.8304394126371, 1006.7835415385728, 1037.818381783185, 1044.8589440425446, 1070.4808168038496, 1156.19066653343, 17808.554786130193, 18110.81889181091, 18591.34930277434, 18799.624007519775, 18944.431667050012, 1086.163653986426, 1095.5124969388546, 1114.0047728915654, 1130.3444002478543, 1140.8802075781923, 10500.054995050483, 10691.786164276671, 10701.89298107009, 10743.51654175564, 10786.35281883074, 523795.0, 533885.0, 537949.0, 538385.0, 548531.0, 86646.56676716576, 97192.51403742936, 99010.0, 99827.50086249522, 103519.48240258901, 1083.869935607732, 1104.094642393246, 1124.9325040497597, 1125.1448898836943, 1126.3129491010477, 17402.912985435498, 17444.563885902953, 17646.20592073334, 17779.644407112042, 17787.288508460035, 40148.0, 40707.796461017504, 40947.590524094376, 40948.0, 41709.58290417057, 57957.71021144867, 62080.0, 62086.0, 62241.377586223556, 62390.0, 8642.43824151428, 8976.730698079067, 9102.36283460178, 9244.306676999118, 9318.347715659838, 12536.623901282612, 12651.114421990373, 12949.870501295243, 13150.802737958747, 13563.59309220725, 11857.940710296394, 12054.939725301318, 12300.753984920431, 12427.378631068592, 12427.813582796085, 12092.818607721067, 12239.632811015685, 12304.084229435464, 12359.938200308941, 12488.625341239778, 12283.877161228631, 12474.313912734886, 12661.556845510362, 12685.175463594836, 12799.872001279868, 9080.091990801131, 9192.770180745534, 9240.075992400707, 9278.211352035152, 9354.625814967598, 12308.692282693004, 12534.811977820522, 12634.178778379375, 12635.557755478514, 12639.494420223456], "value": 9614.343989893208, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1573.6301969037277, 1594.0515393340959, 1779.4928445393066, 1888.4523488188418, 1938.8739731917415, 7481.850362992737, 8243.67025318988, 8548.871766923494, 8763.07987661296, 8773.956130219349, 8325.084240733515, 8426.073131955496, 8517.744467665965, 8578.099299573549, 8611.61247743853, 2471.9752802472017, 2484.416162201881, 2502.36189771608, 2511.698596168453, 2519.143491212987, 2499.462615537668, 2552.1450314144786, 2552.2343297010916, 2558.3476213565514, 2558.667373241476, 2402.171250918439, 2406.0496104038893, 2439.524292762912, 2456.152627343564, 2458.2256589174444, 2476.529459402726, 2498.113169824704, 2500.9749902500926, 2509.648649189115, 2516.069054449847, 2409.361519197414, 2534.6958364996135, 2538.2004668529353, 2559.9360015999564, 2571.1772232885482, 8709.0, 8712.259457946082, 8740.3444741644, 8756.912430875736, 8771.298296136358, 5283.894322113575, 5454.127339625641, 5462.153366228241, 5469.644473109275, 5475.123980163195, 3629.6188900165366, 3649.6532829381167, 3657.9451308230396, 3710.4805327254207, 3747.868824591126, 1264.8988080953502, 1281.6539534325773, 1298.9090763646548, 1307.3397934043248, 1316.3549860568348, 12235.510579576807, 12406.255624662459, 12422.254664720145, 12432.564860229848, 12436.316002619853, 4689.742064186503, 4715.646326525524, 4747.7626118693925, 4764.595009424238, 4872.585830204402, 10683.03852653265, 10689.518971646294, 10702.73243168927, 10707.196960228013, 10707.892921070845, 5485.725713714378, 5525.226468294361, 5534.695591742412, 5546.77812887488, 5562.360328562187, 4434.512203657595, 4876.146674331978, 4977.552020318117, 5000.099982003201, 5056.747162641853, 4221.240176768211, 4230.0, 4245.299525578245, 4248.4477017987465, 4342.0, 1368.6441525203518, 1410.2807568140322, 1412.7386433509835, 1414.5402744108, 1416.6954104867298, 917.3945196170496, 1077.3697387028676, 1078.4661592511757, 1085.180688580117, 1105.2705214558353, 6002.69986500682, 6077.908831367533, 6205.410486003822, 6209.192804935329, 6287.622742635456, 997.1125698128672, 997.6607953295915, 1037.9429131397847, 1064.1061508333037, 1102.3165637304921, 4306.763128027926, 4373.387725718402, 4377.956220437819, 4405.185040767404, 4412.42638457005, 10637.095846853104, 10914.345139291509, 10964.122870170368, 11001.22991390611, 11054.668359949215, 10100.393976361294, 10306.0, 10354.741131471774, 10371.222158338003, 10390.48047597617, 8079.717209897625, 8132.186781321939, 8165.0, 8182.959085204655, 8206.8358632827, 6098.664573448503, 6160.075988601746, 6211.720472578654, 6229.345918678655, 6244.845642518468, 9663.855042174233, 9669.951650241705, 9723.562439690373, 9809.656662016796, 9824.557894894604, 9688.75778105553, 9946.651867184613, 9957.800843982935, 9965.601375944882, 10082.647107351208, 7465.664045117874, 7695.268949449794, 7772.45592808498, 7802.336801371947, 7809.219078092373, 8499.660013599387, 8505.617247223767, 8509.361797865062, 8553.144685531397, 8557.700480483154, 7544.09470863478, 7728.536287822749, 7761.534307941542, 7805.0, 7847.5683837387205, 7505.774826755206, 7510.136334321623, 7600.619969001417, 7643.426742994409, 7694.884576731241, 6660.400563949268, 6681.0, 6707.765228216989, 6710.563813352116, 6724.831879203059, 5035.295058691713, 5055.924161137659, 5100.923486147636, 5173.430922598436, 5197.948020519746, 5107.310513080835, 5179.801755763812, 5240.606954478506, 5260.73696315175, 5278.551323137576], "value": 4790.33299132518, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.15625545497613, 334.40475952804, 352.0670223906647, 357.85685725709715, 357.9194681196733, 6511.186101737283, 6525.249596296428, 6529.347065293468, 6556.901646475307, 6605.0, 6494.577852439588, 6498.577592456487, 6500.349965003497, 6503.8374040649005, 6511.0, 4737.928931066028, 4877.3903262092235, 4911.631627627924, 4911.705297682151, 4914.459409464956, 4875.414950205963, 4890.437599676029, 4900.362952816146, 4904.4114706235305, 4906.877328066791, 4835.734034628094, 4837.516248375169, 4840.733759643218, 4852.902941941159, 4853.320535125085, 4812.446568644616, 4836.588889944342, 4837.975810120962, 4844.0, 4864.708117512961, 4220.198162349144, 4222.387753775685, 4222.5566315536735, 4223.873283801491, 4224.345226489868, 6153.876922461571, 6235.844103897393, 6270.9372906271265, 6290.276618188921, 6292.716827742717, 5613.66318020916, 5636.887262254773, 5648.068068768648, 5661.688607126606, 5662.49037586615, 6808.0, 6810.62541560214, 6815.148106486689, 6817.488688348344, 6836.4530837532875, 4652.5114862939245, 4652.74409907455, 4659.324397962277, 4666.090112428078, 4667.0, 7106.827373483369, 7115.1461824580865, 7184.784456466315, 7187.964060179718, 7194.064771579713, 4575.473820510684, 4661.603763680057, 4668.673192876534, 4682.086993036325, 4692.601128904013, 4694.272387779832, 4726.172919739032, 4728.929066064012, 4733.0, 4744.905101898012, 5558.027345214571, 5558.944410555923, 5564.610477266552, 5565.443455654402, 5569.27599412082, 6861.4853885957755, 6885.931140688629, 6893.517453778188, 6900.068490753784, 6933.93066069343, 1678.2867281405354, 1698.694235037705, 1699.7960244770459, 1701.0644145719805, 1702.8126906040325, 1057.5082586597268, 1096.2435919215875, 1098.8846171152074, 1100.5872797700863, 1103.0623969625753, 6766.6278354689985, 6778.152730908637, 6790.0493930849725, 6795.932040679629, 6796.966015169992, 7125.857482850418, 7134.643267836587, 7149.714011439484, 7160.57036577807, 7172.713091476387, 6638.103855979478, 6833.111695479556, 6833.3166683331265, 6853.554518956252, 6858.554193977376, 3864.381698928158, 3892.902677433087, 3896.922061558753, 3900.258950799339, 3903.512060992376, 7520.21037791035, 7535.849283014309, 7544.547327160388, 7550.244975502515, 7554.735584254525, 7174.1032370953635, 7314.561126332437, 7317.926820731725, 7328.450366222554, 7348.375388092072, 7537.8115547111765, 7549.886751698729, 7550.886736698954, 7556.055493063367, 7557.206493318233, 7168.89246661311, 7183.138023437221, 7201.891971620325, 7210.495265331377, 7222.09723784527, 7049.612271325126, 7062.39969602569, 7101.893471597827, 7114.964425177841, 7125.608091555015, 7363.926360736538, 7454.217307182885, 7510.361619262206, 7525.473216874766, 7532.811679708051, 4805.423349198119, 4831.251156070814, 4847.80608775645, 4859.8579333855805, 4863.392075990501, 7144.928550714426, 7163.785086447415, 7169.7132114716915, 7178.0, 7185.0, 6854.34883686049, 6957.582545047313, 6964.129483814523, 6969.0, 6977.79066628002, 6794.218664853605, 6923.584584924921, 6934.895976560457, 6947.340002699735, 6949.339812717783, 6797.1843378794865, 6826.317368263134, 6832.658367081526, 6846.486513511407, 6850.0752398425575, 6973.860522789414, 6975.651217439209, 6979.092717946634, 6981.511294209559, 6990.580565166107, 6809.591424514545, 6826.249112597511, 6830.112085428863, 6832.555883867532, 6836.28219036994], "value": 5169.660283294348, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [841.045413455728, 857.1043259793518, 867.9739607811764, 870.8345414371273, 937.8499440089579, 14856.25718714064, 15199.77200341993, 15282.235888205587, 15285.31216095277, 15321.616959576017, 13829.654258643539, 13849.169049857015, 13851.376688049037, 13858.861411385884, 13888.4444622215, 872.6378552900538, 899.1862364560051, 902.8149229407956, 906.6735975048977, 912.9726108216731, 917.3670167584379, 917.6283605139929, 922.9630814767402, 925.4401087342162, 926.9768255793591, 951.490952340498, 951.6241084771511, 953.9618415263382, 954.4654993203793, 954.5847556312991, 946.2335508238315, 949.1599934058339, 952.8665986761858, 953.4517652349857, 954.627695198873, 698.2005603583868, 699.8740226759181, 700.6741865032784, 700.8913618389158, 703.6516924122541, 14558.4904528341, 14701.411943522246, 14707.117572945657, 14707.411703531845, 14801.925990370088, 14627.122372657568, 14719.411223551153, 14753.409863605442, 14764.630884227872, 14808.185549794756, 11781.540663009782, 11839.0, 11907.912409340117, 12032.27806331623, 12123.393830308449, 3795.981020094882, 3813.809309534512, 3838.2323535292776, 3945.6448919597374, 3961.485006949078, 58827.11759323614, 59437.702811486095, 59773.402265977216, 60402.697986510226, 60495.09257361099, 10033.297669163088, 10230.079292863678, 10244.385336879812, 10445.268831181897, 11625.128115391379, 19672.213111475667, 19710.21159153618, 19722.211111555665, 19792.40622781319, 19944.0, 8392.370572207108, 8527.829443411098, 8544.273736732448, 8632.697855575025, 8767.605457754416, 4795.304680821261, 4853.19922212838, 4885.022995400863, 4910.729909854992, 4967.05625931072, 3614.7831130132276, 3657.030886814996, 3661.4690869823726, 3667.0465678923138, 3679.0, 623.2116372788427, 696.5020010692278, 702.8875379939185, 703.809971307744, 704.3308856586264, 772.4631381190019, 808.7775861638097, 809.6478032056075, 818.60706860706, 827.9503229806111, 8949.776255593531, 9024.0, 9028.774280642905, 9067.863982040275, 9090.727278181665, 683.0335075867691, 724.3517052238323, 754.1252147508864, 779.9142094369615, 809.514291425152, 4276.615104640598, 4287.6355509782015, 4396.912061758747, 4446.82212711488, 4528.50186479487, 60927.0, 61096.69451652713, 61601.0, 61652.69173654193, 61985.38014619796, 53962.0, 55241.723791380784, 55779.442205578234, 55971.72014139903, 55976.72011639997, 49145.26282105771, 50227.0, 50380.0, 50461.73845653888, 50797.49202507928, 8489.363297752816, 8587.570621469027, 8626.61180246903, 8635.222829945335, 8690.130986901258, 36049.63950360463, 36078.8196059018, 37129.25741485101, 37181.25637487181, 37210.6278937218, 42831.785841070596, 44338.3349249768, 45449.545504544534, 45850.54149458463, 46493.302600460345, 11546.307221566733, 13501.729965400833, 13571.796423053465, 13674.521391751241, 13758.31208439594, 14013.0, 14252.786208206679, 14253.786193207317, 14488.0, 15005.849941500883, 12657.367131643197, 13290.933545332211, 13320.93339533296, 13398.598042058753, 13525.93237033848, 12923.15999460032, 13091.214527128403, 13155.0, 13183.472661093449, 13237.801432978706, 13343.799843002169, 13348.733025339634, 13829.446822127002, 13856.099353541988, 13862.02965792385, 10895.91040895904, 11099.445027748741, 11144.777104458028, 11197.384143872165, 11736.413179340829, 10732.248742587944, 11146.665600032013, 11202.271852329572, 11452.427378630868, 11521.308721476738], "value": 7091.0184809428065, "unit": "score"}], "name": "dromaeo_css", "value": 7546.200444095192, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 111.0, 104.0, 109.0, 110.0, 112.0, 103.0, 109.0, 111.0, 110.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 128.0, 160.0, 178.0, 127.0, 159.0, 146.0, 129.0, 177.0, 127.0], "value": 148.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 161.0, 161.0, 162.0, 163.0, 161.0, 165.0, 163.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 76.0, 175.0, 73.0, 77.0, 77.0, 75.0, 172.0, 73.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 155.0, 92.0, 92.0, 92.0, 151.0, 92.0, 91.0, 153.0], "value": 110.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 145.0, 146.0, 146.0, 146.0, 147.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 98.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 60.0, 55.0, 55.0, 59.0, 59.0, 74.0, 56.0, 53.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 58.0, 60.0, 59.0, 58.0, 58.0, 58.0, 60.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 73.0, 82.0, 73.0, 74.0, 75.0, 74.0, 74.0, 74.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 115.0, 116.0, 127.0, 113.0, 116.0, 126.0, 129.0], "value": 118.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 160.0, 160.0, 162.0, 166.0, 161.0, 160.0, 161.0, 157.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 69.0, 65.0, 66.0, 71.0, 66.0, 67.0, 73.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1523.1000000000004, "alertThreshold": 2.0}]} 18:38:26 INFO - Return code: 0 18:38:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:38:27 ERROR - # TBPL SUCCESS # 18:38:27 INFO - Running post-action listener: _resource_record_post_action 18:38:27 INFO - Running post-run listener: _resource_record_post_run 18:38:28 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 3342336; Write bytes: 290869248; Read time: 320; Write time: 136952 18:38:28 INFO - install - Wall time: 13s; CPU: 13.0%; Read bytes: 0; Write bytes: 69341184; Read time: 0; Write time: 36748 18:38:28 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 3317760; Write bytes: 221528064; Read time: 304; Write time: 100204 18:38:28 INFO - Running post-run listener: _upload_blobber_files 18:38:28 INFO - Blob upload gear active. 18:38:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:38:28 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 18:38:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:38:28 INFO - (blobuploader) - INFO - Open directory for files ... 18:38:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:38:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:38:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:29 INFO - (blobuploader) - INFO - Done attempting. 18:38:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:38:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:38:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:30 INFO - (blobuploader) - INFO - Done attempting. 18:38:30 INFO - (blobuploader) - INFO - Iteration through files over. 18:38:30 INFO - Return code: 0 18:38:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:38:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:38:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78d7915625f1b22d364ca146317a2b8dcc9cdf7608e89e1193bbc4a017303a560ac359c73a03ef0383d99ad4e45f692b5f960fe9fe4d6116a08b26eeadcfc6ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/827903c36bd15711e7bde2efd8dacb94bbcd0d2f9093f147ecdf78a6508d532bd58fe7ecfdd72a9240845605484346c4e6ffc48e6e6a739a57724b30a077311f"} 18:38:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:38:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:38:30 INFO - Contents: 18:38:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78d7915625f1b22d364ca146317a2b8dcc9cdf7608e89e1193bbc4a017303a560ac359c73a03ef0383d99ad4e45f692b5f960fe9fe4d6116a08b26eeadcfc6ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/827903c36bd15711e7bde2efd8dacb94bbcd0d2f9093f147ecdf78a6508d532bd58fe7ecfdd72a9240845605484346c4e6ffc48e6e6a739a57724b30a077311f"} 18:38:30 INFO - Running post-run listener: copy_logs_to_upload_dir 18:38:30 INFO - Copying logs to upload dir... 18:38:30 INFO - mkdir: /builds/slave/test/build/upload/logs 18:38:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1227.724197 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2016-07-13 18:38:30.167671) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 18:38:30.170985) ========= 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=d2a8c569965ab8367867b893000001a1-1468458546.949499-965453286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78d7915625f1b22d364ca146317a2b8dcc9cdf7608e89e1193bbc4a017303a560ac359c73a03ef0383d99ad4e45f692b5f960fe9fe4d6116a08b26eeadcfc6ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/827903c36bd15711e7bde2efd8dacb94bbcd0d2f9093f147ecdf78a6508d532bd58fe7ecfdd72a9240845605484346c4e6ffc48e6e6a739a57724b30a077311f"} build_url:https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011239 build_url: 'https://queue.taskcluster.net/v1/task/HLwl6fOpS7ugiM7Q239UYQ/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78d7915625f1b22d364ca146317a2b8dcc9cdf7608e89e1193bbc4a017303a560ac359c73a03ef0383d99ad4e45f692b5f960fe9fe4d6116a08b26eeadcfc6ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/827903c36bd15711e7bde2efd8dacb94bbcd0d2f9093f147ecdf78a6508d532bd58fe7ecfdd72a9240845605484346c4e6ffc48e6e6a739a57724b30a077311f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 18:38:30.197997) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:38:30.198347) ========= 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=d2a8c569965ab8367867b893000001a1-1468458546.949499-965453286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004724 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 18:38:30.284694) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 18:38:30.285355) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 18:38:30.286039) ========= ========= Total master_lag: 0.19 =========