builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-067 starttime: 1461477102.68 results: success (0) buildid: 20160423200744 builduid: fb54db8278d74430a046e3cc41a1c1c2 revision: 2b7291cc565421eebd5328f09dede6ff40ce0b42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:42.676379) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:42.676877) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:42.677175) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:42.693736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:42.694092) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-23 22:51:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.3M=0s 2016-04-23 22:51:45 (50.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119155 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:42.824453) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:42.824770) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019420 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:42.874884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:42.875266) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2b7291cc565421eebd5328f09dede6ff40ce0b42 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2b7291cc565421eebd5328f09dede6ff40ce0b42 --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=e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-23 22:51:45,774 truncating revision to first 12 chars 2016-04-23 22:51:45,774 Setting DEBUG logging. 2016-04-23 22:51:45,774 attempt 1/10 2016-04-23 22:51:45,774 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2b7291cc5654?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-23 22:51:46,022 unpacking tar archive at: mozilla-aurora-2b7291cc5654/testing/mozharness/ program finished with exit code 0 elapsedTime=0.528931 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:43.417405) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:43.417711) ========= script_repo_revision: 2b7291cc565421eebd5328f09dede6ff40ce0b42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:43.418121) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:43.418391) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-23 22:51:43.428800) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-04-23 22:51:43.429084) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:51:46 INFO - MultiFileLogger online at 20160423 22:51:46 in /builds/slave/test 22:51:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:51:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:51:46 INFO - {'append_to_log': False, 22:51:46 INFO - 'base_work_dir': '/builds/slave/test', 22:51:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:51:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:51:46 INFO - 'branch': 'Mozilla-Aurora', 22:51:46 INFO - 'buildbot_json_path': 'buildprops.json', 22:51:46 INFO - 'config_files': ('talos/linux_config.py',), 22:51:46 INFO - 'default_actions': ('clobber', 22:51:46 INFO - 'read-buildbot-config', 22:51:46 INFO - 'download-and-extract', 22:51:46 INFO - 'populate-webroot', 22:51:46 INFO - 'create-virtualenv', 22:51:46 INFO - 'install', 22:51:46 INFO - 'run-tests'), 22:51:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:51:46 INFO - 'download_minidump_stackwalk': True, 22:51:46 INFO - 'download_symbols': 'ondemand', 22:51:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:51:46 INFO - 'tooltool.py': '/tools/tooltool.py', 22:51:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:51:46 INFO - '/tools/misc-python/virtualenv.py')}, 22:51:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:51:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:51:46 INFO - 'installer_path': 'installer.exe', 22:51:46 INFO - 'log_level': 'info', 22:51:46 INFO - 'log_name': 'talos', 22:51:46 INFO - 'log_to_console': True, 22:51:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:51:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:51:46 INFO - 'opt_config_files': (), 22:51:46 INFO - 'pip_index': False, 22:51:46 INFO - 'sps_profile': False, 22:51:46 INFO - 'sps_profile_interval': 0, 22:51:46 INFO - 'suite': 'dromaeojs-e10s', 22:51:46 INFO - 'system_bits': '32', 22:51:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:51:46 INFO - 'title': 'talos-linux64-ix-067', 22:51:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:51:46 INFO - 'use_talos_json': True, 22:51:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:51:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:51:46 INFO - 'work_dir': 'build'} 22:51:46 INFO - ##### 22:51:46 INFO - ##### Running clobber step. 22:51:46 INFO - ##### 22:51:46 INFO - Running pre-action listener: _resource_record_pre_action 22:51:46 INFO - Running main action method: clobber 22:51:46 INFO - rmtree: /builds/slave/test/build 22:51:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:51:47 INFO - Running post-action listener: _resource_record_post_action 22:51:47 INFO - ##### 22:51:47 INFO - ##### Running read-buildbot-config step. 22:51:47 INFO - ##### 22:51:47 INFO - Running pre-action listener: _resource_record_pre_action 22:51:47 INFO - Running main action method: read_buildbot_config 22:51:47 INFO - Using buildbot properties: 22:51:47 INFO - { 22:51:47 INFO - "project": "", 22:51:47 INFO - "product": "firefox", 22:51:47 INFO - "script_repo_revision": "production", 22:51:47 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 22:51:47 INFO - "repository": "", 22:51:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 22:51:47 INFO - "buildid": "20160423200744", 22:51:47 INFO - "pgo_build": "True", 22:51:47 INFO - "basedir": "/builds/slave/test", 22:51:47 INFO - "buildnumber": 248, 22:51:47 INFO - "slavename": "talos-linux64-ix-067", 22:51:47 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:51:47 INFO - "platform": "ubuntu64_hw", 22:51:47 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:51:47 INFO - "branch": "mozilla-aurora", 22:51:47 INFO - "repo_path": "releases/mozilla-aurora", 22:51:47 INFO - "revision": "2b7291cc565421eebd5328f09dede6ff40ce0b42", 22:51:47 INFO - "stage_platform": "linux64-pgo", 22:51:47 INFO - "builduid": "fb54db8278d74430a046e3cc41a1c1c2", 22:51:47 INFO - "slavebuilddir": "test" 22:51:47 INFO - } 22:51:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2. 22:51:47 INFO - Running post-action listener: _resource_record_post_action 22:51:47 INFO - ##### 22:51:47 INFO - ##### Running download-and-extract step. 22:51:47 INFO - ##### 22:51:47 INFO - Running pre-action listener: _resource_record_pre_action 22:51:47 INFO - Running main action method: download_and_extract 22:51:47 INFO - mkdir: /builds/slave/test/build/tests 22:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:47 INFO - https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:51:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 22:51:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 22:51:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 22:51:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:51:48 INFO - Downloaded 1448 bytes. 22:51:48 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 22:51:48 INFO - Using the following test package requirements: 22:51:48 INFO - {u'common': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 22:51:48 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 22:51:48 INFO - u'firefox-47.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:51:48 INFO - u'gtest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 22:51:48 INFO - u'firefox-47.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:51:48 INFO - u'jittest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 22:51:48 INFO - u'jsshell-linux-x86_64.zip'], 22:51:48 INFO - u'mochitest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 22:51:48 INFO - u'firefox-47.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:51:48 INFO - u'mozbase': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 22:51:48 INFO - u'reftest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 22:51:48 INFO - u'firefox-47.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:51:48 INFO - u'talos': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 22:51:48 INFO - u'firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:51:48 INFO - u'web-platform': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 22:51:48 INFO - u'firefox-47.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:51:48 INFO - u'webapprt': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 22:51:48 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 22:51:48 INFO - u'firefox-47.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:51:48 INFO - Downloading packages: [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:48 INFO - https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:51:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 22:51:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 22:51:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 22:51:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:51:53 INFO - Downloaded 23358968 bytes. 22:51:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:51:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:51:54 INFO - Return code: 0 22:51:54 INFO - Downloading packages: [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:54 INFO - https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:51:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 22:51:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 22:51:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 22:51:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:52:00 INFO - Downloaded 23358968 bytes. 22:52:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:52:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:52:02 INFO - Return code: 0 22:52:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:02 INFO - https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:52:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 22:52:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 22:52:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 22:52:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:52:03 INFO - Downloaded 10845194 bytes. 22:52:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:52:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:52:03 INFO - Return code: 0 22:52: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')]} 22:52:03 INFO - https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:52:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 22:52:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 22:52:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 22:52:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:52:05 INFO - Downloaded 60839413 bytes. 22:52:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 22:52:05 INFO - mkdir: /builds/slave/test/properties 22:52:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:52:05 INFO - Writing to file /builds/slave/test/properties/build_url 22:52:05 INFO - Contents: 22:52:05 INFO - build_url:https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 22:52:06 INFO - Running post-action listener: _resource_record_post_action 22:52:06 INFO - Running post-action listener: set_extra_try_arguments 22:52:06 INFO - ##### 22:52:06 INFO - ##### Running populate-webroot step. 22:52:06 INFO - ##### 22:52:06 INFO - Running pre-action listener: _resource_record_pre_action 22:52:06 INFO - Running main action method: populate_webroot 22:52:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:52:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:52:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:52:06 INFO - '--tppagecycles', 22:52:06 INFO - '7'], 22:52:06 INFO - 'tests': ['tsvgm']}}, 22:52:06 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:52:06 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:52:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:52:06 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 22:52:06 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:52:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:52:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:52:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:06 INFO - 'talos_options': ['--e10s'], 22:52:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:52:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:06 INFO - 'tests': ['damp', 'tps']}, 22:52:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:06 INFO - 'talos_options': ['--e10s'], 22:52:06 INFO - 'tests': ['damp', 'tps']}, 22:52:06 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:52:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:52:06 INFO - 'other': {'tests': ['a11yr', 22:52:06 INFO - 'ts_paint', 22:52:06 INFO - 'tpaint', 22:52:06 INFO - 'sessionrestore', 22:52:06 INFO - 'sessionrestore_no_auto_restore', 22:52:06 INFO - 'tabpaint']}, 22:52:06 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:52:06 INFO - 'tests': ['a11yr', 22:52:06 INFO - 'ts_paint', 22:52:06 INFO - 'tpaint', 22:52:06 INFO - 'sessionrestore', 22:52:06 INFO - 'sessionrestore_no_auto_restore', 22:52:06 INFO - 'tabpaint']}, 22:52:06 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:52:06 INFO - 'tests': ['a11yr', 22:52:06 INFO - 'ts_paint', 22:52:06 INFO - 'tpaint', 22:52:06 INFO - 'sessionrestore', 22:52:06 INFO - 'sessionrestore_no_auto_restore', 22:52:06 INFO - 'tabpaint']}, 22:52:06 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:52:06 INFO - 'tests': ['a11yr', 22:52:06 INFO - 'ts_paint', 22:52:06 INFO - 'tpaint', 22:52:06 INFO - 'sessionrestore', 22:52:06 INFO - 'sessionrestore_no_auto_restore', 22:52:06 INFO - 'tabpaint']}, 22:52:06 INFO - 'other_l64': {'tests': ['a11yr', 22:52:06 INFO - 'ts_paint', 22:52:06 INFO - 'tpaint', 22:52:06 INFO - 'sessionrestore', 22:52:06 INFO - 'sessionrestore_no_auto_restore', 22:52:06 INFO - 'tabpaint']}, 22:52:06 INFO - 'other_nol64': {'tests': ['a11yr', 22:52:06 INFO - 'ts_paint', 22:52:06 INFO - 'tpaint', 22:52:06 INFO - 'sessionrestore', 22:52:06 INFO - 'sessionrestore_no_auto_restore', 22:52:06 INFO - 'tabpaint']}, 22:52:06 INFO - 'svgr': {'tests': ['tsvgx', 22:52:06 INFO - 'tsvgr_opacity', 22:52:06 INFO - 'tart', 22:52:06 INFO - 'tscrollx', 22:52:06 INFO - 'cart']}, 22:52:06 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:52:06 INFO - 'tests': ['tsvgx', 22:52:06 INFO - 'tsvgr_opacity', 22:52:06 INFO - 'tart', 22:52:06 INFO - 'tscrollx', 22:52:06 INFO - 'cart']}, 22:52:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:06 INFO - 'tests': ['tp5o']}, 22:52:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:06 INFO - 'talos_options': ['--e10s'], 22:52:06 INFO - 'tests': ['tp5o']}, 22:52:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:06 INFO - 'talos_options': ['--xperf_path', 22:52:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:52:06 INFO - 'tests': ['tp5n']}, 22:52:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:06 INFO - 'talos_options': ['--e10s', 22:52:06 INFO - '--xperf_path', 22:52:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:52:06 INFO - 'tests': ['tp5n']}}, 22:52:06 INFO - 'talos.zip': {'path': '', 22:52:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:52:06 INFO - Running post-action listener: _resource_record_post_action 22:52:06 INFO - ##### 22:52:06 INFO - ##### Running create-virtualenv step. 22:52:06 INFO - ##### 22:52:06 INFO - Running pre-action listener: _resource_record_pre_action 22:52:06 INFO - Running main action method: create_virtualenv 22:52:06 INFO - Creating virtualenv /builds/slave/test/build/venv 22:52:06 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:52:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:52:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:52:06 INFO - Using real prefix '/usr' 22:52:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:52:07 INFO - Installing distribute.............................................................................................................................................................................................done. 22:52:09 INFO - Installing pip.................done. 22:52:09 INFO - Return code: 0 22:52:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:09 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 0x7fb25ae3bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fac410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203b160>, '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 0x203c060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203c4f0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:52:09 INFO - Using env: {'DISPLAY': ':0', 22:52:09 INFO - 'HOME': '/home/cltbld', 22:52:09 INFO - 'LANG': 'en_US.UTF-8', 22:52:09 INFO - 'LANGUAGE': 'en_US:en', 22:52:09 INFO - 'LOGNAME': 'cltbld', 22:52:09 INFO - 'MAIL': '/var/mail/cltbld', 22:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:09 INFO - 'MOZ_NO_REMOTE': '1', 22:52:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:52:09 INFO - 'NO_EM_RESTART': '1', 22:52:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:09 INFO - 'PWD': '/builds/slave/test', 22:52:09 INFO - 'SHELL': '/bin/bash', 22:52:09 INFO - 'SHLVL': '1', 22:52:09 INFO - 'TERM': 'linux', 22:52:09 INFO - 'TMOUT': '86400', 22:52:09 INFO - 'USER': 'cltbld', 22:52:09 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 22:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:09 INFO - '_': '/tools/buildbot/bin/python'} 22:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:52:09 INFO - Cleaning up... 22:52:09 INFO - Return code: 0 22:52:09 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:09 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 0x7fb25ae3bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fac410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203b160>, '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 0x203c060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203c4f0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:52:09 INFO - Using env: {'DISPLAY': ':0', 22:52:09 INFO - 'HOME': '/home/cltbld', 22:52:09 INFO - 'LANG': 'en_US.UTF-8', 22:52:09 INFO - 'LANGUAGE': 'en_US:en', 22:52:09 INFO - 'LOGNAME': 'cltbld', 22:52:09 INFO - 'MAIL': '/var/mail/cltbld', 22:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:09 INFO - 'MOZ_NO_REMOTE': '1', 22:52:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:52:09 INFO - 'NO_EM_RESTART': '1', 22:52:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:09 INFO - 'PWD': '/builds/slave/test', 22:52:09 INFO - 'SHELL': '/bin/bash', 22:52:09 INFO - 'SHLVL': '1', 22:52:09 INFO - 'TERM': 'linux', 22:52:09 INFO - 'TMOUT': '86400', 22:52:09 INFO - 'USER': 'cltbld', 22:52:09 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 22:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:09 INFO - '_': '/tools/buildbot/bin/python'} 22:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:09 INFO - Downloading/unpacking psutil>=0.7.1 22:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:52:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:52:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:52:11 INFO - Installing collected packages: psutil 22:52:11 INFO - Running setup.py install for psutil 22:52:11 INFO - building 'psutil._psutil_linux' extension 22:52:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:52:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:52:11 INFO - building 'psutil._psutil_posix' extension 22:52:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:52:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:52:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:52:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:52:11 INFO - Successfully installed psutil 22:52:11 INFO - Cleaning up... 22:52:12 INFO - Return code: 0 22:52:12 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:12 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 0x7fb25ae3bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fac410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203b160>, '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 0x203c060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203c4f0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:52:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:52:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:52:12 INFO - Using env: {'DISPLAY': ':0', 22:52:12 INFO - 'HOME': '/home/cltbld', 22:52:12 INFO - 'LANG': 'en_US.UTF-8', 22:52:12 INFO - 'LANGUAGE': 'en_US:en', 22:52:12 INFO - 'LOGNAME': 'cltbld', 22:52:12 INFO - 'MAIL': '/var/mail/cltbld', 22:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:12 INFO - 'MOZ_NO_REMOTE': '1', 22:52:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:52:12 INFO - 'NO_EM_RESTART': '1', 22:52:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:12 INFO - 'PWD': '/builds/slave/test', 22:52:12 INFO - 'SHELL': '/bin/bash', 22:52:12 INFO - 'SHLVL': '1', 22:52:12 INFO - 'TERM': 'linux', 22:52:12 INFO - 'TMOUT': '86400', 22:52:12 INFO - 'USER': 'cltbld', 22:52:12 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 22:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:12 INFO - '_': '/tools/buildbot/bin/python'} 22:52:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:52:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:52:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:52:14 INFO - Installing collected packages: mozsystemmonitor 22:52:14 INFO - Running setup.py install for mozsystemmonitor 22:52:14 INFO - Successfully installed mozsystemmonitor 22:52:14 INFO - Cleaning up... 22:52:14 INFO - Return code: 0 22:52:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:52:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:14 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 0x7fb25ae3bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fac410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203b160>, '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 0x203c060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203c4f0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:52:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:52:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:52:14 INFO - Using env: {'DISPLAY': ':0', 22:52:14 INFO - 'HOME': '/home/cltbld', 22:52:14 INFO - 'LANG': 'en_US.UTF-8', 22:52:14 INFO - 'LANGUAGE': 'en_US:en', 22:52:14 INFO - 'LOGNAME': 'cltbld', 22:52:14 INFO - 'MAIL': '/var/mail/cltbld', 22:52:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:14 INFO - 'MOZ_NO_REMOTE': '1', 22:52:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:52:14 INFO - 'NO_EM_RESTART': '1', 22:52:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:52:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:14 INFO - 'PWD': '/builds/slave/test', 22:52:14 INFO - 'SHELL': '/bin/bash', 22:52:14 INFO - 'SHLVL': '1', 22:52:14 INFO - 'TERM': 'linux', 22:52:14 INFO - 'TMOUT': '86400', 22:52:14 INFO - 'USER': 'cltbld', 22:52:14 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 22:52:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:14 INFO - '_': '/tools/buildbot/bin/python'} 22:52:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:14 INFO - Downloading/unpacking blobuploader==1.2.4 22:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:16 INFO - Downloading blobuploader-1.2.4.tar.gz 22:52:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:52:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:52:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:17 INFO - Downloading docopt-0.6.1.tar.gz 22:52:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:52:17 INFO - Installing collected packages: blobuploader, requests, docopt 22:52:17 INFO - Running setup.py install for blobuploader 22:52:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:52:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:52:17 INFO - Running setup.py install for requests 22:52:17 INFO - Running setup.py install for docopt 22:52:17 INFO - Successfully installed blobuploader requests docopt 22:52:17 INFO - Cleaning up... 22:52:17 INFO - Return code: 0 22:52:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:17 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 0x7fb25ae3bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fac410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203b160>, '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 0x203c060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203c4f0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:52:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:52:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:52:17 INFO - Using env: {'DISPLAY': ':0', 22:52:17 INFO - 'HOME': '/home/cltbld', 22:52:17 INFO - 'LANG': 'en_US.UTF-8', 22:52:17 INFO - 'LANGUAGE': 'en_US:en', 22:52:17 INFO - 'LOGNAME': 'cltbld', 22:52:17 INFO - 'MAIL': '/var/mail/cltbld', 22:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:17 INFO - 'MOZ_NO_REMOTE': '1', 22:52:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:52:17 INFO - 'NO_EM_RESTART': '1', 22:52:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:17 INFO - 'PWD': '/builds/slave/test', 22:52:17 INFO - 'SHELL': '/bin/bash', 22:52:17 INFO - 'SHLVL': '1', 22:52:17 INFO - 'TERM': 'linux', 22:52:17 INFO - 'TMOUT': '86400', 22:52:17 INFO - 'USER': 'cltbld', 22:52:17 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 22:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:17 INFO - '_': '/tools/buildbot/bin/python'} 22:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:52:17 INFO - Running setup.py (path:/tmp/pip-1mkByK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:52:17 INFO - Running setup.py (path:/tmp/pip-FY8Dfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:52:18 INFO - Running setup.py (path:/tmp/pip-bXjWVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:52:18 INFO - Running setup.py (path:/tmp/pip-QFGd3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:52:18 INFO - Running setup.py (path:/tmp/pip-LvKxEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:52:18 INFO - Running setup.py (path:/tmp/pip-n4b5fR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:52:18 INFO - Running setup.py (path:/tmp/pip-7INezF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:52:18 INFO - Running setup.py (path:/tmp/pip-XFCKqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:52:18 INFO - Running setup.py (path:/tmp/pip-SW1lrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:52:18 INFO - Running setup.py (path:/tmp/pip-R1T319-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:52:18 INFO - Running setup.py (path:/tmp/pip-nU3tQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:52:18 INFO - Running setup.py (path:/tmp/pip-tOAT9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:52:18 INFO - Running setup.py (path:/tmp/pip-N4l7fE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:52:18 INFO - Running setup.py (path:/tmp/pip-giD5Ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:52:18 INFO - Running setup.py (path:/tmp/pip-MTXeOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:52:18 INFO - Running setup.py (path:/tmp/pip-bWq2dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:52:19 INFO - Running setup.py (path:/tmp/pip-v2CCsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:52:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:52:19 INFO - Running setup.py install for manifestparser 22:52:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:52:19 INFO - Running setup.py install for mozcrash 22:52:19 INFO - Running setup.py install for mozdebug 22:52:19 INFO - Running setup.py install for mozdevice 22:52:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:52:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:52:19 INFO - Running setup.py install for mozfile 22:52:19 INFO - Running setup.py install for mozhttpd 22:52:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:52:19 INFO - Running setup.py install for mozinfo 22:52:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:52:19 INFO - Running setup.py install for mozInstall 22:52:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:52:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:52:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:52:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:52:19 INFO - Running setup.py install for mozleak 22:52:20 INFO - Running setup.py install for mozlog 22:52:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:52:20 INFO - Running setup.py install for moznetwork 22:52:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:52:20 INFO - Running setup.py install for mozprocess 22:52:20 INFO - Running setup.py install for mozprofile 22:52:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:52:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:52:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:52:20 INFO - Running setup.py install for mozrunner 22:52:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:52:20 INFO - Running setup.py install for mozscreenshot 22:52:20 INFO - Running setup.py install for moztest 22:52:20 INFO - Running setup.py install for mozversion 22:52:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:52:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:52:20 INFO - Cleaning up... 22:52:20 INFO - Return code: 0 22:52:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:52:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:20 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 0x7fb25ae3bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fac410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203b160>, '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 0x203c060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203c4f0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:52:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:52:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:52:20 INFO - Using env: {'DISPLAY': ':0', 22:52:20 INFO - 'HOME': '/home/cltbld', 22:52:20 INFO - 'LANG': 'en_US.UTF-8', 22:52:20 INFO - 'LANGUAGE': 'en_US:en', 22:52:20 INFO - 'LOGNAME': 'cltbld', 22:52:20 INFO - 'MAIL': '/var/mail/cltbld', 22:52:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:20 INFO - 'MOZ_NO_REMOTE': '1', 22:52:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:52:20 INFO - 'NO_EM_RESTART': '1', 22:52:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:52:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:20 INFO - 'PWD': '/builds/slave/test', 22:52:20 INFO - 'SHELL': '/bin/bash', 22:52:20 INFO - 'SHLVL': '1', 22:52:20 INFO - 'TERM': 'linux', 22:52:20 INFO - 'TMOUT': '86400', 22:52:20 INFO - 'USER': 'cltbld', 22:52:20 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 22:52:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:20 INFO - '_': '/tools/buildbot/bin/python'} 22:52:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:52:21 INFO - Running setup.py (path:/tmp/pip-usHh_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:52:21 INFO - Running setup.py (path:/tmp/pip-inNwBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:52:21 INFO - Running setup.py (path:/tmp/pip-JJGNXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:52:21 INFO - Running setup.py (path:/tmp/pip-_UmO1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:52:21 INFO - Running setup.py (path:/tmp/pip-QkqqTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:52:21 INFO - Running setup.py (path:/tmp/pip-REhqCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:52:21 INFO - Running setup.py (path:/tmp/pip-536vub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:52:21 INFO - Running setup.py (path:/tmp/pip-ylbGCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:52:21 INFO - Running setup.py (path:/tmp/pip-L2FuLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:52:21 INFO - Running setup.py (path:/tmp/pip-rxKa5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:52:21 INFO - Running setup.py (path:/tmp/pip-9MNmzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:52:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:52:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:52:21 INFO - Running setup.py (path:/tmp/pip-p8zHAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:52:22 INFO - Running setup.py (path:/tmp/pip-E68mSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:52:22 INFO - Running setup.py (path:/tmp/pip-fFvnSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:52:22 INFO - Running setup.py (path:/tmp/pip-F5oCP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:52:22 INFO - Running setup.py (path:/tmp/pip-G2JyMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:52:22 INFO - Running setup.py (path:/tmp/pip-kqkY9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:52:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:24 INFO - Downloading blessings-1.6.tar.gz 22:52:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:52:24 INFO - Installing collected packages: blessings 22:52:24 INFO - Running setup.py install for blessings 22:52:24 INFO - Successfully installed blessings 22:52:24 INFO - Cleaning up... 22:52:24 INFO - Return code: 0 22:52:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:52:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:52:24 INFO - Reading from file tmpfile_stdout 22:52:24 INFO - Current package versions: 22:52:24 INFO - argparse == 1.2.1 22:52:24 INFO - blessings == 1.6 22:52:24 INFO - blobuploader == 1.2.4 22:52:24 INFO - docopt == 0.6.1 22:52:24 INFO - manifestparser == 1.1 22:52:24 INFO - mozInstall == 1.12 22:52:24 INFO - mozcrash == 0.17 22:52:24 INFO - mozdebug == 0.1 22:52:24 INFO - mozdevice == 0.48 22:52:24 INFO - mozfile == 1.2 22:52:24 INFO - mozhttpd == 0.7 22:52:24 INFO - mozinfo == 0.9 22:52:24 INFO - mozleak == 0.1 22:52:24 INFO - mozlog == 3.1 22:52:24 INFO - moznetwork == 0.27 22:52:24 INFO - mozprocess == 0.22 22:52:24 INFO - mozprofile == 0.28 22:52:24 INFO - mozrunner == 6.11 22:52:24 INFO - mozscreenshot == 0.1 22:52:24 INFO - mozsystemmonitor == 0.0 22:52:24 INFO - moztest == 0.7 22:52:24 INFO - mozversion == 1.4 22:52:24 INFO - psutil == 3.1.1 22:52:24 INFO - requests == 1.2.3 22:52:24 INFO - wsgiref == 0.1.2 22:52:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:24 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 0x7fb25ae3bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fac410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203b160>, '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 0x203c060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203c4f0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:52:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:52:24 INFO - Using env: {'DISPLAY': ':0', 22:52:24 INFO - 'HOME': '/home/cltbld', 22:52:24 INFO - 'LANG': 'en_US.UTF-8', 22:52:24 INFO - 'LANGUAGE': 'en_US:en', 22:52:24 INFO - 'LOGNAME': 'cltbld', 22:52:24 INFO - 'MAIL': '/var/mail/cltbld', 22:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:24 INFO - 'MOZ_NO_REMOTE': '1', 22:52:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:52:24 INFO - 'NO_EM_RESTART': '1', 22:52:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:24 INFO - 'PWD': '/builds/slave/test', 22:52:24 INFO - 'SHELL': '/bin/bash', 22:52:24 INFO - 'SHLVL': '1', 22:52:24 INFO - 'TERM': 'linux', 22:52:24 INFO - 'TMOUT': '86400', 22:52:24 INFO - 'USER': 'cltbld', 22:52:24 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 22:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:24 INFO - '_': '/tools/buildbot/bin/python'} 22:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:52:24 INFO - Cleaning up... 22:52:24 INFO - Return code: 0 22:52:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:24 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 0x7fb25ae3bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fac410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203b160>, '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 0x203c060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203c4f0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:52:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:52:24 INFO - Using env: {'DISPLAY': ':0', 22:52:24 INFO - 'HOME': '/home/cltbld', 22:52:24 INFO - 'LANG': 'en_US.UTF-8', 22:52:24 INFO - 'LANGUAGE': 'en_US:en', 22:52:24 INFO - 'LOGNAME': 'cltbld', 22:52:24 INFO - 'MAIL': '/var/mail/cltbld', 22:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:24 INFO - 'MOZ_NO_REMOTE': '1', 22:52:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:52:24 INFO - 'NO_EM_RESTART': '1', 22:52:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:24 INFO - 'PWD': '/builds/slave/test', 22:52:24 INFO - 'SHELL': '/bin/bash', 22:52:24 INFO - 'SHLVL': '1', 22:52:24 INFO - 'TERM': 'linux', 22:52:24 INFO - 'TMOUT': '86400', 22:52:24 INFO - 'USER': 'cltbld', 22:52:24 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 22:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:24 INFO - '_': '/tools/buildbot/bin/python'} 22:52:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:25 INFO - Downloading/unpacking jsonschema 22:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:52:26 INFO - Downloading/unpacking functools32 (from jsonschema) 22:52: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 22:52: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 22:52: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 22:52: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 22:52: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 22:52: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 22:52:27 INFO - Downloading functools32-3.2.3-2.tar.gz 22:52:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:52:27 INFO - warning: no files found matching '*.txt' 22:52:27 INFO - no previously-included directories found matching 'build' 22:52:27 INFO - no previously-included directories found matching 'dist' 22:52:27 INFO - no previously-included directories found matching '.git*' 22:52:27 INFO - Installing collected packages: jsonschema, functools32 22:52:27 INFO - Running setup.py install for functools32 22:52:27 INFO - warning: no files found matching '*.txt' 22:52:27 INFO - no previously-included directories found matching 'build' 22:52:27 INFO - no previously-included directories found matching 'dist' 22:52:27 INFO - no previously-included directories found matching '.git*' 22:52:27 INFO - Successfully installed jsonschema functools32 22:52:27 INFO - Cleaning up... 22:52:27 INFO - Return code: 0 22:52:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:27 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 0x7fb25ae3bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fac410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203b160>, '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 0x203c060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203c4f0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:52:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:52:27 INFO - Using env: {'DISPLAY': ':0', 22:52:27 INFO - 'HOME': '/home/cltbld', 22:52:27 INFO - 'LANG': 'en_US.UTF-8', 22:52:27 INFO - 'LANGUAGE': 'en_US:en', 22:52:27 INFO - 'LOGNAME': 'cltbld', 22:52:27 INFO - 'MAIL': '/var/mail/cltbld', 22:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:27 INFO - 'MOZ_NO_REMOTE': '1', 22:52:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:52:27 INFO - 'NO_EM_RESTART': '1', 22:52:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:27 INFO - 'PWD': '/builds/slave/test', 22:52:27 INFO - 'SHELL': '/bin/bash', 22:52:27 INFO - 'SHLVL': '1', 22:52:27 INFO - 'TERM': 'linux', 22:52:27 INFO - 'TMOUT': '86400', 22:52:27 INFO - 'USER': 'cltbld', 22:52:27 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 22:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:27 INFO - '_': '/tools/buildbot/bin/python'} 22:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:27 INFO - Downloading/unpacking flake8 22:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:52:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:52:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:52:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:52:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:52:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:52:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:52:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:52:30 INFO - no previously-included directories found matching 'docs/_build' 22:52:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:52:30 INFO - Running setup.py install for pep8 22:52:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:52:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:52:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:52:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:52:30 INFO - no previously-included directories found matching 'docs/_build' 22:52:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:52:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:52:30 INFO - Cleaning up... 22:52:30 INFO - Return code: 0 22:52:30 INFO - Running post-action listener: _resource_record_post_action 22:52:30 INFO - Running post-action listener: _start_resource_monitoring 22:52:30 INFO - Starting resource monitoring. 22:52:30 INFO - ##### 22:52:30 INFO - ##### Running install step. 22:52:30 INFO - ##### 22:52:30 INFO - Running pre-action listener: _resource_record_pre_action 22:52:30 INFO - Running main action method: install 22:52:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:52:30 INFO - Reading from file tmpfile_stdout 22:52:30 INFO - Detecting whether we're running mozinstall >=1.0... 22:52:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:52:30 INFO - Reading from file tmpfile_stdout 22:52:30 INFO - Output received: 22:52:30 INFO - Usage: mozinstall [options] installer 22:52:30 INFO - Options: 22:52:30 INFO - -h, --help show this help message and exit 22:52:30 INFO - -d DEST, --destination=DEST 22:52:30 INFO - Directory to install application into. [default: 22:52:30 INFO - "/builds/slave/test"] 22:52:30 INFO - --app=APP Application being installed. [default: firefox] 22:52:30 INFO - mkdir: /builds/slave/test/build/application 22:52:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:52:46 INFO - Reading from file tmpfile_stdout 22:52:46 INFO - Output received: 22:52:46 INFO - /builds/slave/test/build/application/firefox/firefox 22:52:46 INFO - Running post-action listener: _resource_record_post_action 22:52:46 INFO - ##### 22:52:46 INFO - ##### Running run-tests step. 22:52:46 INFO - ##### 22:52:46 INFO - Running pre-action listener: _resource_record_pre_action 22:52:46 INFO - Running main action method: run_tests 22:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:52:46 INFO - Python 2.7.3 22:52:46 INFO - Return code: 0 22:52:46 INFO - grabbing minidump binary from tooltool 22:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:46 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 0x203b160>, '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 0x203c060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203c4f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:52:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:52:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:52:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:52:46 INFO - Return code: 0 22:52:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:52:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:52:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:52:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:52:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:52:47 INFO - Return code: 0 22:52:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:52:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:52:47 INFO - Using env: {'DISPLAY': ':0', 22:52:47 INFO - 'HOME': '/home/cltbld', 22:52:47 INFO - 'LANG': 'en_US.UTF-8', 22:52:47 INFO - 'LANGUAGE': 'en_US:en', 22:52:47 INFO - 'LOGNAME': 'cltbld', 22:52:47 INFO - 'MAIL': '/var/mail/cltbld', 22:52:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:52:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:47 INFO - 'MOZ_NO_REMOTE': '1', 22:52:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:52:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:52:47 INFO - 'NO_EM_RESTART': '1', 22:52:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:47 INFO - 'PWD': '/builds/slave/test', 22:52:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:52:47 INFO - 'SHELL': '/bin/bash', 22:52:47 INFO - 'SHLVL': '1', 22:52:47 INFO - 'TERM': 'linux', 22:52:47 INFO - 'TMOUT': '86400', 22:52:47 INFO - 'USER': 'cltbld', 22:52:47 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746', 22:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:47 INFO - '_': '/tools/buildbot/bin/python'} 22:52:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:52:47 INFO - mozversion application_buildid: 20160423200744 22:52:47 INFO - mozversion application_changeset: 2b7291cc565421eebd5328f09dede6ff40ce0b42 22:52:47 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:52:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:52:47 INFO - mozversion application_name: Firefox 22:52:47 INFO - mozversion application_remotingname: firefox-dev 22:52:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:52:47 INFO - mozversion application_vendor: Mozilla 22:52:47 INFO - mozversion application_version: 47.0a2 22:52:47 INFO - mozversion platform_buildid: 20160423200744 22:52:47 INFO - mozversion platform_changeset: 2b7291cc565421eebd5328f09dede6ff40ce0b42 22:52:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:52:47 INFO - mozversion platform_version: 47.0a2 22:52:47 INFO - using testdate: 1461477167 22:52:47 INFO - actual date: 1461477167 22:52:47 INFO - starting webserver on 'localhost:60635' 22:52:47 INFO - SUITE-START | Running 2 tests 22:52:47 INFO - TEST-START | dromaeo_css 22:52:47 INFO - operating with platform_type : linux_ 22:52:47 INFO - Initialising browser for dromaeo_css test... 22:52:47 INFO - TEST-INFO | started process 15205 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt8oicJ/profile http://localhost:60635/getInfo.html) 22:52:50 INFO - PROCESS | 15205 | 22:52:50 INFO - PROCESS | 15205 | (process:15280): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:52:51 INFO - PROCESS | 15205 | __metrics Screen width/height:1600/1200 22:52:51 INFO - PROCESS | 15205 | colorDepth:24 22:52:51 INFO - PROCESS | 15205 | Browser inner width/height: 1024/697 22:52:51 INFO - PROCESS | 15205 | __metrics 22:52:51 ERROR - PROCESS | 15205 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 22:52:52 INFO - TEST-INFO | 15205: exit 0 22:52:52 INFO - Browser initialized. 22:52:52 INFO - Running cycle 1/1 for dromaeo_css test... 22:52:52 INFO - TEST-INFO | started process 15331 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt8oicJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:52:54 INFO - PROCESS | 15331 | 22:52:54 INFO - PROCESS | 15331 | (process:15399): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:53:04 INFO - PROCESS | 15331 | RSS: Main: 151949312 22:53:04 INFO - PROCESS | 15331 | 22:53:54 INFO - PROCESS | 15331 | 1461477234120 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:56:01 INFO - PROCESS | 15331 | Cycle 1(1): loaded http://localhost:60635/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60635/tests/dromaeo/cssquery-ext.html) 22:56:02 INFO - PROCESS | 15331 | RSS: Main: 155897856 22:56:02 INFO - PROCESS | 15331 | 22:59:04 INFO - PROCESS | 15331 | Cycle 1(1): loaded http://localhost:60635/tests/dromaeo/cssquery-ext.html (next: http://localhost:60635/tests/dromaeo/cssquery-jquery.html) 22:59:05 INFO - PROCESS | 15331 | RSS: Main: 153849856 22:59:05 INFO - PROCESS | 15331 | 23:02:07 INFO - PROCESS | 15331 | Cycle 1(1): loaded http://localhost:60635/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60635/tests/dromaeo/cssquery-mootools.html) 23:02:07 INFO - PROCESS | 15331 | RSS: Main: 152907776 23:02:07 INFO - PROCESS | 15331 | 23:05:09 INFO - PROCESS | 15331 | Cycle 1(1): loaded http://localhost:60635/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60635/tests/dromaeo/cssquery-prototype.html) 23:05:09 INFO - PROCESS | 15331 | RSS: Main: 152436736 23:05:09 INFO - PROCESS | 15331 | 23:08:12 INFO - PROCESS | 15331 | Cycle 1(1): loaded http://localhost:60635/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60635/tests/dromaeo/cssquery-yui.html) 23:08:12 INFO - PROCESS | 15331 | RSS: Main: 153980928 23:08:12 INFO - PROCESS | 15331 | 23:11:14 INFO - PROCESS | 15331 | Cycle 1(1): loaded http://localhost:60635/tests/dromaeo/cssquery-yui.html (next: http://localhost:60635/tests/dromaeo/cssquery-dojo.html) 23:11:15 INFO - PROCESS | 15331 | RSS: Main: 155533312 23:11:15 INFO - PROCESS | 15331 | 23:11:15 INFO - PROCESS | 15331 | __start_tp_report 23:11:15 INFO - PROCESS | 15331 | _x_x_mozilla_page_load 23:11:15 INFO - PROCESS | 15331 | _x_x_mozilla_page_load_details 23:11:15 INFO - PROCESS | 15331 | |i|pagename|runs| 23:11:15 INFO - PROCESS | 15331 | |0;dojo.html;3284.753643476739;3359.143418428301;3390.169408494919;3435.0553597760627;3436.5704286964115;5510.724463776815;5537.529310008649;5543.390227075029;5564.387917329091;5587.2177895094665;5534.418886016971;5538.501534861862;5559.610827242094;5573;5576.191452239423;5085.0592640361465;5095.694258344502;5157.252198431225;5158.613104017195;5167.870803229931;4876.366072410582;5029.597632189416;5131.486851314876;5181.637285390024;5211.296474975868;5088.363954505687;5113.386393632769;5163.044836705203;5176.870578235536;5203.1414816555225;5166.715830629314;5172.948270517284;5193.740312984336;5219.060569097561;5255.237990491396;4897.314375987365;5121.795128194868;5137.48625137487;5172.353455818023;5201.505856943622;5286.577073834084;5356.11624082026;5362.51737343641;5364.1149210380245;5392.460753924615;5963.224780778471;5980.551458640576;6012.248468941382;6018.939810601925;6058.091286307045;5862.237909071793;5971.761129554812;6046.455818976313;6057.727402266909;6078.088286756978;5636.323641163087;5648.209250704905;5649.435056494359;5654.151877218368;5681.715914204273;6066.575339726265;6092.969535152384;6101.908471372933;6103.38966103395;6134.4172303631085;5732.598718089694;5901.468867801918;5905.734241959122;5920.940790592125;5957.821265362046;5958.851028724316;5959.28488581373;5961.7615295387695;5962.791302304398;5973.163757074013;5904.911426328608;5912.142739302863;5944.613600115978;5967.283925928917;5978.342382337939;5807.0998995155815;5897.823065308047;5953.940460595425;5960.940390596125;5981.5214782817275;4549.476810166807;4552.840650577214;4563.247064234364;4564.42944631921;4577.885552861205;4104.692148088905;4122.175564886975;4124.8143833527565;4128.236276288896;4152.43942067823;5576;5724.627899186539;5795.971020144872;5802.47777700009;5821.534277257894;5843.503302219274;5890.646561206341;5928.851778705567;5959.135925290895;5973.074173503114;5562.9443705563235;5711.457411545896;5717.8570535736935;5745.253117094834;5746.827595172152;5061.190209566452;5099.8725031873755;5124.205748109049;5166.199239117943;5172.146595811649;5985.820425387245;5985.850353741192;5996.550258730613;6045.274567051982;6061.060535616986;5814.127880817742;5888.440598143169;5998.430149135738;6005.819825405245;6013.188219590299;6111.8956224267595;6128.295246046583;6136.110264011604;6157.753689852356;6174.969125154345;5844.298684157929;5846.41535846412;5858.326292476249;5859.267591551056;5888.558358123244;5740.225069615549;5943.316518600416;5950.732217050157;5985.910211346921;6067.605605635619;5913.674747888907;5918.940810592011;5920.348761636303;5923.289205295392;5925.525957923442;5827.825165245049;5853.590248682552;5865.296164460292;5901.645901245939;5913.5564832636865;5543.196236545759;5779.133130030361;5780.942190578208;5842.591018628656;5858.2677165354335;5839.766409343579;5856.824295271149;5875.559333049953;5904.61619994699;5911.142884281841;5770.451807078321;5780.132980053026;5786.971065144648;5791.739371728367;5798.884022319446 23:11:15 INFO - PROCESS | 15331 | |1;ext.html;514.2671692837707;564.1903867949493;568.866316415642;581.680075958223;592.9889538337136;41303.79348103255;41453.58546414535;41543.79228103859;41614.37578436318;41695.37456938145;43874;44442.7777861111;44536.55463445365;44629.33056004154;44697.329540056846;8836.823263534725;9032.412893161956;9042.186203241707;9051.094890510962;9064.637414503444;9027.774305642346;9133.269338452908;9164.083591640816;9197.21823644991;9197.540122993856;9304.441733495978;9379;9393.389429687048;9415.435073895589;9428.622855085789;9300.39547429415;9326.72019839406;9348.065193480632;9359.391639543408;9402.106799854071;9095.363324567283;9228.907710922938;9233;9243.214326782232;9259.351845370828;43899.78050109761;44554;44566.55433445657;44599.331010034875;44680.3297950531;3183.570218020561;3369.4777309517062;3500.9824950875336;3690.5940346561856;3785.015895867067;29456;29751.5537266939;30002.399952001055;30026.699733002828;30309.393812123635;4149.585041495857;4213.136307057046;4215.662746980234;4249.766262855542;4283.657307415399;207276;207779;208291;209128;209531;46497.30254046192;47127.76436117797;64983.675081624286;65993.34006659967;66004.33995659983;45956.310655340196;46076.76961615238;46278.76860615743;46484.302735458994;46526;16078.115703636178;16098.919505402633;16296.511104666879;16308.184590770414;16315.59211019734;12958.416871240817;12986.935065324613;13004.479820807252;13020.414081366362;13020.544280950122;15651.139187344577;15986.120763357894;16055.438059667855;16153.19234038317;16195.19024048793;6151.323354431009;6207.503399728011;6278.686065696788;6290.937090629127;6295.559310848196;1033.540074666766;1042.0361165921493;1102.7849569334064;1141.0814294493014;1171.2035815645272;30598;30897;31138.377232455223;31188;31389.37221255562;1114.3815182573587;1152.3201311226417;1162.587281515067;1168.0246993760306;1206.3063738350484;14995.400183992737;15088.924555377153;15106.924465377822;15239.847601524065;15340.15629140408;339200;518410;533796;546306;546862;62750.37249627537;63283.36716632775;63292.36707632865;63583.682081590225;63727.6813615929;1510.5241848817586;1520.9239538023055;1524.2759689147788;1528.3657282227973;1539.961500962485;32448.188795280308;32875;32878.17804554905;33004.33991320208;33712.820051298084;51237.48762512476;51967.74016129895;52008.73995629998;52060;52124;274076;274437;274571;275026;276490;35756.64243357637;36025.45961810627;50999;51925.48074519358;51962.480375195766;47912;49147.262791057445;49646.25530616971;49816.75091624519;50448.49551504438;43652.56347436612;43737;44409.555904440545;44803.32795008012;45288.0942381144;41757.58242417537;41814.581854181066;42189;42898.35652465153;43642.12715745731;42990.78504607582;43314.56685433105;43423.34864977091;43656.78171609249;43722.34416483692;37493.4375984355;38039.61960380361;38376;38884.2223155541;39051;29229.97695080747;29287.560686590143;29563.556546651387;29846.253843653212;29909.252268692588 23:11:15 INFO - PROCESS | 15331 | |2;jquery.html;371.28898160023556;376.918962423079;382.300390285777;387.932111880421;394.960503949605;13061.67345816355;13348.199108053524;17746.378876739316;18880;19404.126814293355;18445.538861528436;18839.24643014278;19003.52491187721;19101.904490477562;19169.233230670758;6191.133241346204;6240.40716131969;6457.903131453032;6490;6492.188476440445;6392.16901802765;6459.128017717619;6475.967620161892;6477.287498375175;6515.0227465880025;6240.906386404207;6444.387783160592;6457.483401327906;6516.771912983047;6521.7282629887195;6393.392627700361;6456.289808121103;6463.482921366303;6464.418202361763;6491.415772580467;6018.608790428608;6226.87546249077;6260.186175797163;6266.561340706153;6273.937260627426;24947.5010499789;25261.87369063161;25316.113936012334;25547.48905021889;25595;2457.7296497385273;2495.251424572621;2508.134693530729;2659.175655546787;3031.4846476098896;14960.700785984329;15053.473128440452;15205.239738013055;15388.384464621402;15466.9226653866;1997.9300724474574;2056.4036429435446;2057.1360028787944;2078.532330225702;2088.488320361506;65694;78463;80373;81756.59121704353;82321;13309.600711978655;15458.922705386625;15918.9204053979;16148.35406583747;16338.591535211715;13596.320183990762;14221;28044.859775700992;28107.156785296065;28169.43661126766;6992.650367481606;7127.5010749248095;7370.557766533918;7386.59373734439;7546.245375462519;7020.5787652741;7073.115860517435;7082.681279342442;7162.570245785269;7198.064251647253;4315.741055536678;4428.734275943454;4456.153330867125;4463.509014008457;4473.194824931478;2004.7093171490053;2020.8383329333617;2066.628006958762;2067.374434468217;2122.564874200785;494.76004137993044;986.3933680786265;1031.7884833609091;1035.694470131313;1109.0074383426788;11748.118891083202;12097.75804483923;12195.634130976085;12306;12371.814422783666;487.9024195160982;499.4605825708226;501.07051419616084;501.64633933077096;516.9379674439091;5941.821745347646;6689.896167132466;6740.528163028541;6743.35938085881;6769.966150169218;451411;455069;456272;463746;485624;80322.19677803147;82609.17390826135;83519;84545;84555.57722211472;531.7952588253551;1028.1774580335702;1054.1566746602841;1059.6079450603327;1075.7467550303966;16190.838091618933;16497.505074847773;16679.332826686797;16702.58243543921;16816.57059149937;27027.459450810482;33320.66679333176;35203.64796352004;35438.82280588683;37892.62107379001;44668;46283.76858115823;47448.525514745794;49702.502974969786;49932;8335.916640833757;8364.079951205242;8472.322214222724;8546.829063418822;8620.568971551524;11922.225055371373;12378.690532736753;12408.81386779217;12427.005839532634;12514.436850341574;10487.580496780043;10698.358098514114;10737.194710396907;10802.513886874953;10938.781224375309;8125.537403267469;8813.691520796741;10421.531031103466;10469.842952355568;10779.622713205;10660.680179594625;10661.147108231478;10704.464776761286;10818.188635852186;11256.212065155689;8393.664253429795;8398.790030249293;8403.873941890999;8454.323654107533;8479.703210387606;8226.588670566567;10389.480525973822;10441.477926103817;10490;10495.370277783357 23:11:15 INFO - PROCESS | 15331 | |3;mootools.html;1595.2263152371102;1614.1283706798329;1853.406909788868;1931.652302585534;1986.6424043672143;7786.493877897933;8485.745427637165;8630.093840146781;8813.162749538791;8859.822803543926;8591.097934716858;8596.183362580545;8679.305655547556;8699.869501957475;8712.433691810027;2491.9999999999955;2498.6252062190724;2514.962275565868;2515.4845154845157;2520.0549793827313;2411.05968672218;2496.4133428644254;2529.911453099142;2530.7595778401023;2538.23852844146;2531.088808029108;2534.8859301331486;2539.898404063835;2541.809364297685;2547.681539807524;2488.241086468617;2528.380546766045;2528.7471252874657;2531.607600821857;2540.3395117269456;2455.693038370204;2481.2308184462872;2500.1249562653106;2527.658766066594;2530.962035569468;8884.511351875644;8937.46375217489;8944.64221431142;8987.37088403812;8987.955060224656;5511.173324001392;5604.103343465026;5626.071698169797;5631.436856314377;5641.71791410432;3389.1865952171547;3400.5579274694132;3411.198368383428;3461.3423449544757;3472.2708231271463;1086.1907878630393;1098.555085190499;1114.5430373546808;1120.199057673759;1130.949107290174;12598.874011259863;12610.81083783744;12648.74702505963;12651.367431628381;12660.936695316464;4645.837395691202;4737.881552961135;4788.784504697227;4820.831270905502;4896.36347274852;10688.572457101784;10704.625338113128;10705.678829635124;10707.089897358655;10724.946375268073;5427;5490.1764735289225;5491.533219676372;5514.448555144534;5519.39286678465;4397.406350142754;4899.755012249373;4972.8756781080765;5061.670991385548;5097.46476619957;4099.3149487086;4102.405151253051;4112.465379500646;4114.897127571775;4138.420621113047;1352.3576301256824;1362.7002059546883;1366.187118664393;1370.3148425787106;1380.675541247806;1426.436557559758;1481.9555413337616;1499.5801175670829;1500.174903802904;1531.2267305011121;8443.28232100266;8464.576771161417;8481.27909127731;8496.447730897471;8498.490090594583;557.0029646932028;567.7019564728467;568.4145330309788;578.6961845031308;579.8724280658248;6457.902156633289;6473.482121430275;6535.052417399451;6562.868742625121;6569.901451478232;10803.513841877135;11183.273087249303;11261.549538018551;11288.71778205535;11295;10798.082163016075;10976.835347469794;11087.55649774;11103.44482775858;11208.607698730668;8523.65905363791;8554.828903421896;8556.401051926305;8578.270846977952;8585.399022068521;8227.876581851397;8252.546109964009;8326.167383261625;8409.159084091541;8413.70552030676;9763.902360976299;9778.804423911339;9794.16749576294;9895.802083958424;9959.601615935282;9978.501074946369;10110.646127385506;10110.898891010997;10287.43419111956;10302.433366164933;7678.731244406419;7993.280604745368;8063.153368896416;8281.461704989157;8304.128066553167;8463.449875758057;8465.195806398382;8466.111058338785;8473;8474.364422668448;7548.66031028616;7621.352185064332;7690.884636730341;7713.691452341844;7845.254700803414;7600.619969001638;7652.196519365609;7772.572508512086;7773.067231932205;7800.531968081933;8213.383996200428;8256.958715206385;8264.256216940505;8288.046874609256;8293.83412331738;7232.963835180791;7351.816204594714;7415.629218538944;7428.665710043169;7428.73999410018;7570.9242907572425;7571.772846814604;7588.317051465395;7599.430042746705;7602.885956710543 23:11:15 INFO - PROCESS | 15331 | |4;prototype.html;376.12175570043945;376.25689263704186;380.42935596605093;382.296574303282;384.9980750096251;6402.871942561146;6403.231612206536;6425.90361144582;6469.256035555906;6469.70886310116;6339.7464101436;6343.460805831498;6357.61854288743;6359.491240700744;6364.586301890386;4740.383750112498;4894.8286809961655;4925.70445773253;4936.432310284327;4942.406911170656;4873.488283730193;4885.682430642015;4912.336834527328;4923.138450771118;4925.039617274633;4879.487358918746;4895.828645997391;4900.362952816146;4900.803967841282;4910.729909854975;4901.09329773993;4915.115279249733;4920.261960705887;4921.704697718148;4933.1120398328285;4300.849470268526;4306.698531102824;4310.698251122422;4314.439122914033;4318.5465526119615;6071.51427885768;6121.5102791777;6130.4176103270565;6133.539984501181;6135.141080248769;5554.9722251388885;5726.828195154152;5728.770849166029;5729.226554415142;5743.16724075011;6672.332766723337;6758.864822703518;6773.085633439472;6778.525503214777;6801.693923773442;4703.858884233478;4718.528147185289;4747.240441529339;4748.667593268473;4751.833685821014;7048.75329363475;7063.293670632947;7069.399101076363;7104.928950710479;7105.32499412555;4758.690685105457;4760.404949381327;4822.348982887265;4837.22604383304;4841.12859685253;4884.682495637843;4886.096072226649;4895.118878601886;4896.045271172158;4914.950850491521;5640.351359593617;5681.403452637496;5698.686572238566;5708.40061793514;5712.285964254468;6859;6862.382385585322;6866.279040700654;6874.828129296808;6877.449804015667;1730.230047628793;1742.9564260893578;1747.1700942052678;1754.6929287374658;1754.8332908373684;1109.916756243285;1120.8094623913955;1141.2068612314529;1150.2523359816187;1156.5373850459714;6563.1796025496815;6565.409113179837;6572.07990881277;6579.046038324512;6583.572067815577;6988.825279368057;7037.718491260394;7075.39859111971;7096.112985876765;7114.715411383487;6569.57297775643;6570.5729127606555;6571.868562628721;6573.80278591643;6588.934110658832;3798.0504873781556;3815.523059617548;3815.637514436124;3836.865709700147;3840.7887566184127;7175.89236161458;7386.704531818775;7406.629668516553;7421.851562968711;7438;7254.238304978006;7325.633718314063;7343.192248852622;7350.4119670426235;7352.080989876265;7421.220771818989;7432.814179645444;7435.1821299656995;7438;7446.069241344832;6977.825554361181;7049.259827718016;7102.431805455654;7114.822129446805;7137.785866424016;7061.71753129869;7084.752033678797;7102.076730025064;7130;7138.250483699137;7361.079865016873;7361.411087113125;7371.59456229891;7373.225811289737;7450.701971921062;4871.244957031666;4908.214685650279;4913.115639184981;4919.040787046635;4927.605791536596;6923.5499692519825;7117.822054448473;7133.964330178316;7137.10786151731;7142.9642851787485;6887.139107611549;6898.965505172442;6901;6913.308669133247;6940.757073502403;6894.8621027580175;6895.862082758417;6898.068760717239;6902.482313826584;6921.546475240256;6739.224989126314;6742.898856517254;6749.797506074826;6753.797386078426;6764.120664313607;6735.326467353226;6874.621895795779;6950.165980082423;6980.546264492792;6995.160580730141;6721.260661327348;6758.4931130166415;6771.085903402977;6782.050512928293;6805.353491418307 23:11:15 INFO - PROCESS | 15331 | |5;yui.html;792.2275781113415;807.7415227127319;807.850547648685;815.0056930544733;836.1596595421601;14618.195999220039;14816.6295842604;14834.332455039523;14868.553943381688;14879.256037198124;14092.436302547885;14126.64683382916;14151.858481415184;14162.362693678784;14183.78724319136;902.1114202510522;907.9773005674845;912.0560220172123;912.1380295620642;932.6875496708611;912.9863052054225;914.4696076275777;917.4541148016953;917.7705573606598;921.4839689773714;967.110258562121;967.5355829201987;972.6644307713827;972.7324985628974;980.7253968888723;959.8464245720686;963.7253382785942;966.2559828931753;969.3795970578835;978.4520668425669;721.5850885740667;723.0816862584488;723.9493235473519;727.0729819480152;727.1637616740737;13567.253801040939;13650.658733531642;13657.521986730517;13683.794743078863;13714.725705485835;13346.466141354334;13540.526081587195;13610.319484025758;13755.518556850462;13807.861921380758;10870.72823179419;11050.557977680883;11057.336559806437;11129;11133.220674552784;3769.227308401771;3775.452559378875;3794.677452416548;3814.408766641175;3866.671332936704;52088.739556301975;52147;52553.211701824504;53226.20160697593;53246.73376633092;10502.264841461025;10553.84169237462;10568.947155264175;10626.787464250827;10627.9903409176;18495.90752046231;18666.253349866125;18714.906425467787;18739;18872;8073.31376832964;8081.676732930617;8237.423380363318;8266.710665126691;8270.669173233124;4322.610965013164;4369.737815731066;4374.62815660666;4406.713563618355;4426.490953540319;3390.118569172033;3412.0446275043028;3414.573178352706;3419.811910344955;3433.7939723616664;614.6557927560491;615.227888999303;635.9936400636027;637.0221709675626;641.3394203969825;667.7562689618254;669.8693754717783;682.8941514065327;711.6228398948462;717.0248462091552;8376.66146397718;8765.737027889174;9152.954235228914;9287.860682089775;9539.379940303857;670.6076944987237;681.7307163670323;695.6417445015821;728.4099879097947;735.5219107580116;3877.495925529663;3989.5212574491247;3993.880183594497;4011.5787842276723;4077.918441631151;56232;57402.425975740545;57603;57914;58017.41982580205;55292;55733;55759.21203939868;56475.435245647015;56786.14820777692;49544.50455495399;49584;50653;51044.23433648498;51348.74325628423;7706.845863082819;7746.767596972099;7999.160088190656;8081.313088387553;8118.75643730689;35686.28627427385;36310.636893630726;36400;36447.81776091103;36765.81617091897;42403;43954.56045439505;45494.77252613716;45588.7720561395;45700;10769.40768257722;11021.779564408507;11137.888621114009;11196.160287978271;11389.031932285518;11291.887081129413;11315.490802913724;11343.716407089889;11423.885761142283;11439.199256052329;10393.792124157626;10510.789784204426;10711.732206694895;10870.891291087304;11328.376939268419;11102;11229.775404492028;11363.772724545628;11412.48643811047;11667.883321167019;10830.945845270724;11214.55141794319;11229;11480.196386252883;11499;9918.504074796376;9967.700968970943;9980.650677226262;9987.350822196535;10117.494125293853;9736.318457707892;9840.901590983998;9910.702678919644;9911.306208565331;9967.60129594837 23:11:15 INFO - PROCESS | 15331 | __end_tp_report 23:11:15 INFO - PROCESS | 15331 | __start_cc_report 23:11:15 INFO - PROCESS | 15331 | _x_x_mozilla_cycle_collect,337 23:11:15 INFO - PROCESS | 15331 | __end_cc_report 23:11:15 INFO - PROCESS | 15331 | __startTimestamp1461478275135__endTimestamp 23:11:15 INFO - PROCESS | 15331 | 23:11:15 INFO - PROCESS | 15331 | ------- Summary: start ------- 23:11:15 INFO - PROCESS | 15331 | Number of tests: 6 23:11:15 INFO - PROCESS | 15331 | 23:11:15 INFO - PROCESS | 15331 | [#0] dojo.html Cycles:165 Average:5553.74 Median:5798.88 stddev:600.12 (10.3%) stddev-sans-first:574.96 23:11:15 INFO - PROCESS | 15331 | Values: 3284.8 3359.1 3390.2 3435.1 3436.6 5510.7 5537.5 5543.4 5564.4 5587.2 5534.4 5538.5 5559.6 5573.0 5576.2 5085.1 5095.7 5157.3 5158.6 5167.9 4876.4 5029.6 5131.5 5181.6 5211.3 5088.4 5113.4 5163.0 5176.9 5203.1 5166.7 5172.9 5193.7 5219.1 5255.2 4897.3 5121.8 5137.5 5172.4 5201.5 5286.6 5356.1 5362.5 5364.1 5392.5 5963.2 5980.6 6012.2 6018.9 6058.1 5862.2 5971.8 6046.5 6057.7 6078.1 5636.3 5648.2 5649.4 5654.2 5681.7 6066.6 6093.0 6101.9 6103.4 6134.4 5732.6 5901.5 5905.7 5920.9 5957.8 5958.9 5959.3 5961.8 5962.8 5973.2 5904.9 5912.1 5944.6 5967.3 5978.3 5807.1 5897.8 5953.9 5960.9 5981.5 4549.5 4552.8 4563.2 4564.4 4577.9 4104.7 4122.2 4124.8 4128.2 4152.4 5576.0 5724.6 5796.0 5802.5 5821.5 5843.5 5890.6 5928.9 5959.1 5973.1 5562.9 5711.5 5717.9 5745.3 5746.8 5061.2 5099.9 5124.2 5166.2 5172.1 5985.8 5985.9 5996.6 6045.3 6061.1 5814.1 5888.4 5998.4 6005.8 6013.2 6111.9 6128.3 6136.1 6157.8 6175.0 5844.3 5846.4 5858.3 5859.3 5888.6 5740.2 5943.3 5950.7 5985.9 6067.6 5913.7 5918.9 5920.3 5923.3 5925.5 5827.8 5853.6 5865.3 5901.6 5913.6 5543.2 5779.1 5780.9 5842.6 5858.3 5839.8 5856.8 5875.6 5904.6 5911.1 5770.5 5780.1 5787.0 5791.7 5798.9 23:11:15 INFO - PROCESS | 15331 | 23:11:15 INFO - PROCESS | 15331 | [#1] ext.html Cycles:180 Average:51163.89 Median:23591.01 stddev:93493.81 (396.3%) stddev-sans-first:93678.75 23:11:15 INFO - PROCESS | 15331 | Values: 514.3 564.2 568.9 581.7 593.0 41303.8 41453.6 41543.8 41614.4 41695.4 43874.0 44442.8 44536.6 44629.3 44697.3 8836.8 9032.4 9042.2 9051.1 9064.6 9027.8 9133.3 9164.1 9197.2 9197.5 9304.4 9379.0 9393.4 9415.4 9428.6 9300.4 9326.7 9348.1 9359.4 9402.1 9095.4 9228.9 9233.0 9243.2 9259.4 43899.8 44554.0 44566.6 44599.3 44680.3 3183.6 3369.5 3501.0 3690.6 3785.0 29456.0 29751.6 30002.4 30026.7 30309.4 4149.6 4213.1 4215.7 4249.8 4283.7 207276.0 207779.0 208291.0 209128.0 209531.0 46497.3 47127.8 64983.7 65993.3 66004.3 45956.3 46076.8 46278.8 46484.3 46526.0 16078.1 16098.9 16296.5 16308.2 16315.6 12958.4 12986.9 13004.5 13020.4 13020.5 15651.1 15986.1 16055.4 16153.2 16195.2 6151.3 6207.5 6278.7 6290.9 6295.6 1033.5 1042.0 1102.8 1141.1 1171.2 30598.0 30897.0 31138.4 31188.0 31389.4 1114.4 1152.3 1162.6 1168.0 1206.3 14995.4 15088.9 15106.9 15239.8 15340.2 339200.0 518410.0 533796.0 546306.0 546862.0 62750.4 63283.4 63292.4 63583.7 63727.7 1510.5 1520.9 1524.3 1528.4 1540.0 32448.2 32875.0 32878.2 33004.3 33712.8 51237.5 51967.7 52008.7 52060.0 52124.0 274076.0 274437.0 274571.0 275026.0 276490.0 35756.6 36025.5 50999.0 51925.5 51962.5 47912.0 49147.3 49646.3 49816.8 50448.5 43652.6 43737.0 44409.6 44803.3 45288.1 41757.6 41814.6 42189.0 42898.4 43642.1 42990.8 43314.6 43423.3 43656.8 43722.3 37493.4 38039.6 38376.0 38884.2 39051.0 29230.0 29287.6 29563.6 29846.3 29909.3 23:11:15 INFO - PROCESS | 15331 | 23:11:15 INFO - PROCESS | 15331 | [#2] jquery.html Cycles:180 Average:27347.84 Median:19137.46 stddev:76097.44 (397.6%) stddev-sans-first:76283.96 23:11:15 INFO - PROCESS | 15331 | Values: 371.3 376.9 382.3 387.9 395.0 13061.7 13348.2 17746.4 18880.0 19404.1 18445.5 18839.2 19003.5 19101.9 19169.2 6191.1 6240.4 6457.9 6490.0 6492.2 6392.2 6459.1 6476.0 6477.3 6515.0 6240.9 6444.4 6457.5 6516.8 6521.7 6393.4 6456.3 6463.5 6464.4 6491.4 6018.6 6226.9 6260.2 6266.6 6273.9 24947.5 25261.9 25316.1 25547.5 25595.0 2457.7 2495.3 2508.1 2659.2 3031.5 14960.7 15053.5 15205.2 15388.4 15466.9 1997.9 2056.4 2057.1 2078.5 2088.5 65694.0 78463.0 80373.0 81756.6 82321.0 13309.6 15458.9 15918.9 16148.4 16338.6 13596.3 14221.0 28044.9 28107.2 28169.4 6992.7 7127.5 7370.6 7386.6 7546.2 7020.6 7073.1 7082.7 7162.6 7198.1 4315.7 4428.7 4456.2 4463.5 4473.2 2004.7 2020.8 2066.6 2067.4 2122.6 494.8 986.4 1031.8 1035.7 1109.0 11748.1 12097.8 12195.6 12306.0 12371.8 487.9 499.5 501.1 501.6 516.9 5941.8 6689.9 6740.5 6743.4 6770.0 451411.0 455069.0 456272.0 463746.0 485624.0 80322.2 82609.2 83519.0 84545.0 84555.6 531.8 1028.2 1054.2 1059.6 1075.7 16190.8 16497.5 16679.3 16702.6 16816.6 27027.5 33320.7 35203.6 35438.8 37892.6 44668.0 46283.8 47448.5 49702.5 49932.0 8335.9 8364.1 8472.3 8546.8 8620.6 11922.2 12378.7 12408.8 12427.0 12514.4 10487.6 10698.4 10737.2 10802.5 10938.8 8125.5 8813.7 10421.5 10469.8 10779.6 10660.7 10661.1 10704.5 10818.2 11256.2 8393.7 8398.8 8403.9 8454.3 8479.7 8226.6 10389.5 10441.5 10490.0 10495.4 23:11:15 INFO - PROCESS | 15331 | 23:11:15 INFO - PROCESS | 15331 | [#3] mootools.html Cycles:180 Average:6274.87 Median:5490.85 stddev:3346.86 (61.0%) stddev-sans-first:3337.77 23:11:15 INFO - PROCESS | 15331 | Values: 1595.2 1614.1 1853.4 1931.7 1986.6 7786.5 8485.7 8630.1 8813.2 8859.8 8591.1 8596.2 8679.3 8699.9 8712.4 2492.0 2498.6 2515.0 2515.5 2520.1 2411.1 2496.4 2529.9 2530.8 2538.2 2531.1 2534.9 2539.9 2541.8 2547.7 2488.2 2528.4 2528.7 2531.6 2540.3 2455.7 2481.2 2500.1 2527.7 2531.0 8884.5 8937.5 8944.6 8987.4 8988.0 5511.2 5604.1 5626.1 5631.4 5641.7 3389.2 3400.6 3411.2 3461.3 3472.3 1086.2 1098.6 1114.5 1120.2 1130.9 12598.9 12610.8 12648.7 12651.4 12660.9 4645.8 4737.9 4788.8 4820.8 4896.4 10688.6 10704.6 10705.7 10707.1 10724.9 5427.0 5490.2 5491.5 5514.4 5519.4 4397.4 4899.8 4972.9 5061.7 5097.5 4099.3 4102.4 4112.5 4114.9 4138.4 1352.4 1362.7 1366.2 1370.3 1380.7 1426.4 1482.0 1499.6 1500.2 1531.2 8443.3 8464.6 8481.3 8496.4 8498.5 557.0 567.7 568.4 578.7 579.9 6457.9 6473.5 6535.1 6562.9 6569.9 10803.5 11183.3 11261.5 11288.7 11295.0 10798.1 10976.8 11087.6 11103.4 11208.6 8523.7 8554.8 8556.4 8578.3 8585.4 8227.9 8252.5 8326.2 8409.2 8413.7 9763.9 9778.8 9794.2 9895.8 9959.6 9978.5 10110.6 10110.9 10287.4 10302.4 7678.7 7993.3 8063.2 8281.5 8304.1 8463.4 8465.2 8466.1 8473.0 8474.4 7548.7 7621.4 7690.9 7713.7 7845.3 7600.6 7652.2 7772.6 7773.1 7800.5 8213.4 8257.0 8264.3 8288.0 8293.8 7233.0 7351.8 7415.6 7428.7 7428.7 7570.9 7571.8 7588.3 7599.4 7602.9 23:11:15 INFO - PROCESS | 15331 | 23:11:15 INFO - PROCESS | 15331 | [#4] prototype.html Cycles:180 Average:5737.82 Median:6564.29 stddev:1739.79 (26.5%) stddev-sans-first:1697.48 23:11:15 INFO - PROCESS | 15331 | Values: 376.1 376.3 380.4 382.3 385.0 6402.9 6403.2 6425.9 6469.3 6469.7 6339.7 6343.5 6357.6 6359.5 6364.6 4740.4 4894.8 4925.7 4936.4 4942.4 4873.5 4885.7 4912.3 4923.1 4925.0 4879.5 4895.8 4900.4 4900.8 4910.7 4901.1 4915.1 4920.3 4921.7 4933.1 4300.8 4306.7 4310.7 4314.4 4318.5 6071.5 6121.5 6130.4 6133.5 6135.1 5555.0 5726.8 5728.8 5729.2 5743.2 6672.3 6758.9 6773.1 6778.5 6801.7 4703.9 4718.5 4747.2 4748.7 4751.8 7048.8 7063.3 7069.4 7104.9 7105.3 4758.7 4760.4 4822.3 4837.2 4841.1 4884.7 4886.1 4895.1 4896.0 4915.0 5640.4 5681.4 5698.7 5708.4 5712.3 6859.0 6862.4 6866.3 6874.8 6877.4 1730.2 1743.0 1747.2 1754.7 1754.8 1109.9 1120.8 1141.2 1150.3 1156.5 6563.2 6565.4 6572.1 6579.0 6583.6 6988.8 7037.7 7075.4 7096.1 7114.7 6569.6 6570.6 6571.9 6573.8 6588.9 3798.1 3815.5 3815.6 3836.9 3840.8 7175.9 7386.7 7406.6 7421.9 7438.0 7254.2 7325.6 7343.2 7350.4 7352.1 7421.2 7432.8 7435.2 7438.0 7446.1 6977.8 7049.3 7102.4 7114.8 7137.8 7061.7 7084.8 7102.1 7130.0 7138.3 7361.1 7361.4 7371.6 7373.2 7450.7 4871.2 4908.2 4913.1 4919.0 4927.6 6923.5 7117.8 7134.0 7137.1 7143.0 6887.1 6899.0 6901.0 6913.3 6940.8 6894.9 6895.9 6898.1 6902.5 6921.5 6739.2 6742.9 6749.8 6753.8 6764.1 6735.3 6874.6 6950.2 6980.5 6995.2 6721.3 6758.5 6771.1 6782.1 6805.4 23:11:15 INFO - PROCESS | 15331 | 23:11:15 INFO - PROCESS | 15331 | [#5] yui.html Cycles:180 Average:14382.64 Median:47622.25 stddev:16791.25 (35.3%) stddev-sans-first:16807.34 23:11:15 INFO - PROCESS | 15331 | Values: 792.2 807.7 807.9 815.0 836.2 14618.2 14816.6 14834.3 14868.6 14879.3 14092.4 14126.6 14151.9 14162.4 14183.8 902.1 908.0 912.1 912.1 932.7 913.0 914.5 917.5 917.8 921.5 967.1 967.5 972.7 972.7 980.7 959.8 963.7 966.3 969.4 978.5 721.6 723.1 723.9 727.1 727.2 13567.3 13650.7 13657.5 13683.8 13714.7 13346.5 13540.5 13610.3 13755.5 13807.9 10870.7 11050.6 11057.3 11129.0 11133.2 3769.2 3775.5 3794.7 3814.4 3866.7 52088.7 52147.0 52553.2 53226.2 53246.7 10502.3 10553.8 10568.9 10626.8 10628.0 18495.9 18666.3 18714.9 18739.0 18872.0 8073.3 8081.7 8237.4 8266.7 8270.7 4322.6 4369.7 4374.6 4406.7 4426.5 3390.1 3412.0 3414.6 3419.8 3433.8 614.7 615.2 636.0 637.0 641.3 667.8 669.9 682.9 711.6 717.0 8376.7 8765.7 9153.0 9287.9 9539.4 670.6 681.7 695.6 728.4 735.5 3877.5 3989.5 3993.9 4011.6 4077.9 56232.0 57402.4 57603.0 57914.0 58017.4 55292.0 55733.0 55759.2 56475.4 56786.1 49544.5 49584.0 50653.0 51044.2 51348.7 7706.8 7746.8 7999.2 8081.3 8118.8 35686.3 36310.6 36400.0 36447.8 36765.8 42403.0 43954.6 45494.8 45588.8 45700.0 10769.4 11021.8 11137.9 11196.2 11389.0 11291.9 11315.5 11343.7 11423.9 11439.2 10393.8 10510.8 10711.7 10870.9 11328.4 11102.0 11229.8 11363.8 11412.5 11667.9 10830.9 11214.6 11229.0 11480.2 11499.0 9918.5 9967.7 9980.7 9987.4 10117.5 9736.3 9840.9 9910.7 9911.3 9967.6 23:11:15 INFO - PROCESS | 15331 | -------- Summary: end -------- 23:11:15 INFO - PROCESS | 15331 | 23:11:15 INFO - TEST-INFO | 15331: exit 0 23:11:15 INFO - TEST-OK | dromaeo_css | took 1108389ms 23:11:15 INFO - TEST-START | kraken 23:11:15 INFO - operating with platform_type : linux_ 23:11:15 INFO - Initialising browser for kraken test... 23:11:15 INFO - TEST-INFO | started process 15572 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF02xZy/profile http://localhost:60635/getInfo.html) 23:11:19 INFO - PROCESS | 15572 | 23:11:19 INFO - PROCESS | 15572 | (process:15645): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:11:19 INFO - PROCESS | 15572 | __metrics Screen width/height:1600/1200 23:11:19 INFO - PROCESS | 15572 | colorDepth:24 23:11:19 INFO - PROCESS | 15572 | Browser inner width/height: 1024/697 23:11:19 INFO - PROCESS | 15572 | __metrics 23:11:20 ERROR - PROCESS | 15572 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 23:11:21 INFO - TEST-INFO | 15572: exit 0 23:11:21 INFO - Browser initialized. 23:11:21 INFO - Running cycle 1/1 for kraken test... 23:11:21 INFO - TEST-INFO | started process 15696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF02xZy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:11:22 INFO - PROCESS | 15696 | 23:11:22 INFO - PROCESS | 15696 | (process:15763): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:11:32 INFO - PROCESS | 15696 | RSS: Main: 151830528 23:11:32 INFO - PROCESS | 15696 | 23:11:35 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60635/tests/kraken/driver.html?testName=audio-beat-detection) 23:11:36 INFO - PROCESS | 15696 | RSS: Main: 156102656 23:11:36 INFO - PROCESS | 15696 | 23:11:38 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60635/tests/kraken/driver.html?testName=audio-dft) 23:11:39 INFO - PROCESS | 15696 | RSS: Main: 156127232 23:11:39 INFO - PROCESS | 15696 | 23:11:42 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60635/tests/kraken/driver.html?testName=audio-fft) 23:11:42 INFO - PROCESS | 15696 | RSS: Main: 156487680 23:11:42 INFO - PROCESS | 15696 | 23:11:44 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60635/tests/kraken/driver.html?testName=audio-oscillator) 23:11:45 INFO - PROCESS | 15696 | RSS: Main: 156381184 23:11:45 INFO - PROCESS | 15696 | 23:11:47 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60635/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:11:48 INFO - PROCESS | 15696 | RSS: Main: 156577792 23:11:48 INFO - PROCESS | 15696 | 23:11:51 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60635/tests/kraken/driver.html?testName=imaging-darkroom) 23:11:51 INFO - PROCESS | 15696 | RSS: Main: 156254208 23:11:51 INFO - PROCESS | 15696 | 23:11:55 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60635/tests/kraken/driver.html?testName=imaging-desaturate) 23:11:55 INFO - PROCESS | 15696 | RSS: Main: 156241920 23:11:55 INFO - PROCESS | 15696 | 23:11:58 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60635/tests/kraken/driver.html?testName=json-parse-financial) 23:11:59 INFO - PROCESS | 15696 | RSS: Main: 156119040 23:11:59 INFO - PROCESS | 15696 | 23:12:00 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60635/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:12:01 INFO - PROCESS | 15696 | RSS: Main: 156160000 23:12:01 INFO - PROCESS | 15696 | 23:12:03 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60635/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:12:03 INFO - PROCESS | 15696 | RSS: Main: 156475392 23:12:03 INFO - PROCESS | 15696 | 23:12:05 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60635/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:12:06 INFO - PROCESS | 15696 | RSS: Main: 156917760 23:12:06 INFO - PROCESS | 15696 | 23:12:08 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60635/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:12:09 INFO - PROCESS | 15696 | RSS: Main: 157757440 23:12:09 INFO - PROCESS | 15696 | 23:12:12 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60635/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:12:12 INFO - PROCESS | 15696 | RSS: Main: 157282304 23:12:12 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | Cycle 1(1): loaded http://localhost:60635/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60635/tests/kraken/driver.html?testName=ai-astar) 23:12:14 INFO - PROCESS | 15696 | RSS: Main: 158769152 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | __start_tp_report 23:12:14 INFO - PROCESS | 15696 | _x_x_mozilla_page_load 23:12:14 INFO - PROCESS | 15696 | _x_x_mozilla_page_load_details 23:12:14 INFO - PROCESS | 15696 | |i|pagename|runs| 23:12:14 INFO - PROCESS | 15696 | |0;ai-astar;112;108;110;110;111;123;110;111;111;111 23:12:14 INFO - PROCESS | 15696 | |1;audio-beat-detection;161;127;153;161;129;157;152;126;173;134 23:12:14 INFO - PROCESS | 15696 | |2;audio-dft;163;164;163;163;163;163;163;162;165;162 23:12:14 INFO - PROCESS | 15696 | |3;audio-fft;76;74;76;171;73;76;72;74;159;77 23:12:14 INFO - PROCESS | 15696 | |4;audio-oscillator;94;93;147;93;93;93;138;94;94;142 23:12:14 INFO - PROCESS | 15696 | |5;imaging-gaussian-blur;117;117;117;117;117;119;119;118;117;117 23:12:14 INFO - PROCESS | 15696 | |6;imaging-darkroom;147;147;147;147;146;146;148;147;147;146 23:12:14 INFO - PROCESS | 15696 | |7;imaging-desaturate;96;96;96;96;99;96;101;96;96;96 23:12:14 INFO - PROCESS | 15696 | |8;json-parse-financial;55;55;55;55;56;56;55;67;55;53 23:12:14 INFO - PROCESS | 15696 | |9;json-stringify-tinderbox;66;64;64;65;64;67;64;65;65;64 23:12:14 INFO - PROCESS | 15696 | |10;stanford-crypto-aes;85;85;80;80;79;78;80;80;79;79 23:12:14 INFO - PROCESS | 15696 | |11;stanford-crypto-ccm;115;114;114;119;117;117;116;114;117;115 23:12:14 INFO - PROCESS | 15696 | |12;stanford-crypto-pbkdf2;139;149;146;145;144;139;162;144;156;135 23:12:14 INFO - PROCESS | 15696 | |13;stanford-crypto-sha256-iterative;69;71;76;68;67;67;69;67;68;70 23:12:14 INFO - PROCESS | 15696 | __end_tp_report 23:12:14 INFO - PROCESS | 15696 | __start_cc_report 23:12:14 INFO - PROCESS | 15696 | _x_x_mozilla_cycle_collect,860 23:12:14 INFO - PROCESS | 15696 | __end_cc_report 23:12:14 INFO - PROCESS | 15696 | __startTimestamp1461478334572__endTimestamp 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | ------- Summary: start ------- 23:12:14 INFO - PROCESS | 15696 | Number of tests: 14 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#0] ai-astar Cycles:10 Average:111.70 Median:111.00 stddev:4.11 (3.7%) stddev-sans-first:4.36 23:12:14 INFO - PROCESS | 15696 | Values: 112.0 108.0 110.0 110.0 111.0 123.0 110.0 111.0 111.0 111.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#1] audio-beat-detection Cycles:10 Average:147.30 Median:155.00 stddev:16.87 (10.9%) stddev-sans-first:17.15 23:12:14 INFO - PROCESS | 15696 | Values: 161.0 127.0 153.0 161.0 129.0 157.0 152.0 126.0 173.0 134.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 23:12:14 INFO - PROCESS | 15696 | Values: 163.0 164.0 163.0 163.0 163.0 163.0 163.0 162.0 165.0 162.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#3] audio-fft Cycles:10 Average:92.80 Median:75.00 stddev:38.19 (50.9%) stddev-sans-first:40.02 23:12:14 INFO - PROCESS | 15696 | Values: 76.0 74.0 76.0 171.0 73.0 76.0 72.0 74.0 159.0 77.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#4] audio-oscillator Cycles:10 Average:108.10 Median:93.00 stddev:23.72 (25.5%) stddev-sans-first:24.61 23:12:14 INFO - PROCESS | 15696 | Values: 94.0 93.0 147.0 93.0 93.0 93.0 138.0 94.0 94.0 142.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.00 stddev:0.85 (0.7%) stddev-sans-first:0.88 23:12:14 INFO - PROCESS | 15696 | Values: 117.0 117.0 117.0 117.0 117.0 119.0 119.0 118.0 117.0 117.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 23:12:14 INFO - PROCESS | 15696 | Values: 147.0 147.0 147.0 147.0 146.0 146.0 148.0 147.0 147.0 146.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:96.00 stddev:1.75 (1.8%) stddev-sans-first:1.83 23:12:14 INFO - PROCESS | 15696 | Values: 96.0 96.0 96.0 96.0 99.0 96.0 101.0 96.0 96.0 96.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:55.00 stddev:3.88 (7.1%) stddev-sans-first:4.09 23:12:14 INFO - PROCESS | 15696 | Values: 55.0 55.0 55.0 55.0 56.0 56.0 55.0 67.0 55.0 53.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:1.03 (1.6%) stddev-sans-first:1.00 23:12:14 INFO - PROCESS | 15696 | Values: 66.0 64.0 64.0 65.0 64.0 67.0 64.0 65.0 65.0 64.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:80.00 stddev:2.46 (3.1%) stddev-sans-first:2.00 23:12:14 INFO - PROCESS | 15696 | Values: 85.0 85.0 80.0 80.0 79.0 78.0 80.0 80.0 79.0 79.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#11] stanford-crypto-ccm Cycles:10 Average:115.80 Median:116.50 stddev:1.69 (1.4%) stddev-sans-first:1.76 23:12:14 INFO - PROCESS | 15696 | Values: 115.0 114.0 114.0 119.0 117.0 117.0 116.0 114.0 117.0 115.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.90 Median:145.50 stddev:8.12 (5.6%) stddev-sans-first:8.22 23:12:14 INFO - PROCESS | 15696 | Values: 139.0 149.0 146.0 145.0 144.0 139.0 162.0 144.0 156.0 135.0 23:12:14 INFO - PROCESS | 15696 | 23:12:14 INFO - PROCESS | 15696 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.00 stddev:2.74 (4.0%) stddev-sans-first:2.91 23:12:14 INFO - PROCESS | 15696 | Values: 69.0 71.0 76.0 68.0 67.0 67.0 69.0 67.0 68.0 70.0 23:12:14 INFO - PROCESS | 15696 | -------- Summary: end -------- 23:12:14 INFO - PROCESS | 15696 | 23:12:15 INFO - TEST-INFO | 15696: exit 0 23:12:15 INFO - TEST-OK | kraken | took 59648ms 23:12:15 INFO - SUITE-END | took 1168s 23:12:15 INFO - Completed test suite (00:19:28) 23:12:15 INFO - javascript benchmark 23:12:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3284.753643476739, 3359.143418428301, 3390.169408494919, 3435.0553597760627, 3436.5704286964115, 5510.724463776815, 5537.529310008649, 5543.390227075029, 5564.387917329091, 5587.2177895094665, 5534.418886016971, 5538.501534861862, 5559.610827242094, 5573.0, 5576.191452239423, 5085.0592640361465, 5095.694258344502, 5157.252198431225, 5158.613104017195, 5167.870803229931, 4876.366072410582, 5029.597632189416, 5131.486851314876, 5181.637285390024, 5211.296474975868, 5088.363954505687, 5113.386393632769, 5163.044836705203, 5176.870578235536, 5203.1414816555225, 5166.715830629314, 5172.948270517284, 5193.740312984336, 5219.060569097561, 5255.237990491396, 4897.314375987365, 5121.795128194868, 5137.48625137487, 5172.353455818023, 5201.505856943622, 5286.577073834084, 5356.11624082026, 5362.51737343641, 5364.1149210380245, 5392.460753924615, 5963.224780778471, 5980.551458640576, 6012.248468941382, 6018.939810601925, 6058.091286307045, 5862.237909071793, 5971.761129554812, 6046.455818976313, 6057.727402266909, 6078.088286756978, 5636.323641163087, 5648.209250704905, 5649.435056494359, 5654.151877218368, 5681.715914204273, 6066.575339726265, 6092.969535152384, 6101.908471372933, 6103.38966103395, 6134.4172303631085, 5732.598718089694, 5901.468867801918, 5905.734241959122, 5920.940790592125, 5957.821265362046, 5958.851028724316, 5959.28488581373, 5961.7615295387695, 5962.791302304398, 5973.163757074013, 5904.911426328608, 5912.142739302863, 5944.613600115978, 5967.283925928917, 5978.342382337939, 5807.0998995155815, 5897.823065308047, 5953.940460595425, 5960.940390596125, 5981.5214782817275, 4549.476810166807, 4552.840650577214, 4563.247064234364, 4564.42944631921, 4577.885552861205, 4104.692148088905, 4122.175564886975, 4124.8143833527565, 4128.236276288896, 4152.43942067823, 5576.0, 5724.627899186539, 5795.971020144872, 5802.47777700009, 5821.534277257894, 5843.503302219274, 5890.646561206341, 5928.851778705567, 5959.135925290895, 5973.074173503114, 5562.9443705563235, 5711.457411545896, 5717.8570535736935, 5745.253117094834, 5746.827595172152, 5061.190209566452, 5099.8725031873755, 5124.205748109049, 5166.199239117943, 5172.146595811649, 5985.820425387245, 5985.850353741192, 5996.550258730613, 6045.274567051982, 6061.060535616986, 5814.127880817742, 5888.440598143169, 5998.430149135738, 6005.819825405245, 6013.188219590299, 6111.8956224267595, 6128.295246046583, 6136.110264011604, 6157.753689852356, 6174.969125154345, 5844.298684157929, 5846.41535846412, 5858.326292476249, 5859.267591551056, 5888.558358123244, 5740.225069615549, 5943.316518600416, 5950.732217050157, 5985.910211346921, 6067.605605635619, 5913.674747888907, 5918.940810592011, 5920.348761636303, 5923.289205295392, 5925.525957923442, 5827.825165245049, 5853.590248682552, 5865.296164460292, 5901.645901245939, 5913.5564832636865, 5543.196236545759, 5779.133130030361, 5780.942190578208, 5842.591018628656, 5858.2677165354335, 5839.766409343579, 5856.824295271149, 5875.559333049953, 5904.61619994699, 5911.142884281841, 5770.451807078321, 5780.132980053026, 5786.971065144648, 5791.739371728367, 5798.884022319446], "unit": "score", "name": "dojo.html", "value": 5515.799279024345}, {"lowerIsBetter": false, "replicates": [514.2671692837707, 564.1903867949493, 568.866316415642, 581.680075958223, 592.9889538337136, 41303.79348103255, 41453.58546414535, 41543.79228103859, 41614.37578436318, 41695.37456938145, 43874.0, 44442.7777861111, 44536.55463445365, 44629.33056004154, 44697.329540056846, 8836.823263534725, 9032.412893161956, 9042.186203241707, 9051.094890510962, 9064.637414503444, 9027.774305642346, 9133.269338452908, 9164.083591640816, 9197.21823644991, 9197.540122993856, 9304.441733495978, 9379.0, 9393.389429687048, 9415.435073895589, 9428.622855085789, 9300.39547429415, 9326.72019839406, 9348.065193480632, 9359.391639543408, 9402.106799854071, 9095.363324567283, 9228.907710922938, 9233.0, 9243.214326782232, 9259.351845370828, 43899.78050109761, 44554.0, 44566.55433445657, 44599.331010034875, 44680.3297950531, 3183.570218020561, 3369.4777309517062, 3500.9824950875336, 3690.5940346561856, 3785.015895867067, 29456.0, 29751.5537266939, 30002.399952001055, 30026.699733002828, 30309.393812123635, 4149.585041495857, 4213.136307057046, 4215.662746980234, 4249.766262855542, 4283.657307415399, 207276.0, 207779.0, 208291.0, 209128.0, 209531.0, 46497.30254046192, 47127.76436117797, 64983.675081624286, 65993.34006659967, 66004.33995659983, 45956.310655340196, 46076.76961615238, 46278.76860615743, 46484.302735458994, 46526.0, 16078.115703636178, 16098.919505402633, 16296.511104666879, 16308.184590770414, 16315.59211019734, 12958.416871240817, 12986.935065324613, 13004.479820807252, 13020.414081366362, 13020.544280950122, 15651.139187344577, 15986.120763357894, 16055.438059667855, 16153.19234038317, 16195.19024048793, 6151.323354431009, 6207.503399728011, 6278.686065696788, 6290.937090629127, 6295.559310848196, 1033.540074666766, 1042.0361165921493, 1102.7849569334064, 1141.0814294493014, 1171.2035815645272, 30598.0, 30897.0, 31138.377232455223, 31188.0, 31389.37221255562, 1114.3815182573587, 1152.3201311226417, 1162.587281515067, 1168.0246993760306, 1206.3063738350484, 14995.400183992737, 15088.924555377153, 15106.924465377822, 15239.847601524065, 15340.15629140408, 339200.0, 518410.0, 533796.0, 546306.0, 546862.0, 62750.37249627537, 63283.36716632775, 63292.36707632865, 63583.682081590225, 63727.6813615929, 1510.5241848817586, 1520.9239538023055, 1524.2759689147788, 1528.3657282227973, 1539.961500962485, 32448.188795280308, 32875.0, 32878.17804554905, 33004.33991320208, 33712.820051298084, 51237.48762512476, 51967.74016129895, 52008.73995629998, 52060.0, 52124.0, 274076.0, 274437.0, 274571.0, 275026.0, 276490.0, 35756.64243357637, 36025.45961810627, 50999.0, 51925.48074519358, 51962.480375195766, 47912.0, 49147.262791057445, 49646.25530616971, 49816.75091624519, 50448.49551504438, 43652.56347436612, 43737.0, 44409.555904440545, 44803.32795008012, 45288.0942381144, 41757.58242417537, 41814.581854181066, 42189.0, 42898.35652465153, 43642.12715745731, 42990.78504607582, 43314.56685433105, 43423.34864977091, 43656.78171609249, 43722.34416483692, 37493.4375984355, 38039.61960380361, 38376.0, 38884.2223155541, 39051.0, 29229.97695080747, 29287.560686590143, 29563.556546651387, 29846.253843653212, 29909.252268692588], "unit": "score", "name": "ext.html", "value": 19772.405116666792}, {"lowerIsBetter": false, "replicates": [371.28898160023556, 376.918962423079, 382.300390285777, 387.932111880421, 394.960503949605, 13061.67345816355, 13348.199108053524, 17746.378876739316, 18880.0, 19404.126814293355, 18445.538861528436, 18839.24643014278, 19003.52491187721, 19101.904490477562, 19169.233230670758, 6191.133241346204, 6240.40716131969, 6457.903131453032, 6490.0, 6492.188476440445, 6392.16901802765, 6459.128017717619, 6475.967620161892, 6477.287498375175, 6515.0227465880025, 6240.906386404207, 6444.387783160592, 6457.483401327906, 6516.771912983047, 6521.7282629887195, 6393.392627700361, 6456.289808121103, 6463.482921366303, 6464.418202361763, 6491.415772580467, 6018.608790428608, 6226.87546249077, 6260.186175797163, 6266.561340706153, 6273.937260627426, 24947.5010499789, 25261.87369063161, 25316.113936012334, 25547.48905021889, 25595.0, 2457.7296497385273, 2495.251424572621, 2508.134693530729, 2659.175655546787, 3031.4846476098896, 14960.700785984329, 15053.473128440452, 15205.239738013055, 15388.384464621402, 15466.9226653866, 1997.9300724474574, 2056.4036429435446, 2057.1360028787944, 2078.532330225702, 2088.488320361506, 65694.0, 78463.0, 80373.0, 81756.59121704353, 82321.0, 13309.600711978655, 15458.922705386625, 15918.9204053979, 16148.35406583747, 16338.591535211715, 13596.320183990762, 14221.0, 28044.859775700992, 28107.156785296065, 28169.43661126766, 6992.650367481606, 7127.5010749248095, 7370.557766533918, 7386.59373734439, 7546.245375462519, 7020.5787652741, 7073.115860517435, 7082.681279342442, 7162.570245785269, 7198.064251647253, 4315.741055536678, 4428.734275943454, 4456.153330867125, 4463.509014008457, 4473.194824931478, 2004.7093171490053, 2020.8383329333617, 2066.628006958762, 2067.374434468217, 2122.564874200785, 494.76004137993044, 986.3933680786265, 1031.7884833609091, 1035.694470131313, 1109.0074383426788, 11748.118891083202, 12097.75804483923, 12195.634130976085, 12306.0, 12371.814422783666, 487.9024195160982, 499.4605825708226, 501.07051419616084, 501.64633933077096, 516.9379674439091, 5941.821745347646, 6689.896167132466, 6740.528163028541, 6743.35938085881, 6769.966150169218, 451411.0, 455069.0, 456272.0, 463746.0, 485624.0, 80322.19677803147, 82609.17390826135, 83519.0, 84545.0, 84555.57722211472, 531.7952588253551, 1028.1774580335702, 1054.1566746602841, 1059.6079450603327, 1075.7467550303966, 16190.838091618933, 16497.505074847773, 16679.332826686797, 16702.58243543921, 16816.57059149937, 27027.459450810482, 33320.66679333176, 35203.64796352004, 35438.82280588683, 37892.62107379001, 44668.0, 46283.76858115823, 47448.525514745794, 49702.502974969786, 49932.0, 8335.916640833757, 8364.079951205242, 8472.322214222724, 8546.829063418822, 8620.568971551524, 11922.225055371373, 12378.690532736753, 12408.81386779217, 12427.005839532634, 12514.436850341574, 10487.580496780043, 10698.358098514114, 10737.194710396907, 10802.513886874953, 10938.781224375309, 8125.537403267469, 8813.691520796741, 10421.531031103466, 10469.842952355568, 10779.622713205, 10660.680179594625, 10661.147108231478, 10704.464776761286, 10818.188635852186, 11256.212065155689, 8393.664253429795, 8398.790030249293, 8403.873941890999, 8454.323654107533, 8479.703210387606, 8226.588670566567, 10389.480525973822, 10441.477926103817, 10490.0, 10495.370277783357], "unit": "score", "name": "jquery.html", "value": 8847.55359834318}, {"lowerIsBetter": false, "replicates": [1595.2263152371102, 1614.1283706798329, 1853.406909788868, 1931.652302585534, 1986.6424043672143, 7786.493877897933, 8485.745427637165, 8630.093840146781, 8813.162749538791, 8859.822803543926, 8591.097934716858, 8596.183362580545, 8679.305655547556, 8699.869501957475, 8712.433691810027, 2491.9999999999955, 2498.6252062190724, 2514.962275565868, 2515.4845154845157, 2520.0549793827313, 2411.05968672218, 2496.4133428644254, 2529.911453099142, 2530.7595778401023, 2538.23852844146, 2531.088808029108, 2534.8859301331486, 2539.898404063835, 2541.809364297685, 2547.681539807524, 2488.241086468617, 2528.380546766045, 2528.7471252874657, 2531.607600821857, 2540.3395117269456, 2455.693038370204, 2481.2308184462872, 2500.1249562653106, 2527.658766066594, 2530.962035569468, 8884.511351875644, 8937.46375217489, 8944.64221431142, 8987.37088403812, 8987.955060224656, 5511.173324001392, 5604.103343465026, 5626.071698169797, 5631.436856314377, 5641.71791410432, 3389.1865952171547, 3400.5579274694132, 3411.198368383428, 3461.3423449544757, 3472.2708231271463, 1086.1907878630393, 1098.555085190499, 1114.5430373546808, 1120.199057673759, 1130.949107290174, 12598.874011259863, 12610.81083783744, 12648.74702505963, 12651.367431628381, 12660.936695316464, 4645.837395691202, 4737.881552961135, 4788.784504697227, 4820.831270905502, 4896.36347274852, 10688.572457101784, 10704.625338113128, 10705.678829635124, 10707.089897358655, 10724.946375268073, 5427.0, 5490.1764735289225, 5491.533219676372, 5514.448555144534, 5519.39286678465, 4397.406350142754, 4899.755012249373, 4972.8756781080765, 5061.670991385548, 5097.46476619957, 4099.3149487086, 4102.405151253051, 4112.465379500646, 4114.897127571775, 4138.420621113047, 1352.3576301256824, 1362.7002059546883, 1366.187118664393, 1370.3148425787106, 1380.675541247806, 1426.436557559758, 1481.9555413337616, 1499.5801175670829, 1500.174903802904, 1531.2267305011121, 8443.28232100266, 8464.576771161417, 8481.27909127731, 8496.447730897471, 8498.490090594583, 557.0029646932028, 567.7019564728467, 568.4145330309788, 578.6961845031308, 579.8724280658248, 6457.902156633289, 6473.482121430275, 6535.052417399451, 6562.868742625121, 6569.901451478232, 10803.513841877135, 11183.273087249303, 11261.549538018551, 11288.71778205535, 11295.0, 10798.082163016075, 10976.835347469794, 11087.55649774, 11103.44482775858, 11208.607698730668, 8523.65905363791, 8554.828903421896, 8556.401051926305, 8578.270846977952, 8585.399022068521, 8227.876581851397, 8252.546109964009, 8326.167383261625, 8409.159084091541, 8413.70552030676, 9763.902360976299, 9778.804423911339, 9794.16749576294, 9895.802083958424, 9959.601615935282, 9978.501074946369, 10110.646127385506, 10110.898891010997, 10287.43419111956, 10302.433366164933, 7678.731244406419, 7993.280604745368, 8063.153368896416, 8281.461704989157, 8304.128066553167, 8463.449875758057, 8465.195806398382, 8466.111058338785, 8473.0, 8474.364422668448, 7548.66031028616, 7621.352185064332, 7690.884636730341, 7713.691452341844, 7845.254700803414, 7600.619969001638, 7652.196519365609, 7772.572508512086, 7773.067231932205, 7800.531968081933, 8213.383996200428, 8256.958715206385, 8264.256216940505, 8288.046874609256, 8293.83412331738, 7232.963835180791, 7351.816204594714, 7415.629218538944, 7428.665710043169, 7428.73999410018, 7570.9242907572425, 7571.772846814604, 7588.317051465395, 7599.430042746705, 7602.885956710543], "unit": "score", "name": "mootools.html", "value": 5037.904827494767}, {"lowerIsBetter": false, "replicates": [376.12175570043945, 376.25689263704186, 380.42935596605093, 382.296574303282, 384.9980750096251, 6402.871942561146, 6403.231612206536, 6425.90361144582, 6469.256035555906, 6469.70886310116, 6339.7464101436, 6343.460805831498, 6357.61854288743, 6359.491240700744, 6364.586301890386, 4740.383750112498, 4894.8286809961655, 4925.70445773253, 4936.432310284327, 4942.406911170656, 4873.488283730193, 4885.682430642015, 4912.336834527328, 4923.138450771118, 4925.039617274633, 4879.487358918746, 4895.828645997391, 4900.362952816146, 4900.803967841282, 4910.729909854975, 4901.09329773993, 4915.115279249733, 4920.261960705887, 4921.704697718148, 4933.1120398328285, 4300.849470268526, 4306.698531102824, 4310.698251122422, 4314.439122914033, 4318.5465526119615, 6071.51427885768, 6121.5102791777, 6130.4176103270565, 6133.539984501181, 6135.141080248769, 5554.9722251388885, 5726.828195154152, 5728.770849166029, 5729.226554415142, 5743.16724075011, 6672.332766723337, 6758.864822703518, 6773.085633439472, 6778.525503214777, 6801.693923773442, 4703.858884233478, 4718.528147185289, 4747.240441529339, 4748.667593268473, 4751.833685821014, 7048.75329363475, 7063.293670632947, 7069.399101076363, 7104.928950710479, 7105.32499412555, 4758.690685105457, 4760.404949381327, 4822.348982887265, 4837.22604383304, 4841.12859685253, 4884.682495637843, 4886.096072226649, 4895.118878601886, 4896.045271172158, 4914.950850491521, 5640.351359593617, 5681.403452637496, 5698.686572238566, 5708.40061793514, 5712.285964254468, 6859.0, 6862.382385585322, 6866.279040700654, 6874.828129296808, 6877.449804015667, 1730.230047628793, 1742.9564260893578, 1747.1700942052678, 1754.6929287374658, 1754.8332908373684, 1109.916756243285, 1120.8094623913955, 1141.2068612314529, 1150.2523359816187, 1156.5373850459714, 6563.1796025496815, 6565.409113179837, 6572.07990881277, 6579.046038324512, 6583.572067815577, 6988.825279368057, 7037.718491260394, 7075.39859111971, 7096.112985876765, 7114.715411383487, 6569.57297775643, 6570.5729127606555, 6571.868562628721, 6573.80278591643, 6588.934110658832, 3798.0504873781556, 3815.523059617548, 3815.637514436124, 3836.865709700147, 3840.7887566184127, 7175.89236161458, 7386.704531818775, 7406.629668516553, 7421.851562968711, 7438.0, 7254.238304978006, 7325.633718314063, 7343.192248852622, 7350.4119670426235, 7352.080989876265, 7421.220771818989, 7432.814179645444, 7435.1821299656995, 7438.0, 7446.069241344832, 6977.825554361181, 7049.259827718016, 7102.431805455654, 7114.822129446805, 7137.785866424016, 7061.71753129869, 7084.752033678797, 7102.076730025064, 7130.0, 7138.250483699137, 7361.079865016873, 7361.411087113125, 7371.59456229891, 7373.225811289737, 7450.701971921062, 4871.244957031666, 4908.214685650279, 4913.115639184981, 4919.040787046635, 4927.605791536596, 6923.5499692519825, 7117.822054448473, 7133.964330178316, 7137.10786151731, 7142.9642851787485, 6887.139107611549, 6898.965505172442, 6901.0, 6913.308669133247, 6940.757073502403, 6894.8621027580175, 6895.862082758417, 6898.068760717239, 6902.482313826584, 6921.546475240256, 6739.224989126314, 6742.898856517254, 6749.797506074826, 6753.797386078426, 6764.120664313607, 6735.326467353226, 6874.621895795779, 6950.165980082423, 6980.546264492792, 6995.160580730141, 6721.260661327348, 6758.4931130166415, 6771.085903402977, 6782.050512928293, 6805.353491418307], "unit": "score", "name": "prototype.html", "value": 5176.314586117332}, {"lowerIsBetter": false, "replicates": [792.2275781113415, 807.7415227127319, 807.850547648685, 815.0056930544733, 836.1596595421601, 14618.195999220039, 14816.6295842604, 14834.332455039523, 14868.553943381688, 14879.256037198124, 14092.436302547885, 14126.64683382916, 14151.858481415184, 14162.362693678784, 14183.78724319136, 902.1114202510522, 907.9773005674845, 912.0560220172123, 912.1380295620642, 932.6875496708611, 912.9863052054225, 914.4696076275777, 917.4541148016953, 917.7705573606598, 921.4839689773714, 967.110258562121, 967.5355829201987, 972.6644307713827, 972.7324985628974, 980.7253968888723, 959.8464245720686, 963.7253382785942, 966.2559828931753, 969.3795970578835, 978.4520668425669, 721.5850885740667, 723.0816862584488, 723.9493235473519, 727.0729819480152, 727.1637616740737, 13567.253801040939, 13650.658733531642, 13657.521986730517, 13683.794743078863, 13714.725705485835, 13346.466141354334, 13540.526081587195, 13610.319484025758, 13755.518556850462, 13807.861921380758, 10870.72823179419, 11050.557977680883, 11057.336559806437, 11129.0, 11133.220674552784, 3769.227308401771, 3775.452559378875, 3794.677452416548, 3814.408766641175, 3866.671332936704, 52088.739556301975, 52147.0, 52553.211701824504, 53226.20160697593, 53246.73376633092, 10502.264841461025, 10553.84169237462, 10568.947155264175, 10626.787464250827, 10627.9903409176, 18495.90752046231, 18666.253349866125, 18714.906425467787, 18739.0, 18872.0, 8073.31376832964, 8081.676732930617, 8237.423380363318, 8266.710665126691, 8270.669173233124, 4322.610965013164, 4369.737815731066, 4374.62815660666, 4406.713563618355, 4426.490953540319, 3390.118569172033, 3412.0446275043028, 3414.573178352706, 3419.811910344955, 3433.7939723616664, 614.6557927560491, 615.227888999303, 635.9936400636027, 637.0221709675626, 641.3394203969825, 667.7562689618254, 669.8693754717783, 682.8941514065327, 711.6228398948462, 717.0248462091552, 8376.66146397718, 8765.737027889174, 9152.954235228914, 9287.860682089775, 9539.379940303857, 670.6076944987237, 681.7307163670323, 695.6417445015821, 728.4099879097947, 735.5219107580116, 3877.495925529663, 3989.5212574491247, 3993.880183594497, 4011.5787842276723, 4077.918441631151, 56232.0, 57402.425975740545, 57603.0, 57914.0, 58017.41982580205, 55292.0, 55733.0, 55759.21203939868, 56475.435245647015, 56786.14820777692, 49544.50455495399, 49584.0, 50653.0, 51044.23433648498, 51348.74325628423, 7706.845863082819, 7746.767596972099, 7999.160088190656, 8081.313088387553, 8118.75643730689, 35686.28627427385, 36310.636893630726, 36400.0, 36447.81776091103, 36765.81617091897, 42403.0, 43954.56045439505, 45494.77252613716, 45588.7720561395, 45700.0, 10769.40768257722, 11021.779564408507, 11137.888621114009, 11196.160287978271, 11389.031932285518, 11291.887081129413, 11315.490802913724, 11343.716407089889, 11423.885761142283, 11439.199256052329, 10393.792124157626, 10510.789784204426, 10711.732206694895, 10870.891291087304, 11328.376939268419, 11102.0, 11229.775404492028, 11363.772724545628, 11412.48643811047, 11667.883321167019, 10830.945845270724, 11214.55141794319, 11229.0, 11480.196386252883, 11499.0, 9918.504074796376, 9967.700968970943, 9980.650677226262, 9987.350822196535, 10117.494125293853, 9736.318457707892, 9840.901590983998, 9910.702678919644, 9911.306208565331, 9967.60129594837], "unit": "score", "name": "yui.html", "value": 6633.008381844843}], "name": "dromaeo_css", "value": 7420.236579428377}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 108.0, 110.0, 110.0, 111.0, 123.0, 110.0, 111.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.7}, {"lowerIsBetter": true, "replicates": [161.0, 127.0, 153.0, 161.0, 129.0, 157.0, 152.0, 126.0, 173.0, 134.0], "unit": "score", "name": "audio-beat-detection", "value": 147.3}, {"lowerIsBetter": true, "replicates": [163.0, 164.0, 163.0, 163.0, 163.0, 163.0, 163.0, 162.0, 165.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.1}, {"lowerIsBetter": true, "replicates": [76.0, 74.0, 76.0, 171.0, 73.0, 76.0, 72.0, 74.0, 159.0, 77.0], "unit": "score", "name": "audio-fft", "value": 92.8}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 147.0, 93.0, 93.0, 93.0, 138.0, 94.0, 94.0, 142.0], "unit": "score", "name": "audio-oscillator", "value": 108.1}, {"lowerIsBetter": true, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 119.0, 119.0, 118.0, 117.0, 117.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 117.5}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 148.0, 147.0, 147.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.8}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 99.0, 96.0, 101.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.8}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 55.0, 55.0, 56.0, 56.0, 55.0, 67.0, 55.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [66.0, 64.0, 64.0, 65.0, 64.0, 67.0, 64.0, 65.0, 65.0, 64.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.8}, {"lowerIsBetter": true, "replicates": [85.0, 85.0, 80.0, 80.0, 79.0, 78.0, 80.0, 80.0, 79.0, 79.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.5}, {"lowerIsBetter": true, "replicates": [115.0, 114.0, 114.0, 119.0, 117.0, 117.0, 116.0, 114.0, 117.0, 115.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 115.8}, {"lowerIsBetter": true, "replicates": [139.0, 149.0, 146.0, 145.0, 144.0, 139.0, 162.0, 144.0, 156.0, 135.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 145.9}, {"lowerIsBetter": true, "replicates": [69.0, 71.0, 76.0, 68.0, 67.0, 67.0, 69.0, 67.0, 68.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.2}], "name": "kraken", "value": 1516.5}]} 23:12:15 INFO - Return code: 0 23:12:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:12:15 ERROR - # TBPL SUCCESS # 23:12:15 INFO - Running post-action listener: _resource_record_post_action 23:12:15 INFO - Running post-run listener: _resource_record_post_run 23:12:16 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 501137408; Read time: 12; Write time: 440392 23:12:16 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 245100544; Read time: 0; Write time: 286596 23:12:16 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 4096; Write bytes: 255852544; Read time: 12; Write time: 153772 23:12:16 INFO - Running post-run listener: _upload_blobber_files 23:12:16 INFO - Blob upload gear active. 23:12:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:12:16 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 23:12:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:12:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:12:17 INFO - (blobuploader) - INFO - Open directory for files ... 23:12:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:12:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:12:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:12:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:12:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:12:18 INFO - (blobuploader) - INFO - Done attempting. 23:12:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:12:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:12:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:12:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:12:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:12:18 INFO - (blobuploader) - INFO - Done attempting. 23:12:18 INFO - (blobuploader) - INFO - Iteration through files over. 23:12:18 INFO - Return code: 0 23:12:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:12:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:12:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2210684ffe1e566efdfff879174243b18efd87a0facae2452640b2b3ee1976e54d402fcb2a649e24722a443d32048bdbe12c020911d942307771b9742d7c899", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a639e0ebafc41ca533d7e19d89574342bb1f97bbc1cc3f90e7f9406911258b63d7b79d57871604aeb3c00e4ec48240e0ea3e75666b4da4e8aaa6efe6e28281b6"} 23:12:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:12:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:12:18 INFO - Contents: 23:12:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2210684ffe1e566efdfff879174243b18efd87a0facae2452640b2b3ee1976e54d402fcb2a649e24722a443d32048bdbe12c020911d942307771b9742d7c899", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a639e0ebafc41ca533d7e19d89574342bb1f97bbc1cc3f90e7f9406911258b63d7b79d57871604aeb3c00e4ec48240e0ea3e75666b4da4e8aaa6efe6e28281b6"} 23:12:18 INFO - Running post-run listener: copy_logs_to_upload_dir 23:12:18 INFO - Copying logs to upload dir... 23:12:18 INFO - mkdir: /builds/slave/test/build/upload/logs 23:12:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.724088 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-04-23 23:12:16.150677) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-23 23:12:16.154402) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2210684ffe1e566efdfff879174243b18efd87a0facae2452640b2b3ee1976e54d402fcb2a649e24722a443d32048bdbe12c020911d942307771b9742d7c899", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a639e0ebafc41ca533d7e19d89574342bb1f97bbc1cc3f90e7f9406911258b63d7b79d57871604aeb3c00e4ec48240e0ea3e75666b4da4e8aaa6efe6e28281b6"} build_url:https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011494 build_url: 'https://queue.taskcluster.net/v1/task/NbhB-bobSEiQiAq4uhPxJQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2210684ffe1e566efdfff879174243b18efd87a0facae2452640b2b3ee1976e54d402fcb2a649e24722a443d32048bdbe12c020911d942307771b9742d7c899", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a639e0ebafc41ca533d7e19d89574342bb1f97bbc1cc3f90e7f9406911258b63d7b79d57871604aeb3c00e4ec48240e0ea3e75666b4da4e8aaa6efe6e28281b6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-23 23:12:16.179559) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 23:12:16.179933) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1461475805.149122-48372746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004620 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 23:12:16.263791) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-23 23:12:16.264104) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-23 23:12:16.264383) ========= ========= Total master_lag: 0.15 =========