builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-010 starttime: 1468192561.76 results: success (0) buildid: 20160710132925 builduid: 3736ed9763b34a63a290d9069e82464e revision: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:01.756651) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:01.757178) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:01.757499) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:01.799527) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:01.799836) ========= 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=eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-10 16:16:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.5M=0s 2016-07-10 16:16:02 (40.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109063 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:01.924176) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:01.924514) ========= 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=eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017714 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:01.976229) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:01.976596) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e --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=eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-10 16:16:02,120 truncating revision to first 12 chars 2016-07-10 16:16:02,120 Setting DEBUG logging. 2016-07-10 16:16:02,120 attempt 1/10 2016-07-10 16:16:02,120 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ebd3919e8a3a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-10 16:16:02,654 unpacking tar archive at: mozilla-beta-ebd3919e8a3a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.842753 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:02.832586) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:02.832900) ========= script_repo_revision: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:02.833320) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:02.833588) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 16:16:02.844471) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-10 16:16:02.844848) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:16:03 INFO - MultiFileLogger online at 20160710 16:16:03 in /builds/slave/test 16:16:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:16:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:16:03 INFO - {'append_to_log': False, 16:16:03 INFO - 'base_work_dir': '/builds/slave/test', 16:16:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:16:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:16:03 INFO - 'branch': 'Mozilla-Beta', 16:16:03 INFO - 'buildbot_json_path': 'buildprops.json', 16:16:03 INFO - 'config_files': ('talos/linux_config.py',), 16:16:03 INFO - 'default_actions': ('clobber', 16:16:03 INFO - 'read-buildbot-config', 16:16:03 INFO - 'download-and-extract', 16:16:03 INFO - 'populate-webroot', 16:16:03 INFO - 'create-virtualenv', 16:16:03 INFO - 'install', 16:16:03 INFO - 'run-tests'), 16:16:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:16:03 INFO - 'download_minidump_stackwalk': True, 16:16:03 INFO - 'download_symbols': 'ondemand', 16:16:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:16:03 INFO - 'tooltool.py': '/tools/tooltool.py', 16:16:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:16:03 INFO - '/tools/misc-python/virtualenv.py')}, 16:16:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:16:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:16:03 INFO - 'installer_path': 'installer.exe', 16:16:03 INFO - 'log_level': 'info', 16:16:03 INFO - 'log_name': 'talos', 16:16:03 INFO - 'log_to_console': True, 16:16:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:16:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:16:03 INFO - 'opt_config_files': (), 16:16:03 INFO - 'pip_index': False, 16:16:03 INFO - 'sps_profile': False, 16:16:03 INFO - 'sps_profile_interval': 0, 16:16:03 INFO - 'suite': 'dromaeojs-e10s', 16:16:03 INFO - 'system_bits': '32', 16:16:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:16:03 INFO - 'title': 'talos-linux64-ix-010', 16:16:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:16:03 INFO - 'use_talos_json': True, 16:16:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:16:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:16:03 INFO - 'work_dir': 'build'} 16:16:03 INFO - ##### 16:16:03 INFO - ##### Running clobber step. 16:16:03 INFO - ##### 16:16:03 INFO - Running pre-action listener: _resource_record_pre_action 16:16:03 INFO - Running main action method: clobber 16:16:03 INFO - rmtree: /builds/slave/test/build 16:16:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:16:03 INFO - Running post-action listener: _resource_record_post_action 16:16:03 INFO - ##### 16:16:03 INFO - ##### Running read-buildbot-config step. 16:16:03 INFO - ##### 16:16:03 INFO - Running pre-action listener: _resource_record_pre_action 16:16:03 INFO - Running main action method: read_buildbot_config 16:16:03 INFO - Using buildbot properties: 16:16:03 INFO - { 16:16:03 INFO - "project": "", 16:16:03 INFO - "product": "firefox", 16:16:03 INFO - "script_repo_revision": "production", 16:16:03 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 16:16:03 INFO - "repository": "", 16:16:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 16:16:03 INFO - "buildid": "20160710132925", 16:16:03 INFO - "pgo_build": "True", 16:16:03 INFO - "basedir": "/builds/slave/test", 16:16:03 INFO - "buildnumber": 43, 16:16:03 INFO - "slavename": "talos-linux64-ix-010", 16:16:03 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:16:03 INFO - "platform": "ubuntu64_hw", 16:16:03 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:16:03 INFO - "branch": "mozilla-beta", 16:16:03 INFO - "repo_path": "releases/mozilla-beta", 16:16:03 INFO - "revision": "ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e", 16:16:03 INFO - "stage_platform": "linux64-pgo", 16:16:03 INFO - "builduid": "3736ed9763b34a63a290d9069e82464e", 16:16:03 INFO - "slavebuilddir": "test" 16:16:03 INFO - } 16:16:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2. 16:16:03 INFO - Running post-action listener: _resource_record_post_action 16:16:03 INFO - ##### 16:16:03 INFO - ##### Running download-and-extract step. 16:16:03 INFO - ##### 16:16:03 INFO - Running pre-action listener: _resource_record_pre_action 16:16:03 INFO - Running main action method: download_and_extract 16:16:03 INFO - mkdir: /builds/slave/test/build/tests 16:16: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')]} 16:16:03 INFO - https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:16:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 16:16:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 16:16:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 16:16:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:16:11 INFO - Downloaded 1327 bytes. 16:16:11 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 16:16:11 INFO - Using the following test package requirements: 16:16:11 INFO - {u'common': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 16:16:11 INFO - u'cppunittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 16:16:11 INFO - u'firefox-48.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:16:11 INFO - u'gtest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 16:16:11 INFO - u'firefox-48.0.en-US.linux-x86_64.gtest.tests.zip'], 16:16:11 INFO - u'jittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 16:16:11 INFO - u'jsshell-linux-x86_64.zip'], 16:16:11 INFO - u'mochitest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 16:16:11 INFO - u'firefox-48.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:16:11 INFO - u'mozbase': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 16:16:11 INFO - u'reftest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 16:16:11 INFO - u'firefox-48.0.en-US.linux-x86_64.reftest.tests.zip'], 16:16:11 INFO - u'talos': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 16:16:11 INFO - u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'], 16:16:11 INFO - u'web-platform': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 16:16:11 INFO - u'firefox-48.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:16:11 INFO - u'xpcshell': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 16:16:11 INFO - u'firefox-48.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:16:11 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:11 INFO - https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:16:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 16:16:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 16:16:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 16:16:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:16:17 INFO - Downloaded 24237140 bytes. 16:16:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:16:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:16:18 INFO - Return code: 0 16:16:18 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:16:18 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')]} 16:16:18 INFO - https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:16:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 16:16:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 16:16:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 16:16:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:16:20 INFO - Downloaded 24237140 bytes. 16:16:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:16:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:16:21 INFO - Return code: 0 16:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:21 INFO - https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:16:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 16:16:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 16:16:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 16:16:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:16:22 INFO - Downloaded 10840145 bytes. 16:16:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:16:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:16:22 INFO - Return code: 0 16:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:22 INFO - https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:16:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 16:16:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 16:16:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 16:16:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:16:24 INFO - Downloaded 56024629 bytes. 16:16:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 16:16:24 INFO - mkdir: /builds/slave/test/properties 16:16:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:16:24 INFO - Writing to file /builds/slave/test/properties/build_url 16:16:24 INFO - Contents: 16:16:24 INFO - build_url:https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 16:16:25 INFO - Running post-action listener: _resource_record_post_action 16:16:25 INFO - Running post-action listener: set_extra_try_arguments 16:16:25 INFO - ##### 16:16:25 INFO - ##### Running populate-webroot step. 16:16:25 INFO - ##### 16:16:25 INFO - Running pre-action listener: _resource_record_pre_action 16:16:25 INFO - Running main action method: populate_webroot 16:16:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:16:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:16:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:16:25 INFO - '--tppagecycles', 16:16:25 INFO - '7'], 16:16:25 INFO - 'tests': ['tsvgm']}}, 16:16:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:16:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:16:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:16:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:16:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:16:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:16:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:25 INFO - 'talos_options': ['--disable-e10s'], 16:16:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:25 INFO - 'talos_options': ['--disable-e10s'], 16:16:25 INFO - 'tests': ['damp', 'tps']}, 16:16:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:25 INFO - 'tests': ['damp', 'tps']}, 16:16:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:16:25 INFO - 'tests': ['dromaeo_dom']}, 16:16:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:16:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:16:25 INFO - 'tests': ['a11yr', 16:16:25 INFO - 'ts_paint', 16:16:25 INFO - 'tpaint', 16:16:25 INFO - 'sessionrestore', 16:16:25 INFO - 'sessionrestore_no_auto_restore', 16:16:25 INFO - 'tabpaint']}, 16:16:25 INFO - 'other-e10s': {'tests': ['a11yr', 16:16:25 INFO - 'ts_paint', 16:16:25 INFO - 'tpaint', 16:16:25 INFO - 'sessionrestore', 16:16:25 INFO - 'sessionrestore_no_auto_restore', 16:16:25 INFO - 'tabpaint']}, 16:16:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:16:25 INFO - 'ts_paint', 16:16:25 INFO - 'tpaint', 16:16:25 INFO - 'sessionrestore', 16:16:25 INFO - 'sessionrestore_no_auto_restore', 16:16:25 INFO - 'tabpaint']}, 16:16:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:16:25 INFO - 'ts_paint', 16:16:25 INFO - 'tpaint', 16:16:25 INFO - 'sessionrestore', 16:16:25 INFO - 'sessionrestore_no_auto_restore', 16:16:25 INFO - 'tabpaint']}, 16:16:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:16:25 INFO - 'tests': ['a11yr', 16:16:25 INFO - 'ts_paint', 16:16:25 INFO - 'tpaint', 16:16:25 INFO - 'sessionrestore', 16:16:25 INFO - 'sessionrestore_no_auto_restore', 16:16:25 INFO - 'tabpaint']}, 16:16:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:16:25 INFO - 'tests': ['a11yr', 16:16:25 INFO - 'ts_paint', 16:16:25 INFO - 'tpaint', 16:16:25 INFO - 'sessionrestore', 16:16:25 INFO - 'sessionrestore_no_auto_restore', 16:16:25 INFO - 'tabpaint']}, 16:16:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:16:25 INFO - 'tests': ['tsvgx', 16:16:25 INFO - 'tsvgr_opacity', 16:16:25 INFO - 'tart', 16:16:25 INFO - 'tscrollx', 16:16:25 INFO - 'cart']}, 16:16:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:16:25 INFO - 'tsvgr_opacity', 16:16:25 INFO - 'tart', 16:16:25 INFO - 'tscrollx', 16:16:25 INFO - 'cart']}, 16:16:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:25 INFO - 'talos_options': ['--disable-e10s'], 16:16:25 INFO - 'tests': ['tp5o']}, 16:16:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:25 INFO - 'tests': ['tp5o']}, 16:16:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:25 INFO - 'talos_options': ['--disable-e10s', 16:16:25 INFO - '--xperf_path', 16:16:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:25 INFO - 'tests': ['tp5n']}, 16:16:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:25 INFO - 'talos_options': ['--xperf_path', 16:16:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:25 INFO - 'tests': ['tp5n']}}, 16:16:25 INFO - 'talos.zip': {'path': '', 16:16:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:16:25 INFO - Running post-action listener: _resource_record_post_action 16:16:25 INFO - ##### 16:16:25 INFO - ##### Running create-virtualenv step. 16:16:25 INFO - ##### 16:16:25 INFO - Running pre-action listener: _resource_record_pre_action 16:16:25 INFO - Running main action method: create_virtualenv 16:16:25 INFO - Creating virtualenv /builds/slave/test/build/venv 16:16:25 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 16:16:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:16:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:16:25 INFO - Using real prefix '/usr' 16:16:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:16:25 INFO - Installing distribute.............................................................................................................................................................................................done. 16:16:27 INFO - Installing pip.................done. 16:16:27 INFO - Return code: 0 16:16:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:16: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')]} 16:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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')]} 16:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6f5553f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a075a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a96410>, '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 0x2a97310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a977a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', '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 16:16: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', 'pip>=1.5'] in /builds/slave/test/build 16:16: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 pip>=1.5 16:16:27 INFO - Using env: {'DISPLAY': ':0', 16:16:27 INFO - 'HOME': '/home/cltbld', 16:16:27 INFO - 'LANG': 'en_US.UTF-8', 16:16:27 INFO - 'LANGUAGE': 'en_US:en', 16:16:27 INFO - 'LOGNAME': 'cltbld', 16:16:27 INFO - 'MAIL': '/var/mail/cltbld', 16:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:27 INFO - 'MOZ_NO_REMOTE': '1', 16:16:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:27 INFO - 'NO_EM_RESTART': '1', 16:16:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:27 INFO - 'PWD': '/builds/slave/test', 16:16:27 INFO - 'SHELL': '/bin/bash', 16:16:27 INFO - 'SHLVL': '1', 16:16:27 INFO - 'TERM': 'linux', 16:16:27 INFO - 'TMOUT': '86400', 16:16:27 INFO - 'USER': 'cltbld', 16:16:27 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', 16:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:27 INFO - '_': '/tools/buildbot/bin/python'} 16:16:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:27 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 16:16:27 INFO - Cleaning up... 16:16:27 INFO - Return code: 0 16:16:27 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:16: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')]} 16:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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')]} 16:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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', '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 0x7fd6f5553f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a075a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a96410>, '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 0x2a97310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a977a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', '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 16:16: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', 'psutil>=0.7.1'] in /builds/slave/test/build 16:16: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 psutil>=0.7.1 16:16:27 INFO - Using env: {'DISPLAY': ':0', 16:16:27 INFO - 'HOME': '/home/cltbld', 16:16:27 INFO - 'LANG': 'en_US.UTF-8', 16:16:27 INFO - 'LANGUAGE': 'en_US:en', 16:16:27 INFO - 'LOGNAME': 'cltbld', 16:16:27 INFO - 'MAIL': '/var/mail/cltbld', 16:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:27 INFO - 'MOZ_NO_REMOTE': '1', 16:16:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:27 INFO - 'NO_EM_RESTART': '1', 16:16:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:27 INFO - 'PWD': '/builds/slave/test', 16:16:27 INFO - 'SHELL': '/bin/bash', 16:16:27 INFO - 'SHLVL': '1', 16:16:27 INFO - 'TERM': 'linux', 16:16:27 INFO - 'TMOUT': '86400', 16:16:27 INFO - 'USER': 'cltbld', 16:16:27 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', 16:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:27 INFO - '_': '/tools/buildbot/bin/python'} 16:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:28 INFO - Downloading/unpacking psutil>=0.7.1 16:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:16:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:30 INFO - Installing collected packages: psutil 16:16:30 INFO - Running setup.py install for psutil 16:16:30 INFO - building 'psutil._psutil_linux' extension 16:16:30 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 16:16:30 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 16:16:30 INFO - building 'psutil._psutil_posix' extension 16:16:30 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 16:16:30 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 16:16:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:30 INFO - Successfully installed psutil 16:16:30 INFO - Cleaning up... 16:16:30 INFO - Return code: 0 16:16:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:30 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 0x7fd6f5553f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a075a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a96410>, '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 0x2a97310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a977a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', '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 16:16:30 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 16:16:30 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 16:16:30 INFO - Using env: {'DISPLAY': ':0', 16:16:30 INFO - 'HOME': '/home/cltbld', 16:16:30 INFO - 'LANG': 'en_US.UTF-8', 16:16:30 INFO - 'LANGUAGE': 'en_US:en', 16:16:30 INFO - 'LOGNAME': 'cltbld', 16:16:30 INFO - 'MAIL': '/var/mail/cltbld', 16:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:30 INFO - 'MOZ_NO_REMOTE': '1', 16:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:30 INFO - 'NO_EM_RESTART': '1', 16:16:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:30 INFO - 'PWD': '/builds/slave/test', 16:16:30 INFO - 'SHELL': '/bin/bash', 16:16:30 INFO - 'SHLVL': '1', 16:16:30 INFO - 'TERM': 'linux', 16:16:30 INFO - 'TMOUT': '86400', 16:16:30 INFO - 'USER': 'cltbld', 16:16:30 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', 16:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:30 INFO - '_': '/tools/buildbot/bin/python'} 16:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:16:30 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 16:16:30 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 16:16:30 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 16:16:30 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 16:16:30 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 16:16:30 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 16:16:32 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:16:33 INFO - Installing collected packages: mozsystemmonitor 16:16:33 INFO - Running setup.py install for mozsystemmonitor 16:16:33 INFO - Successfully installed mozsystemmonitor 16:16:33 INFO - Cleaning up... 16:16:34 INFO - Return code: 0 16:16:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd6f5553f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a075a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a96410>, '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 0x2a97310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a977a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', '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 16:16:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:16:34 INFO - Using env: {'DISPLAY': ':0', 16:16:34 INFO - 'HOME': '/home/cltbld', 16:16:34 INFO - 'LANG': 'en_US.UTF-8', 16:16:34 INFO - 'LANGUAGE': 'en_US:en', 16:16:34 INFO - 'LOGNAME': 'cltbld', 16:16:34 INFO - 'MAIL': '/var/mail/cltbld', 16:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:34 INFO - 'MOZ_NO_REMOTE': '1', 16:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:34 INFO - 'NO_EM_RESTART': '1', 16:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:34 INFO - 'PWD': '/builds/slave/test', 16:16:34 INFO - 'SHELL': '/bin/bash', 16:16:34 INFO - 'SHLVL': '1', 16:16:34 INFO - 'TERM': 'linux', 16:16:34 INFO - 'TMOUT': '86400', 16:16:34 INFO - 'USER': 'cltbld', 16:16:34 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', 16:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:34 INFO - '_': '/tools/buildbot/bin/python'} 16:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:34 INFO - Downloading/unpacking blobuploader==1.2.4 16:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:36 INFO - Downloading blobuploader-1.2.4.tar.gz 16:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:16:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:16:36 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 16:16:36 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 16:16:36 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 16:16:36 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 16:16:36 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 16:16:36 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 16:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:16:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:16:36 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 16:16:36 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 16:16:36 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 16:16:36 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 16:16:36 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 16:16:36 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 16:16:36 INFO - Downloading docopt-0.6.1.tar.gz 16:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:16:37 INFO - Installing collected packages: blobuploader, requests, docopt 16:16:37 INFO - Running setup.py install for blobuploader 16:16:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:16:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:16:37 INFO - Running setup.py install for requests 16:16:37 INFO - Running setup.py install for docopt 16:16:37 INFO - Successfully installed blobuploader requests docopt 16:16:37 INFO - Cleaning up... 16:16:37 INFO - Return code: 0 16:16:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:37 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 0x7fd6f5553f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a075a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a96410>, '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 0x2a97310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a977a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', '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 16:16:37 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 16:16:37 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 16:16:37 INFO - Using env: {'DISPLAY': ':0', 16:16:37 INFO - 'HOME': '/home/cltbld', 16:16:37 INFO - 'LANG': 'en_US.UTF-8', 16:16:37 INFO - 'LANGUAGE': 'en_US:en', 16:16:37 INFO - 'LOGNAME': 'cltbld', 16:16:37 INFO - 'MAIL': '/var/mail/cltbld', 16:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:37 INFO - 'MOZ_NO_REMOTE': '1', 16:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:37 INFO - 'NO_EM_RESTART': '1', 16:16:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:37 INFO - 'PWD': '/builds/slave/test', 16:16:37 INFO - 'SHELL': '/bin/bash', 16:16:37 INFO - 'SHLVL': '1', 16:16:37 INFO - 'TERM': 'linux', 16:16:37 INFO - 'TMOUT': '86400', 16:16:37 INFO - 'USER': 'cltbld', 16:16:37 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', 16:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:37 INFO - '_': '/tools/buildbot/bin/python'} 16:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:37 INFO - Running setup.py (path:/tmp/pip-FpMHB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:37 INFO - Running setup.py (path:/tmp/pip-YvoTrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:37 INFO - Running setup.py (path:/tmp/pip-24PV7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:38 INFO - Running setup.py (path:/tmp/pip-GQZfRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:38 INFO - Running setup.py (path:/tmp/pip-LI8vcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:38 INFO - Running setup.py (path:/tmp/pip-fP69Z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:38 INFO - Running setup.py (path:/tmp/pip-2zf_4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:38 INFO - Running setup.py (path:/tmp/pip-BM05J0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:38 INFO - Running setup.py (path:/tmp/pip-oToZqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:38 INFO - Running setup.py (path:/tmp/pip-BnSxK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:38 INFO - Running setup.py (path:/tmp/pip-I7pmHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:38 INFO - Running setup.py (path:/tmp/pip-cEIswa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:38 INFO - Running setup.py (path:/tmp/pip-nS9jZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:38 INFO - Running setup.py (path:/tmp/pip-6uqKnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:38 INFO - Running setup.py (path:/tmp/pip-JvB4yd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:38 INFO - Running setup.py (path:/tmp/pip-ShGQHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:38 INFO - Running setup.py (path:/tmp/pip-iEmeAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:16:39 INFO - Running setup.py install for manifestparser 16:16:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:16:39 INFO - Running setup.py install for mozcrash 16:16:39 INFO - Running setup.py install for mozdebug 16:16:39 INFO - Running setup.py install for mozdevice 16:16:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:16:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:16:39 INFO - Running setup.py install for mozfile 16:16:39 INFO - Running setup.py install for mozhttpd 16:16:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:16:39 INFO - Running setup.py install for mozinfo 16:16:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:16:39 INFO - Running setup.py install for mozInstall 16:16:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:16:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:16:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:16:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:16:39 INFO - Running setup.py install for mozleak 16:16:39 INFO - Running setup.py install for mozlog 16:16:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:16:40 INFO - Running setup.py install for moznetwork 16:16:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:16:40 INFO - Running setup.py install for mozprocess 16:16:40 INFO - Running setup.py install for mozprofile 16:16:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:16:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:16:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:16:40 INFO - Running setup.py install for mozrunner 16:16:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:16:40 INFO - Running setup.py install for mozscreenshot 16:16:40 INFO - Running setup.py install for moztest 16:16:40 INFO - Running setup.py install for mozversion 16:16:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:16:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:16:40 INFO - Cleaning up... 16:16:40 INFO - Return code: 0 16:16:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:40 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 0x7fd6f5553f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a075a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a96410>, '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 0x2a97310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a977a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', '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 16:16:40 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 16:16:40 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 16:16:40 INFO - Using env: {'DISPLAY': ':0', 16:16:40 INFO - 'HOME': '/home/cltbld', 16:16:40 INFO - 'LANG': 'en_US.UTF-8', 16:16:40 INFO - 'LANGUAGE': 'en_US:en', 16:16:40 INFO - 'LOGNAME': 'cltbld', 16:16:40 INFO - 'MAIL': '/var/mail/cltbld', 16:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:40 INFO - 'MOZ_NO_REMOTE': '1', 16:16:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:40 INFO - 'NO_EM_RESTART': '1', 16:16:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:40 INFO - 'PWD': '/builds/slave/test', 16:16:40 INFO - 'SHELL': '/bin/bash', 16:16:40 INFO - 'SHLVL': '1', 16:16:40 INFO - 'TERM': 'linux', 16:16:40 INFO - 'TMOUT': '86400', 16:16:40 INFO - 'USER': 'cltbld', 16:16:40 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', 16:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:40 INFO - '_': '/tools/buildbot/bin/python'} 16:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:41 INFO - Running setup.py (path:/tmp/pip-kZpp3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:41 INFO - Running setup.py (path:/tmp/pip-YWHoMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:41 INFO - Running setup.py (path:/tmp/pip-7VeHRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:41 INFO - Running setup.py (path:/tmp/pip-U4GSdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:41 INFO - Running setup.py (path:/tmp/pip-MmAT_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:41 INFO - Running setup.py (path:/tmp/pip-lOVI_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:41 INFO - Running setup.py (path:/tmp/pip-zIfusL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:41 INFO - Running setup.py (path:/tmp/pip-4ah0BY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:41 INFO - Running setup.py (path:/tmp/pip-EdHPR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:41 INFO - Running setup.py (path:/tmp/pip-fR1jRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:41 INFO - Running setup.py (path:/tmp/pip-GvTg6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:41 INFO - Running setup.py (path:/tmp/pip-dpu0bu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:41 INFO - Running setup.py (path:/tmp/pip-9s8VwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:41 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)) 16:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:41 INFO - Running setup.py (path:/tmp/pip-qIkIH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:42 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)) 16:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:42 INFO - Running setup.py (path:/tmp/pip-OSS62p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:42 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)) 16:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:42 INFO - Running setup.py (path:/tmp/pip-RzRVr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:42 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)) 16:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:42 INFO - Running setup.py (path:/tmp/pip-ncbNCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:42 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)) 16:16:42 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)) 16:16:42 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)) 16:16:42 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)) 16:16:42 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)) 16:16:42 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)) 16:16:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:44 INFO - Downloading blessings-1.6.tar.gz 16:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:16:44 INFO - Installing collected packages: blessings 16:16:44 INFO - Running setup.py install for blessings 16:16:44 INFO - Successfully installed blessings 16:16:44 INFO - Cleaning up... 16:16:44 INFO - Return code: 0 16:16:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:16:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:44 INFO - Reading from file tmpfile_stdout 16:16:44 INFO - Current package versions: 16:16:44 INFO - argparse == 1.2.1 16:16:44 INFO - blessings == 1.6 16:16:44 INFO - blobuploader == 1.2.4 16:16:44 INFO - docopt == 0.6.1 16:16:44 INFO - manifestparser == 1.1 16:16:44 INFO - mozInstall == 1.12 16:16:44 INFO - mozcrash == 0.17 16:16:44 INFO - mozdebug == 0.1 16:16:44 INFO - mozdevice == 0.48 16:16:44 INFO - mozfile == 1.2 16:16:44 INFO - mozhttpd == 0.7 16:16:44 INFO - mozinfo == 0.9 16:16:44 INFO - mozleak == 0.1 16:16:44 INFO - mozlog == 3.1 16:16:44 INFO - moznetwork == 0.27 16:16:44 INFO - mozprocess == 0.22 16:16:44 INFO - mozprofile == 0.28 16:16:44 INFO - mozrunner == 6.11 16:16:44 INFO - mozscreenshot == 0.1 16:16:44 INFO - mozsystemmonitor == 0.0 16:16:44 INFO - moztest == 0.7 16:16:44 INFO - mozversion == 1.4 16:16:44 INFO - psutil == 3.1.1 16:16:44 INFO - requests == 1.2.3 16:16:44 INFO - wsgiref == 0.1.2 16:16:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:44 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')]} 16:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:44 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')]} 16:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:44 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 0x7fd6f5553f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a075a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a96410>, '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 0x2a97310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a977a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', '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 16:16:44 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 16:16:44 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 16:16:44 INFO - Using env: {'DISPLAY': ':0', 16:16:44 INFO - 'HOME': '/home/cltbld', 16:16:44 INFO - 'LANG': 'en_US.UTF-8', 16:16:44 INFO - 'LANGUAGE': 'en_US:en', 16:16:44 INFO - 'LOGNAME': 'cltbld', 16:16:44 INFO - 'MAIL': '/var/mail/cltbld', 16:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:44 INFO - 'MOZ_NO_REMOTE': '1', 16:16:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:44 INFO - 'NO_EM_RESTART': '1', 16:16:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:44 INFO - 'PWD': '/builds/slave/test', 16:16:44 INFO - 'SHELL': '/bin/bash', 16:16:44 INFO - 'SHLVL': '1', 16:16:44 INFO - 'TERM': 'linux', 16:16:44 INFO - 'TMOUT': '86400', 16:16:44 INFO - 'USER': 'cltbld', 16:16:44 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', 16:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:44 INFO - '_': '/tools/buildbot/bin/python'} 16:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:44 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)) 16:16:44 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)) 16:16:44 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)) 16:16:44 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)) 16:16:44 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)) 16:16:44 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)) 16:16:44 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)) 16:16:44 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)) 16:16:44 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)) 16:16:44 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)) 16:16:44 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)) 16:16:44 INFO - Cleaning up... 16:16:44 INFO - Return code: 0 16:16:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:16:44 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')]} 16:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:44 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')]} 16:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:44 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 0x7fd6f5553f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a075a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a96410>, '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 0x2a97310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a977a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', '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 16:16:44 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 16:16:44 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 16:16:44 INFO - Using env: {'DISPLAY': ':0', 16:16:44 INFO - 'HOME': '/home/cltbld', 16:16:44 INFO - 'LANG': 'en_US.UTF-8', 16:16:44 INFO - 'LANGUAGE': 'en_US:en', 16:16:44 INFO - 'LOGNAME': 'cltbld', 16:16:44 INFO - 'MAIL': '/var/mail/cltbld', 16:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:44 INFO - 'MOZ_NO_REMOTE': '1', 16:16:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:44 INFO - 'NO_EM_RESTART': '1', 16:16:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:44 INFO - 'PWD': '/builds/slave/test', 16:16:44 INFO - 'SHELL': '/bin/bash', 16:16:44 INFO - 'SHLVL': '1', 16:16:44 INFO - 'TERM': 'linux', 16:16:44 INFO - 'TMOUT': '86400', 16:16:44 INFO - 'USER': 'cltbld', 16:16:44 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', 16:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:44 INFO - '_': '/tools/buildbot/bin/python'} 16:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:45 INFO - Downloading/unpacking jsonschema 16:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:16:46 INFO - Downloading/unpacking functools32 (from jsonschema) 16:16:46 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 16:16:46 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 16:16:46 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 16:16:46 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 16:16:46 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 16:16:46 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 16:16:47 INFO - Downloading functools32-3.2.3-2.tar.gz 16:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:16:47 INFO - warning: no files found matching '*.txt' 16:16:47 INFO - no previously-included directories found matching 'build' 16:16:47 INFO - no previously-included directories found matching 'dist' 16:16:47 INFO - no previously-included directories found matching '.git*' 16:16:47 INFO - Installing collected packages: jsonschema, functools32 16:16:47 INFO - Running setup.py install for functools32 16:16:47 INFO - warning: no files found matching '*.txt' 16:16:47 INFO - no previously-included directories found matching 'build' 16:16:47 INFO - no previously-included directories found matching 'dist' 16:16:47 INFO - no previously-included directories found matching '.git*' 16:16:47 INFO - Successfully installed jsonschema functools32 16:16:47 INFO - Cleaning up... 16:16:47 INFO - Return code: 0 16:16:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:16: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')]} 16:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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')]} 16:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6f5553f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a075a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a96410>, '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 0x2a97310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a977a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', '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 16:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:16:47 INFO - Using env: {'DISPLAY': ':0', 16:16:47 INFO - 'HOME': '/home/cltbld', 16:16:47 INFO - 'LANG': 'en_US.UTF-8', 16:16:47 INFO - 'LANGUAGE': 'en_US:en', 16:16:47 INFO - 'LOGNAME': 'cltbld', 16:16:47 INFO - 'MAIL': '/var/mail/cltbld', 16:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:47 INFO - 'MOZ_NO_REMOTE': '1', 16:16:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:47 INFO - 'NO_EM_RESTART': '1', 16:16:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:47 INFO - 'PWD': '/builds/slave/test', 16:16:47 INFO - 'SHELL': '/bin/bash', 16:16:47 INFO - 'SHLVL': '1', 16:16:47 INFO - 'TERM': 'linux', 16:16:47 INFO - 'TMOUT': '86400', 16:16:47 INFO - 'USER': 'cltbld', 16:16:47 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', 16:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:47 INFO - '_': '/tools/buildbot/bin/python'} 16:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:47 INFO - Downloading/unpacking flake8 16:16:47 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 16:16:47 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 16:16:47 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 16:16:47 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 16:16:47 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 16:16:47 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 16:16:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:16:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:16:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 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 16:16:49 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 16:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:16:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:16:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:16:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:16:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:16:50 INFO - no previously-included directories found matching 'docs/_build' 16:16:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:16:50 INFO - Running setup.py install for pep8 16:16:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:16:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:16:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:16:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:16:50 INFO - no previously-included directories found matching 'docs/_build' 16:16:50 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:16:50 INFO - Cleaning up... 16:16:50 INFO - Return code: 0 16:16:50 INFO - Running post-action listener: _resource_record_post_action 16:16:50 INFO - Running post-action listener: _start_resource_monitoring 16:16:50 INFO - Starting resource monitoring. 16:16:50 INFO - ##### 16:16:50 INFO - ##### Running install step. 16:16:50 INFO - ##### 16:16:50 INFO - Running pre-action listener: _resource_record_pre_action 16:16:50 INFO - Running main action method: install 16:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:50 INFO - Reading from file tmpfile_stdout 16:16:50 INFO - Detecting whether we're running mozinstall >=1.0... 16:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:16:50 INFO - Reading from file tmpfile_stdout 16:16:50 INFO - Output received: 16:16:50 INFO - Usage: mozinstall [options] installer 16:16:50 INFO - Options: 16:16:50 INFO - -h, --help show this help message and exit 16:16:50 INFO - -d DEST, --destination=DEST 16:16:50 INFO - Directory to install application into. [default: 16:16:50 INFO - "/builds/slave/test"] 16:16:50 INFO - --app=APP Application being installed. [default: firefox] 16:16:50 INFO - mkdir: /builds/slave/test/build/application 16:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:17:03 INFO - Reading from file tmpfile_stdout 16:17:03 INFO - Output received: 16:17:03 INFO - /builds/slave/test/build/application/firefox/firefox 16:17:03 INFO - Running post-action listener: _resource_record_post_action 16:17:03 INFO - ##### 16:17:03 INFO - ##### Running run-tests step. 16:17:03 INFO - ##### 16:17:03 INFO - Running pre-action listener: _resource_record_pre_action 16:17:03 INFO - Running main action method: run_tests 16:17:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:17:03 INFO - Python 2.7.3 16:17:03 INFO - Return code: 0 16:17:03 INFO - grabbing minidump binary from tooltool 16:17: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')]} 16:17:03 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 0x2a96410>, '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 0x2a97310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a977a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:17:03 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 16:17:03 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 16:17:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:17:04 INFO - Return code: 0 16:17:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:17:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:17:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:17:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:17:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:17:04 INFO - Return code: 0 16:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 16:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 16:17:04 INFO - Using env: {'DISPLAY': ':0', 16:17:04 INFO - 'HOME': '/home/cltbld', 16:17:04 INFO - 'LANG': 'en_US.UTF-8', 16:17:04 INFO - 'LANGUAGE': 'en_US:en', 16:17:04 INFO - 'LOGNAME': 'cltbld', 16:17:04 INFO - 'MAIL': '/var/mail/cltbld', 16:17:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:17:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:04 INFO - 'MOZ_NO_REMOTE': '1', 16:17:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:17:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:04 INFO - 'NO_EM_RESTART': '1', 16:17:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:04 INFO - 'PWD': '/builds/slave/test', 16:17:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:17:04 INFO - 'SHELL': '/bin/bash', 16:17:04 INFO - 'SHLVL': '1', 16:17:04 INFO - 'TERM': 'linux', 16:17:04 INFO - 'TMOUT': '86400', 16:17:04 INFO - 'USER': 'cltbld', 16:17:04 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470', 16:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:04 INFO - '_': '/tools/buildbot/bin/python'} 16:17:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 16:17:05 INFO - mozversion application_buildid: 20160710132925 16:17:05 INFO - mozversion application_changeset: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e 16:17:05 INFO - mozversion application_display_name: Firefox 16:17:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:17:05 INFO - mozversion application_name: Firefox 16:17:05 INFO - mozversion application_remotingname: firefox 16:17:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:17:05 INFO - mozversion application_vendor: Mozilla 16:17:05 INFO - mozversion application_version: 48.0 16:17:05 INFO - mozversion platform_buildid: 20160710132925 16:17:05 INFO - mozversion platform_changeset: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e 16:17:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:17:05 INFO - mozversion platform_version: 48.0 16:17:05 INFO - using testdate: 1468192625 16:17:05 INFO - actual date: 1468192625 16:17:05 INFO - starting webserver on 'localhost:58682' 16:17:05 INFO - SUITE-START | Running 2 tests 16:17:05 INFO - TEST-START | dromaeo_css 16:17:05 INFO - operating with platform_type : linux_ 16:17:05 INFO - Initialising browser for dromaeo_css test... 16:17:05 INFO - TEST-INFO | started process 4478 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpztc8cl/profile http://localhost:58682/getInfo.html) 16:17:09 INFO - PROCESS | 4478 | 16:17:09 INFO - PROCESS | 4478 | (process:4563): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:17:09 INFO - PROCESS | 4478 | __metrics Screen width/height:1600/1200 16:17:09 INFO - PROCESS | 4478 | colorDepth:24 16:17:09 INFO - PROCESS | 4478 | Browser inner width/height: 1024/697 16:17:09 INFO - PROCESS | 4478 | __metrics 16:17:10 ERROR - PROCESS | 4478 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 16:17:11 INFO - TEST-INFO | 4478: exit 0 16:17:11 INFO - Browser initialized. 16:17:11 INFO - Running cycle 1/1 for dromaeo_css test... 16:17:11 INFO - TEST-INFO | started process 4613 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpztc8cl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:17:13 INFO - PROCESS | 4613 | 16:17:13 INFO - PROCESS | 4613 | (process:4683): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:17:23 INFO - PROCESS | 4613 | RSS: Main: 150642688 16:17:23 INFO - PROCESS | 4613 | 16:18:12 INFO - PROCESS | 4613 | 1468192692814 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] 16:20:20 INFO - PROCESS | 4613 | Cycle 1(1): loaded http://localhost:58682/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58682/tests/dromaeo/cssquery-ext.html) 16:20:21 INFO - PROCESS | 4613 | RSS: Main: 154513408 16:20:21 INFO - PROCESS | 4613 | 16:23:23 INFO - PROCESS | 4613 | Cycle 1(1): loaded http://localhost:58682/tests/dromaeo/cssquery-ext.html (next: http://localhost:58682/tests/dromaeo/cssquery-jquery.html) 16:23:24 INFO - PROCESS | 4613 | RSS: Main: 152670208 16:23:24 INFO - PROCESS | 4613 | 16:26:25 INFO - PROCESS | 4613 | Cycle 1(1): loaded http://localhost:58682/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58682/tests/dromaeo/cssquery-mootools.html) 16:26:26 INFO - PROCESS | 4613 | RSS: Main: 152518656 16:26:26 INFO - PROCESS | 4613 | 16:29:27 INFO - PROCESS | 4613 | Cycle 1(1): loaded http://localhost:58682/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58682/tests/dromaeo/cssquery-prototype.html) 16:29:28 INFO - PROCESS | 4613 | RSS: Main: 153739264 16:29:28 INFO - PROCESS | 4613 | 16:32:30 INFO - PROCESS | 4613 | Cycle 1(1): loaded http://localhost:58682/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58682/tests/dromaeo/cssquery-yui.html) 16:32:31 INFO - PROCESS | 4613 | RSS: Main: 152272896 16:32:31 INFO - PROCESS | 4613 | 16:35:33 INFO - PROCESS | 4613 | Cycle 1(1): loaded http://localhost:58682/tests/dromaeo/cssquery-yui.html (next: http://localhost:58682/tests/dromaeo/cssquery-dojo.html) 16:35:33 INFO - PROCESS | 4613 | RSS: Main: 156303360 16:35:33 INFO - PROCESS | 4613 | 16:35:33 INFO - PROCESS | 4613 | __start_tp_report 16:35:33 INFO - PROCESS | 4613 | _x_x_mozilla_page_load 16:35:33 INFO - PROCESS | 4613 | _x_x_mozilla_page_load_details 16:35:33 INFO - PROCESS | 4613 | |i|pagename|runs| 16:35:33 INFO - PROCESS | 4613 | |0;dojo.html;3355.647656996016;3425.5375524304227;3426.16059065529;3469.583649962005;3470.84381202846;5653.915191272129;5655.066913959192;5659.349174844894;5666.858328541788;5674.51766599839;5578.888422231553;5661.943380566193;5665.093585026397;5665.433456654332;5690.914636280449;5129.692218466886;5152.587792976572;5161.612879034069;5179.404368497614;5247.422783493813;5105.923411148836;5232.529072383485;5295.0468915595175;5304.469553044684;5330.626856120073;5224.712640804754;5245.527902488775;5287.920681189766;5289.735513224343;5292.788288468457;5242.659227150242;5250.894982100355;5281.102212623863;5282.392524859613;5286.365636123652;4978.427480839678;5091.949080509185;5153.2270159476;5170.25031370453;5231.372235331784;5510.421405752379;5532.5573954083575;5550.084236101038;5570.470805273492;5596.972015139898;6027.216461859974;6046;6069.211002569682;6071.787487437919;6079.331273559905;6040.154378387029;6102.389761023862;6120.14317995481;6125.050617154348;6151.384861513858;5603.2435621191025;5608.046632072557;5609.326880774333;5619.662820230799;5645.096784514499;6117.480014198754;6120.449159575571;6121.296050954108;6124.6325220486915;6127.080937859357;5895.793647222413;5896.498797602252;5940.910886336708;6007.609505382136;6028.8794224116145;5986.970065149646;5997.880042399215;6006.519478441714;6009.06859436788;6010.939890601126;5902.291724993029;5916.497097746654;5954.3152537458745;5959.523238140939;5972.25346831646;5915.3788852170765;5939.4357536034;5999.220101386792;6014.639121652627;6020.69896505173;4575.725456472623;4596.149712303168;4598.356230127785;4603;4633.7683115844075;4104.384342348671;4116.5060192777055;4127.814248358771;4138.627523522898;4148.543660197376;5736;5781.797637082766;5892.852678683067;5930;5964.7315870785915;6005.789797357072;6064.272287325549;6065.727042283108;6068.241469816273;6110.663913484801;5745.85635359111;5800.303963524404;5805.506531944832;5825.300963884361;5837.3870743571315;5147.893202961382;5178.6374953752875;5185.429602743695;5197.558207552324;5215.973920130375;6264.812055638338;6307.684615769193;6314.778982735582;6316.178896743486;6353.96823015882;5955.285365756137;6001.039833626599;6080.118382834553;6085.908711369421;6088.299845517822;6194.535409844189;6216.036514340284;6250.656213908279;6252.499800015897;6263.060540918898;5924.7037648118285;5944.464998150187;5954.07711804671;5954.583179177416;5962.70186490665;5930.140129681258;6046.30467496226;6064.423879731418;6068.4538391544975;6129.203203583684;5816.214811000461;5837.241158649348;5840.795572154954;5845.473907348359;5868.823935281948;5783.971080144573;5791.160281759037;5819.243498345188;5822.854428639318;5837.066069428871;5668.178114173505;5739.713014349182;5793.478586927196;5883.264591926009;5932.436418540232;5912.763489460373;5941.58408911372;5955.076963070731;5956.702164891824;5977.641341519523;5829.795957141479;5833.241678581757;5838;5844.1525978732;5846.181534585076 16:35:33 INFO - PROCESS | 4613 | |1;ext.html;650.1061041068531;672.2135101930741;673.7237732529663;721.765426236473;730.4302643937729;32553.837230813842;33286.50070248948;40036.99999999996;40559.39160912589;40693.186136277254;43240.13519729603;43949;43987;43987.78006109965;44037.33943990835;8808.867866981984;8839.381243312939;8842.955785221066;8887.377883548153;8972;8873.467591944504;8973.820523589524;8976.41653292534;8992.730218093468;9005.684801031965;8974.50640214788;9256.537173141349;9301.06989301068;9307.395019323721;9320.72037838866;9123.771905702377;9148.725538233864;9213.355065145442;9223.07769223079;9232.6306947722;9035.683751068682;9123.08769123089;9123.361364704408;9133.132352426508;9136.45181289118;44015;44542.777286113684;44658;44736.10527789459;44835.32747008798;3604.1502939625902;3648.525691660094;3677.7793332399874;3714.1457464783252;3927.960720392788;33963.66036339654;34049.82975085109;34136.14659633504;34462.65537344619;34496;4517.503074661785;4651.046535460223;4713.5993440557595;4735.218688916325;4771.832985845479;306898;307852;308947;308978.4551077253;309097;39747.403788943186;40422.19155616871;64998.67500662561;65453;65565.67217163884;38405;38651.80674096668;38656.42015369772;38750.6124938747;38780;15969;16118.919405403132;16134.677306453805;16146.515604531647;16192.109433981244;13173.407196676173;13196.538121165713;13305.268210248529;13348.599542013755;13362.532311369056;9219.585118669676;9475.478848663251;9523.095305945924;9547.665831695856;9579.042095790364;3605.2789442111684;3673.4489826526233;3684.1158122050515;3693.0582701410967;3702.8889133326043;1107.8338249262674;1108.7172770943357;1124.245598284207;1146.8165093584987;1157.3345326437332;33678.32643347119;33860.66139338575;33924.49113263303;34048.319033619686;34496;1075.2043487819114;1141.5547936304847;1156.9942150289194;1158.692946369213;1249.1630607493003;17438.825611743974;17503.47489575315;17675.40921317062;17766.46700598984;18006;221522;505430;516802;519540;520929;56000.71999640057;56969.43030569641;57111.28666140023;57130.428695713344;57132.42867571354;1512.2136489025645;1528.1900592685895;1529.3041666042147;1532.1496569403864;1572.4889410941262;30961;31243.375132497677;31498.212544685648;32214.19464513406;32365;55937.44062559485;60804.391956079875;61940.69029654823;62005.37994619996;62103.37896621157;415515;422864;422885;423682;425163;47524.2871356923;47560.52439475561;47860.760696196296;48193;48332.516674832805;52406;53316.73341633267;53615.73192134014;53629.90331183046;53820;43226.78386608047;43684.34473482964;43746;44250;44937;43718.56281437272;43917.560824391345;43960;44300.556994429644;44826.10347792961;44120.55879441164;44225.33661995137;44496;44709.10581788411;45239.09521809611;32764.672353277114;33169.83415082909;33499.83250083734;33559.16102097467;33844.661553384154;26563.867180664747;27235.182944511696;29470.263243419085;29723;29881.252968675955 16:35:33 INFO - PROCESS | 4613 | |2;jquery.html;432.9783510824458;436.14448277229286;444.6816079686944;448.3924282597081;450.2997838361347;18760.4371868844;18880.62238755224;19189.136488858;19797.109130089146;19806.10872510737;17286.222120004597;17379.73930391045;18018.819811801877;18579;18634;5265.76304066318;5851.15158302046;6081.118237855509;6086.908696369558;6102.206713127287;5930.555208359368;5987.491063259628;6000.459958603747;6003.6697981611;6027.9095813562835;5850.970745146268;5930.110483427477;5947.464728174464;6013.639181649093;6014.488768454687;5938.8218353449465;5949.9107513387335;5953.404659534055;5955.255593050869;5962.672053037081;5307.628466007381;5397.433269506724;5406.54044406226;5423.945760542383;5428.782848686048;23646.76353236463;24300.149494767775;24320.270391888273;24328.27015189547;24516.632250516257;2451.7915977141965;2504.3864253257884;2518.0431436054364;2539.1874600127967;2545.4272788622598;9394.389364691273;9926.354786938899;15081.019733717274;15081.472148474752;15091.943563950528;2016.0927582587894;2028.2292728763123;2035.8574899757023;2055.2498338106634;2108.8945552722303;79198.60400698017;79394;79507;82118.58940705318;82851;11668.782931243037;11794.764104717857;11817.763644727058;11830.704232394088;11953.043756499459;16286.51140465788;16317;16391.098489583186;16431.671366572602;16440.83559164417;8590.570471476402;8737.91262087371;8742.431741936754;8779.429337093195;8927.24118449926;8036.276735093869;8066;8117.878231826528;8125.146859579659;8160.3471722262075;4580.748058856728;4667.766611669403;4799.544043315949;5129;5135.87160320995;1970.5369238229005;1987.8012198780295;2069.6688529835455;2141.668041453577;2201.4276288165192;1024.257413375312;1048.9475526223657;1112.4660163121746;1145.9484323205477;1160.512584714422;18901;19149.425517234504;19159;19464.890291524058;19608.607827843647;1031.128696251666;1064.1327318235612;1091.165258577189;1149;1149.2874417860812;10915.890841091488;11057.944710276397;11059.728131264847;11062.557497700163;11116.833247501294;474914;481726;515704;517777;523647;78750.6062469684;88646;91854.54072729594;95700;101667.49166254122;1121.2711737370773;1132.4903793293051;1148.3397046698014;1157.89578937896;1167.8832116788424;17456.214470348612;17845.196966136813;17972.460826175236;18225.726614100535;18480.075996200405;27244.31889202786;37754.8112259437;41547;42137.36793948032;42468.36297455479;60320.39679603148;61337;61612;61900.38099618946;62318;8072.59637018135;8734.868976965223;8868.86696699537;9070.365074444726;9104.90895091067;12899.871001289866;13203.933980330037;13276.601701948957;13348.93325533366;14058.367373468014;11158.44207789591;11233.32600044;11438.256513326607;11564.363959982282;12015;11596.130290228099;11788.410579470821;11831.46758395857;12005.939970300093;12105.939470302592;6371.840703982438;8439.915600843913;11536;11968.341741204318;12424.0681948856;8945.776355591162;9119.726408207764;9127.680531181377;9214.677486287948;9230.907690923004;8488.05307527306;11613.30320180792;11638.476268567765;11674.007709344802;11866.881331186923 16:35:33 INFO - PROCESS | 4613 | |3;mootools.html;1514.8182218133825;1516.5450364890532;1697.5840918974852;1833.7707786526692;1874.7656542932134;8456.830863382736;8630.827383452328;8661.26379257763;8725.476471411712;8812.691555795533;8752.956235218815;8789.560521973908;8792.428492148007;8834.86747698785;8853.291736661067;2427.2354208424335;2461.950760984779;2465.0879174705296;2465.3959779853963;2466.7779899809016;2484.4534202475515;2485.142625794098;2485.6768620079456;2501.4496810701617;2501.774840264376;2493.488834788873;2494.2143224884103;2501.662275592799;2508.8745562721883;2517.697876254843;2464.039024780337;2483.714372847128;2491.2650768023386;2514.6102354135046;2531.556977528934;2497.1509686706427;2517.1315896015844;2528.683914510686;2532.430203204283;2539.5682733935278;8764.474131552064;8783.638536026861;8803.471791692518;8822.073682263399;8833.867491987625;5282.366116066097;5359.785608575652;5414.3232095988005;5415.512603865671;5416.620836541444;3535.4520049392127;3577.105723569108;3585.8744943926836;3592.4072528032843;3663.5786884508357;1170.5259369955181;1188.566173346731;1210.5278941212935;1212.0182652051867;1224.4428784902848;12251.877481225163;12312.81530777039;12354.505819767199;12360.134790564663;12386.071044671686;4598.26427771555;4643.698159619614;4682.508336624674;4686.250199968057;4735.266033764772;9445.0554944505;9449.480278584606;9463.38487998278;9469.195118414875;9480.57337419804;5317.335333083364;5356.544693701079;5366.168243922199;5378.381486129067;5381.892362152735;4348.304271316574;4807.375041244686;4874;4913.533214344631;5022.899542009212;3766.152615661454;3813.0848596336677;3826.789526576065;3850.7882066486613;3881.990682422534;1188.8811118888043;1191.1542804608825;1191.7318603314186;1193.6896406934259;1211.4003568233604;1031.6131450705984;1039.0388890276463;1039.0752230514847;1059.6132411669685;1079.7786453776957;6050.667213303311;6124.5100391968535;6310.905336419957;6337.619742815354;6405.551611387158;435.3382858055738;438.4081489988554;439.93181056936226;439.9428074350314;456.19253920560425;4218.6414154797185;4277.230098582285;4380.6057454829215;4390.62679672225;4418.823247070145;10534.315269507457;10802.67591972242;10881.564737410574;10899.455027248765;10945.945270273598;10521.947390263;10620.681379558626;10676.199285053497;10709.143268538499;10794.46027698612;8327.750167494985;8405.201505857056;8413.915860841436;8425.620847061897;8505.61724722389;5799.362070172362;5904.822855314347;5952.494038006645;6030.1256317832795;6066.848328791815;9649.758756031155;9751.268654851056;9875.40747555149;9887.85168222463;9890.258230632588;9342;9739.9026009739;9759.365641233297;9847.507624618884;10012.84980725275;7150.10623672041;7315.524490908074;7343.77968660941;7779.183185765413;7902;7774.805629859299;7823.765287041398;7866.292033716993;7880;7911.485753426009;6815.488838337045;7001.089858318386;7019.719211231493;7041.683124259318;7240.239774823568;7115.928840711526;7168.17565979919;7187.928120718726;7290.744823931349;7307.817304567216;6612.669366531751;6613.173353330833;6615.040819081302;6630.138082049302;6666.066750655009;4992.825251116194;5052.368453943257;5079.3650793650795;5087.872803179802;5194.480551944926;5114.266585392758;5119.129747942784;5126.2054381570915;5209.6092793041435;5227.89544209106 16:35:33 INFO - PROCESS | 4613 | |4;prototype.html;351.4061236510298;351.83287938229347;357.5959166142262;358.4104148675427;366.957799853017;6585.868282634357;6604.778116048535;6609.669516524166;6653.2681405045405;6691.799246022621;6499.480041596673;6537.248216455109;6558.376954189344;6565.934340656592;6582.96708516458;4776.092542416938;4821.180399332105;4911.582515486188;4913.410390753114;4921.876953076184;4972.129877271481;4990.950090499085;4992.151334273164;5004.374453193351;5012.598992080625;4950.183219768752;4953.331300274471;4961.826336078238;4963.975180124095;4969.900601987958;4962.280469331927;4962.925556116661;4964.379452568429;4968.925466118011;4971.37857767779;4352.586504282088;4362.781860906974;4366.235908715962;4368.7815609219415;4379.167958087953;6144.87710245797;6233.158523599302;6256.499480041632;6321.304656487829;6323.494120470351;5757.4818266355815;5793.275840519935;5795.2176456178295;5798.855028624318;5812.796552120655;6888.896666550006;6894.172699276119;6901.482388820858;6901.792946211622;6918.100646915919;4810.542998415145;4810.831620893252;4827.51724827518;4831.0096430231715;4831.516848315176;7241.782746517613;7264.745733899288;7270.491065625409;7274.563526188445;7285.5628662279905;4809.759512024455;4948.925766113512;4949.604031677457;4963.70217786934;4964.702117872867;4994.601511576764;5019.272205530251;5022.3219865317715;5025.698458092526;5032.370953630796;5778.942210577925;5793.362730099686;5796.768129274866;5797.188393624896;5807.157962095473;6981.127359080115;6987.26633703464;6992.895106573405;7003.5797852129035;7005.684744186524;1752.7020406530921;1778.8221177882367;1782.8128046554925;1785.2234278089072;1787.3297513432462;1080.2924084724518;1087.0922779479224;1088.151242031217;1180.3508070561156;1214.4352875912743;6585.83535411609;6722.529422940347;6757.763478278237;6758.425533829582;6762.4928130389435;7087;7124.180719217356;7124.821879453055;7129.714811407485;7134.821629459305;6889.758858439931;6902.6203558803745;6906.896396554056;6907.447404207652;6910.930890691029;3896.103896103912;3935.1932853765484;3936.724429289922;3941.743786653916;3941.921161576752;7569.167391586921;7646.541207527566;7648.349890259279;7651.885221721679;7656.808579785438;7242.891356629655;7418.590467811098;7437.553746775211;7442.776716698508;7453.440991925628;7591.089069311718;7598.316151546387;7599.848003039909;7644.3120119188425;7653.158152603321;7216.7474138404905;7224.385927196037;7228.31331023552;7229.385502232369;7234.348908598251;7141.642917853983;7153.463490238149;7155.284471552803;7156.892646610409;7161.176464706626;7414.295641914009;7548.773536793905;7559.2408770797165;7590.924090759022;7645.311921926831;4803.711777293373;4823.3729615149805;4825.372701548777;4844.103840789465;4846.442659094108;7218.9893414923;7231.2768723129375;7245.311695388929;7245.818854528679;7256.89114663291;6942.132233470817;6977.686004129725;6986.6157361343585;6994.160700715946;7020.22777494465;6869.793906182823;7008.299170083154;7014.403775679124;7019.29807019294;7029.0862187915245;6844.657767111525;6861.210960739579;6878.793636190922;6878.965605172142;6896.965515172392;6978.057962175041;7031.542949708252;7051.964740176266;7055.3650171484815;7061;6856.24581296067;6860.451163906975;6860.794176174722;6863.691133899087;6874.037634731041 16:35:33 INFO - PROCESS | 4613 | |5;yui.html;864.7492227254095;925.6621333213377;932.105179028133;933.7712260496183;944.5371767833764;14704.191269480174;14922.925385373072;15102.244887755596;15122;15158.166300853449;14111.647208819784;15979.520614381587;16253.18734063298;16258.674826503462;16267.10530920799;889.8932128144631;908.4730856103445;911.2072496927649;911.7538264668535;912.7489940266447;760.7565579014718;883.995580022099;899.4872922434215;921.2767977137948;921.8478950973081;947.6967370441462;949.3591825517782;949.9857502137473;954.7565370830429;955.1594596754846;932.1191474057019;941.0213378086781;941.9811603767921;943.8253923024193;945.5744914788338;688.7658196213262;689.2452764223168;690.702997710984;692.1866806502339;695.8121307247065;15747.291371888292;15786.684266314724;15822.920885395499;15926.840731592652;15951;14741.705165896728;14894;14910;14912.105273683614;14918.62703432412;12111.152219344649;12230.205036672587;12411.689707757288;12484.063695222894;12728.61814145577;3821.617838216184;3973.0861901762755;3994.520657521087;4060.634542891154;4165.416841642173;59652.70173649104;59859;60026.39973600252;60476.09285860715;60637;11338.206325557132;11529.48117334722;11544.653660390202;11684.532618695326;11713.765724685458;20159.798402016084;20386.49033774138;20460.897695511725;20483.283085092247;20526.281580144623;8540.14598540141;8664.350173736995;8708.95645521781;8760.912390876138;8824.470531768115;4649.744264065441;4667.533246675305;4717.622590192845;4718.693284936468;4786.042791441726;3698.963010369862;3759.774413535197;3762.209935913434;3790.0714324990554;3798.9050273742823;669.8291935556402;684.2131548719013;684.2575805251258;689.6344937183301;701.5089437393844;696.4846013949742;766.4864540757706;789.3014682006466;794.7615715285391;796.653455746752;9535.570899309549;9959.502024898871;9990.950045249727;10099.495025248854;10103.19174466041;719.1226703421817;734.4968696442953;761.3680645064583;766.6703317573497;804.7424824056246;4575.5424457554645;4598.816047358135;4599.7470139142;4622.6301895848255;4726.669133160645;60120;61576.69211654003;61824.690876545326;63897.36102639007;64314.67842660757;58625.99999999915;58851;58882.41117588855;58910;60181.69909150426;52854.20718689223;53648;54442.72778636082;54445.183322250195;56295.87408251812;9968.451735154636;10275.537600807833;10372.429516376369;10413.062824345543;10569.260151789302;37555.2488950214;38446.23107537778;38506.422403664525;39837.80081099576;40886.59113408828;50942.49057509526;51420.74289628528;52029.21956170736;52139.47860521498;52629.7368513155;12607.243565386107;12642.620721378373;12821.615351539469;13163.276019819003;13293.933530332673;13541.932290338485;14037.438502459787;14068.226247556482;14219.431222751387;14299.285035747964;13526.661833453849;13706.177629342272;13947.16317020982;14001.859981400463;14101.929490352893;13166.341682915625;13166.341682915625;13215.537456188988;13226.4048117833;13484.797728034284;12786.61640150797;13231;13309.134906231064;13665.316734163052;13702.588922332347;10734.624288149877;10959.123270138529;11154;11158.772535020978;11301.886981130412;10766.246362754846;11185.776284474428;11329.943350283196;11405.087592992706;11639.243449175776 16:35:33 INFO - PROCESS | 4613 | __end_tp_report 16:35:33 INFO - PROCESS | 4613 | __start_cc_report 16:35:33 INFO - PROCESS | 4613 | _x_x_mozilla_cycle_collect,326 16:35:33 INFO - PROCESS | 4613 | __end_cc_report 16:35:33 INFO - PROCESS | 4613 | __startTimestamp1468193733929__endTimestamp 16:35:33 INFO - PROCESS | 4613 | 16:35:33 INFO - PROCESS | 4613 | ------- Summary: start ------- 16:35:33 INFO - PROCESS | 4613 | Number of tests: 6 16:35:33 INFO - PROCESS | 4613 | 16:35:33 INFO - PROCESS | 4613 | [#0] dojo.html Cycles:165 Average:5625.30 Median:5837.24 stddev:609.95 (10.4%) stddev-sans-first:585.26 16:35:33 INFO - PROCESS | 4613 | Values: 3355.6 3425.5 3426.2 3469.6 3470.8 5653.9 5655.1 5659.3 5666.9 5674.5 5578.9 5661.9 5665.1 5665.4 5690.9 5129.7 5152.6 5161.6 5179.4 5247.4 5105.9 5232.5 5295.0 5304.5 5330.6 5224.7 5245.5 5287.9 5289.7 5292.8 5242.7 5250.9 5281.1 5282.4 5286.4 4978.4 5091.9 5153.2 5170.3 5231.4 5510.4 5532.6 5550.1 5570.5 5597.0 6027.2 6046.0 6069.2 6071.8 6079.3 6040.2 6102.4 6120.1 6125.1 6151.4 5603.2 5608.0 5609.3 5619.7 5645.1 6117.5 6120.4 6121.3 6124.6 6127.1 5895.8 5896.5 5940.9 6007.6 6028.9 5987.0 5997.9 6006.5 6009.1 6010.9 5902.3 5916.5 5954.3 5959.5 5972.3 5915.4 5939.4 5999.2 6014.6 6020.7 4575.7 4596.1 4598.4 4603.0 4633.8 4104.4 4116.5 4127.8 4138.6 4148.5 5736.0 5781.8 5892.9 5930.0 5964.7 6005.8 6064.3 6065.7 6068.2 6110.7 5745.9 5800.3 5805.5 5825.3 5837.4 5147.9 5178.6 5185.4 5197.6 5216.0 6264.8 6307.7 6314.8 6316.2 6354.0 5955.3 6001.0 6080.1 6085.9 6088.3 6194.5 6216.0 6250.7 6252.5 6263.1 5924.7 5944.5 5954.1 5954.6 5962.7 5930.1 6046.3 6064.4 6068.5 6129.2 5816.2 5837.2 5840.8 5845.5 5868.8 5784.0 5791.2 5819.2 5822.9 5837.1 5668.2 5739.7 5793.5 5883.3 5932.4 5912.8 5941.6 5955.1 5956.7 5977.6 5829.8 5833.2 5838.0 5844.2 5846.2 16:35:33 INFO - PROCESS | 4613 | 16:35:33 INFO - PROCESS | 4613 | [#1] ext.html Cycles:180 Average:56627.19 Median:43701.45 stddev:107646.41 (246.3%) stddev-sans-first:107866.34 16:35:33 INFO - PROCESS | 4613 | Values: 650.1 672.2 673.7 721.8 730.4 32553.8 33286.5 40037.0 40559.4 40693.2 43240.1 43949.0 43987.0 43987.8 44037.3 8808.9 8839.4 8843.0 8887.4 8972.0 8873.5 8973.8 8976.4 8992.7 9005.7 8974.5 9256.5 9301.1 9307.4 9320.7 9123.8 9148.7 9213.4 9223.1 9232.6 9035.7 9123.1 9123.4 9133.1 9136.5 44015.0 44542.8 44658.0 44736.1 44835.3 3604.2 3648.5 3677.8 3714.1 3928.0 33963.7 34049.8 34136.1 34462.7 34496.0 4517.5 4651.0 4713.6 4735.2 4771.8 306898.0 307852.0 308947.0 308978.5 309097.0 39747.4 40422.2 64998.7 65453.0 65565.7 38405.0 38651.8 38656.4 38750.6 38780.0 15969.0 16118.9 16134.7 16146.5 16192.1 13173.4 13196.5 13305.3 13348.6 13362.5 9219.6 9475.5 9523.1 9547.7 9579.0 3605.3 3673.4 3684.1 3693.1 3702.9 1107.8 1108.7 1124.2 1146.8 1157.3 33678.3 33860.7 33924.5 34048.3 34496.0 1075.2 1141.6 1157.0 1158.7 1249.2 17438.8 17503.5 17675.4 17766.5 18006.0 221522.0 505430.0 516802.0 519540.0 520929.0 56000.7 56969.4 57111.3 57130.4 57132.4 1512.2 1528.2 1529.3 1532.1 1572.5 30961.0 31243.4 31498.2 32214.2 32365.0 55937.4 60804.4 61940.7 62005.4 62103.4 415515.0 422864.0 422885.0 423682.0 425163.0 47524.3 47560.5 47860.8 48193.0 48332.5 52406.0 53316.7 53615.7 53629.9 53820.0 43226.8 43684.3 43746.0 44250.0 44937.0 43718.6 43917.6 43960.0 44300.6 44826.1 44120.6 44225.3 44496.0 44709.1 45239.1 32764.7 33169.8 33499.8 33559.2 33844.7 26563.9 27235.2 29470.3 29723.0 29881.3 16:35:33 INFO - PROCESS | 4613 | 16:35:33 INFO - PROCESS | 4613 | [#2] jquery.html Cycles:180 Average:29511.47 Median:13484.21 stddev:82873.30 (614.6%) stddev-sans-first:83077.02 16:35:33 INFO - PROCESS | 4613 | Values: 433.0 436.1 444.7 448.4 450.3 18760.4 18880.6 19189.1 19797.1 19806.1 17286.2 17379.7 18018.8 18579.0 18634.0 5265.8 5851.2 6081.1 6086.9 6102.2 5930.6 5987.5 6000.5 6003.7 6027.9 5851.0 5930.1 5947.5 6013.6 6014.5 5938.8 5949.9 5953.4 5955.3 5962.7 5307.6 5397.4 5406.5 5423.9 5428.8 23646.8 24300.1 24320.3 24328.3 24516.6 2451.8 2504.4 2518.0 2539.2 2545.4 9394.4 9926.4 15081.0 15081.5 15091.9 2016.1 2028.2 2035.9 2055.2 2108.9 79198.6 79394.0 79507.0 82118.6 82851.0 11668.8 11794.8 11817.8 11830.7 11953.0 16286.5 16317.0 16391.1 16431.7 16440.8 8590.6 8737.9 8742.4 8779.4 8927.2 8036.3 8066.0 8117.9 8125.1 8160.3 4580.7 4667.8 4799.5 5129.0 5135.9 1970.5 1987.8 2069.7 2141.7 2201.4 1024.3 1048.9 1112.5 1145.9 1160.5 18901.0 19149.4 19159.0 19464.9 19608.6 1031.1 1064.1 1091.2 1149.0 1149.3 10915.9 11057.9 11059.7 11062.6 11116.8 474914.0 481726.0 515704.0 517777.0 523647.0 78750.6 88646.0 91854.5 95700.0 101667.5 1121.3 1132.5 1148.3 1157.9 1167.9 17456.2 17845.2 17972.5 18225.7 18480.1 27244.3 37754.8 41547.0 42137.4 42468.4 60320.4 61337.0 61612.0 61900.4 62318.0 8072.6 8734.9 8868.9 9070.4 9104.9 12899.9 13203.9 13276.6 13348.9 14058.4 11158.4 11233.3 11438.3 11564.4 12015.0 11596.1 11788.4 11831.5 12005.9 12105.9 6371.8 8439.9 11536.0 11968.3 12424.1 8945.8 9119.7 9127.7 9214.7 9230.9 8488.1 11613.3 11638.5 11674.0 11866.9 16:35:33 INFO - PROCESS | 4613 | 16:35:33 INFO - PROCESS | 4613 | [#3] mootools.html Cycles:180 Average:5726.62 Median:5007.86 stddev:3174.87 (63.4%) stddev-sans-first:3168.00 16:35:33 INFO - PROCESS | 4613 | Values: 1514.8 1516.5 1697.6 1833.8 1874.8 8456.8 8630.8 8661.3 8725.5 8812.7 8753.0 8789.6 8792.4 8834.9 8853.3 2427.2 2462.0 2465.1 2465.4 2466.8 2484.5 2485.1 2485.7 2501.4 2501.8 2493.5 2494.2 2501.7 2508.9 2517.7 2464.0 2483.7 2491.3 2514.6 2531.6 2497.2 2517.1 2528.7 2532.4 2539.6 8764.5 8783.6 8803.5 8822.1 8833.9 5282.4 5359.8 5414.3 5415.5 5416.6 3535.5 3577.1 3585.9 3592.4 3663.6 1170.5 1188.6 1210.5 1212.0 1224.4 12251.9 12312.8 12354.5 12360.1 12386.1 4598.3 4643.7 4682.5 4686.3 4735.3 9445.1 9449.5 9463.4 9469.2 9480.6 5317.3 5356.5 5366.2 5378.4 5381.9 4348.3 4807.4 4874.0 4913.5 5022.9 3766.2 3813.1 3826.8 3850.8 3882.0 1188.9 1191.2 1191.7 1193.7 1211.4 1031.6 1039.0 1039.1 1059.6 1079.8 6050.7 6124.5 6310.9 6337.6 6405.6 435.3 438.4 439.9 439.9 456.2 4218.6 4277.2 4380.6 4390.6 4418.8 10534.3 10802.7 10881.6 10899.5 10945.9 10521.9 10620.7 10676.2 10709.1 10794.5 8327.8 8405.2 8413.9 8425.6 8505.6 5799.4 5904.8 5952.5 6030.1 6066.8 9649.8 9751.3 9875.4 9887.9 9890.3 9342.0 9739.9 9759.4 9847.5 10012.8 7150.1 7315.5 7343.8 7779.2 7902.0 7774.8 7823.8 7866.3 7880.0 7911.5 6815.5 7001.1 7019.7 7041.7 7240.2 7115.9 7168.2 7187.9 7290.7 7307.8 6612.7 6613.2 6615.0 6630.1 6666.1 4992.8 5052.4 5079.4 5087.9 5194.5 5114.3 5119.1 5126.2 5209.6 5227.9 16:35:33 INFO - PROCESS | 4613 | 16:35:33 INFO - PROCESS | 4613 | [#4] prototype.html Cycles:180 Average:5845.45 Median:6707.16 stddev:1786.25 (26.6%) stddev-sans-first:1743.01 16:35:33 INFO - PROCESS | 4613 | Values: 351.4 351.8 357.6 358.4 367.0 6585.9 6604.8 6609.7 6653.3 6691.8 6499.5 6537.2 6558.4 6565.9 6583.0 4776.1 4821.2 4911.6 4913.4 4921.9 4972.1 4991.0 4992.2 5004.4 5012.6 4950.2 4953.3 4961.8 4964.0 4969.9 4962.3 4962.9 4964.4 4968.9 4971.4 4352.6 4362.8 4366.2 4368.8 4379.2 6144.9 6233.2 6256.5 6321.3 6323.5 5757.5 5793.3 5795.2 5798.9 5812.8 6888.9 6894.2 6901.5 6901.8 6918.1 4810.5 4810.8 4827.5 4831.0 4831.5 7241.8 7264.7 7270.5 7274.6 7285.6 4809.8 4948.9 4949.6 4963.7 4964.7 4994.6 5019.3 5022.3 5025.7 5032.4 5778.9 5793.4 5796.8 5797.2 5807.2 6981.1 6987.3 6992.9 7003.6 7005.7 1752.7 1778.8 1782.8 1785.2 1787.3 1080.3 1087.1 1088.2 1180.4 1214.4 6585.8 6722.5 6757.8 6758.4 6762.5 7087.0 7124.2 7124.8 7129.7 7134.8 6889.8 6902.6 6906.9 6907.4 6910.9 3896.1 3935.2 3936.7 3941.7 3941.9 7569.2 7646.5 7648.3 7651.9 7656.8 7242.9 7418.6 7437.6 7442.8 7453.4 7591.1 7598.3 7599.8 7644.3 7653.2 7216.7 7224.4 7228.3 7229.4 7234.3 7141.6 7153.5 7155.3 7156.9 7161.2 7414.3 7548.8 7559.2 7590.9 7645.3 4803.7 4823.4 4825.4 4844.1 4846.4 7219.0 7231.3 7245.3 7245.8 7256.9 6942.1 6977.7 6986.6 6994.2 7020.2 6869.8 7008.3 7014.4 7019.3 7029.1 6844.7 6861.2 6878.8 6879.0 6897.0 6978.1 7031.5 7052.0 7055.4 7061.0 6856.2 6860.5 6860.8 6863.7 6874.0 16:35:33 INFO - PROCESS | 4613 | 16:35:33 INFO - PROCESS | 4613 | [#5] yui.html Cycles:180 Average:15997.45 Median:4370.48 stddev:18400.82 (421.0%) stddev-sans-first:18417.35 16:35:33 INFO - PROCESS | 4613 | Values: 864.7 925.7 932.1 933.8 944.5 14704.2 14922.9 15102.2 15122.0 15158.2 14111.6 15979.5 16253.2 16258.7 16267.1 889.9 908.5 911.2 911.8 912.7 760.8 884.0 899.5 921.3 921.8 947.7 949.4 950.0 954.8 955.2 932.1 941.0 942.0 943.8 945.6 688.8 689.2 690.7 692.2 695.8 15747.3 15786.7 15822.9 15926.8 15951.0 14741.7 14894.0 14910.0 14912.1 14918.6 12111.2 12230.2 12411.7 12484.1 12728.6 3821.6 3973.1 3994.5 4060.6 4165.4 59652.7 59859.0 60026.4 60476.1 60637.0 11338.2 11529.5 11544.7 11684.5 11713.8 20159.8 20386.5 20460.9 20483.3 20526.3 8540.1 8664.4 8709.0 8760.9 8824.5 4649.7 4667.5 4717.6 4718.7 4786.0 3699.0 3759.8 3762.2 3790.1 3798.9 669.8 684.2 684.3 689.6 701.5 696.5 766.5 789.3 794.8 796.7 9535.6 9959.5 9991.0 10099.5 10103.2 719.1 734.5 761.4 766.7 804.7 4575.5 4598.8 4599.7 4622.6 4726.7 60120.0 61576.7 61824.7 63897.4 64314.7 58626.0 58851.0 58882.4 58910.0 60181.7 52854.2 53648.0 54442.7 54445.2 56295.9 9968.5 10275.5 10372.4 10413.1 10569.3 37555.2 38446.2 38506.4 39837.8 40886.6 50942.5 51420.7 52029.2 52139.5 52629.7 12607.2 12642.6 12821.6 13163.3 13293.9 13541.9 14037.4 14068.2 14219.4 14299.3 13526.7 13706.2 13947.2 14001.9 14101.9 13166.3 13166.3 13215.5 13226.4 13484.8 12786.6 13231.0 13309.1 13665.3 13702.6 10734.6 10959.1 11154.0 11158.8 11301.9 10766.2 11185.8 11329.9 11405.1 11639.2 16:35:33 INFO - PROCESS | 4613 | -------- Summary: end -------- 16:35:33 INFO - PROCESS | 4613 | 16:35:34 INFO - TEST-INFO | 4613: exit 0 16:35:34 INFO - TEST-OK | dromaeo_css | took 1109395ms 16:35:34 INFO - TEST-START | kraken 16:35:34 INFO - operating with platform_type : linux_ 16:35:34 INFO - Initialising browser for kraken test... 16:35:34 INFO - TEST-INFO | started process 4858 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmBUC9e/profile http://localhost:58682/getInfo.html) 16:35:38 INFO - PROCESS | 4858 | 16:35:38 INFO - PROCESS | 4858 | (process:4932): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:35:39 INFO - PROCESS | 4858 | __metrics Screen width/height:1600/1200 16:35:39 INFO - PROCESS | 4858 | colorDepth:24 16:35:39 INFO - PROCESS | 4858 | Browser inner width/height: 1024/697 16:35:39 INFO - PROCESS | 4858 | __metrics 16:35:39 ERROR - PROCESS | 4858 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 16:35:40 INFO - TEST-INFO | 4858: exit 0 16:35:40 INFO - Browser initialized. 16:35:40 INFO - Running cycle 1/1 for kraken test... 16:35:40 INFO - TEST-INFO | started process 4982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmBUC9e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:35:42 INFO - PROCESS | 4982 | 16:35:42 INFO - PROCESS | 4982 | (process:5050): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:35:52 INFO - PROCESS | 4982 | RSS: Main: 150872064 16:35:52 INFO - PROCESS | 4982 | 16:35:54 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58682/tests/kraken/driver.html?testName=audio-beat-detection) 16:35:55 INFO - PROCESS | 4982 | RSS: Main: 155783168 16:35:55 INFO - PROCESS | 4982 | 16:35:58 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58682/tests/kraken/driver.html?testName=audio-dft) 16:35:58 INFO - PROCESS | 4982 | RSS: Main: 155734016 16:35:58 INFO - PROCESS | 4982 | 16:36:01 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58682/tests/kraken/driver.html?testName=audio-fft) 16:36:02 INFO - PROCESS | 4982 | RSS: Main: 155979776 16:36:02 INFO - PROCESS | 4982 | 16:36:04 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58682/tests/kraken/driver.html?testName=audio-oscillator) 16:36:05 INFO - PROCESS | 4982 | RSS: Main: 155926528 16:36:05 INFO - PROCESS | 4982 | 16:36:07 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58682/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:36:07 INFO - PROCESS | 4982 | RSS: Main: 155705344 16:36:07 INFO - PROCESS | 4982 | 16:36:10 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58682/tests/kraken/driver.html?testName=imaging-darkroom) 16:36:11 INFO - PROCESS | 4982 | RSS: Main: 156102656 16:36:11 INFO - PROCESS | 4982 | 16:36:14 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58682/tests/kraken/driver.html?testName=imaging-desaturate) 16:36:15 INFO - PROCESS | 4982 | RSS: Main: 156409856 16:36:15 INFO - PROCESS | 4982 | 16:36:18 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58682/tests/kraken/driver.html?testName=json-parse-financial) 16:36:18 INFO - PROCESS | 4982 | RSS: Main: 156569600 16:36:18 INFO - PROCESS | 4982 | 16:36:20 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58682/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:36:21 INFO - PROCESS | 4982 | RSS: Main: 156479488 16:36:21 INFO - PROCESS | 4982 | 16:36:22 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58682/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:36:23 INFO - PROCESS | 4982 | RSS: Main: 156487680 16:36:23 INFO - PROCESS | 4982 | 16:36:25 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58682/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:36:26 INFO - PROCESS | 4982 | RSS: Main: 156553216 16:36:26 INFO - PROCESS | 4982 | 16:36:28 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:36:29 INFO - PROCESS | 4982 | RSS: Main: 157016064 16:36:29 INFO - PROCESS | 4982 | 16:36:31 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:36:32 INFO - PROCESS | 4982 | RSS: Main: 156971008 16:36:32 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:58682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58682/tests/kraken/driver.html?testName=ai-astar) 16:36:34 INFO - PROCESS | 4982 | RSS: Main: 158187520 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | __start_tp_report 16:36:34 INFO - PROCESS | 4982 | _x_x_mozilla_page_load 16:36:34 INFO - PROCESS | 4982 | _x_x_mozilla_page_load_details 16:36:34 INFO - PROCESS | 4982 | |i|pagename|runs| 16:36:34 INFO - PROCESS | 4982 | |0;ai-astar;110;110;108;112;111;110;102;109;110;112 16:36:34 INFO - PROCESS | 4982 | |1;audio-beat-detection;160;127;153;179;127;150;156;127;180;127 16:36:34 INFO - PROCESS | 4982 | |2;audio-dft;170;162;165;162;161;163;161;165;161;162 16:36:34 INFO - PROCESS | 4982 | |3;audio-fft;76;75;75;180;76;78;74;76;173;76 16:36:34 INFO - PROCESS | 4982 | |4;audio-oscillator;93;92;166;92;93;92;149;94;92;158 16:36:34 INFO - PROCESS | 4982 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;117;116;117 16:36:34 INFO - PROCESS | 4982 | |6;imaging-darkroom;150;150;149;148;146;146;146;146;146;146 16:36:34 INFO - PROCESS | 4982 | |7;imaging-desaturate;96;95;95;96;95;95;95;96;95;96 16:36:34 INFO - PROCESS | 4982 | |8;json-parse-financial;57;56;57;58;62;60;58;73;56;57 16:36:34 INFO - PROCESS | 4982 | |9;json-stringify-tinderbox;57;57;57;58;58;58;57;59;58;58 16:36:34 INFO - PROCESS | 4982 | |10;stanford-crypto-aes;75;74;72;73;74;80;74;74;75;73 16:36:34 INFO - PROCESS | 4982 | |11;stanford-crypto-ccm;123;117;114;111;115;112;112;114;137;138 16:36:34 INFO - PROCESS | 4982 | |12;stanford-crypto-pbkdf2;162;167;163;162;166;162;161;163;163;169 16:36:34 INFO - PROCESS | 4982 | |13;stanford-crypto-sha256-iterative;67;66;66;66;66;65;70;68;65;67 16:36:34 INFO - PROCESS | 4982 | __end_tp_report 16:36:34 INFO - PROCESS | 4982 | __start_cc_report 16:36:34 INFO - PROCESS | 4982 | _x_x_mozilla_cycle_collect,848 16:36:34 INFO - PROCESS | 4982 | __end_cc_report 16:36:34 INFO - PROCESS | 4982 | __startTimestamp1468193794313__endTimestamp 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | ------- Summary: start ------- 16:36:34 INFO - PROCESS | 4982 | Number of tests: 14 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:2.88 (2.6%) stddev-sans-first:3.04 16:36:34 INFO - PROCESS | 4982 | Values: 110.0 110.0 108.0 112.0 111.0 110.0 102.0 109.0 110.0 112.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#1] audio-beat-detection Cycles:10 Average:148.60 Median:154.50 stddev:21.04 (13.6%) stddev-sans-first:21.90 16:36:34 INFO - PROCESS | 4982 | Values: 160.0 127.0 153.0 179.0 127.0 150.0 156.0 127.0 180.0 127.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#2] audio-dft Cycles:10 Average:163.20 Median:162.50 stddev:2.82 (1.7%) stddev-sans-first:1.59 16:36:34 INFO - PROCESS | 4982 | Values: 170.0 162.0 165.0 162.0 161.0 163.0 161.0 165.0 161.0 162.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#3] audio-fft Cycles:10 Average:95.90 Median:76.00 stddev:42.52 (56.0%) stddev-sans-first:44.49 16:36:34 INFO - PROCESS | 4982 | Values: 76.0 75.0 75.0 180.0 76.0 78.0 74.0 76.0 173.0 76.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#4] audio-oscillator Cycles:10 Average:112.10 Median:92.00 stddev:31.71 (34.5%) stddev-sans-first:32.87 16:36:34 INFO - PROCESS | 4982 | Values: 93.0 92.0 166.0 92.0 93.0 92.0 149.0 94.0 92.0 158.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 16:36:34 INFO - PROCESS | 4982 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 116.0 117.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:1.77 (1.2%) stddev-sans-first:1.58 16:36:34 INFO - PROCESS | 4982 | Values: 150.0 150.0 149.0 148.0 146.0 146.0 146.0 146.0 146.0 146.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 16:36:34 INFO - PROCESS | 4982 | Values: 96.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 96.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:58.00 stddev:5.13 (8.8%) stddev-sans-first:5.36 16:36:34 INFO - PROCESS | 4982 | Values: 57.0 56.0 57.0 58.0 62.0 60.0 58.0 73.0 56.0 57.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#9] json-stringify-tinderbox Cycles:10 Average:57.70 Median:58.00 stddev:0.67 (1.2%) stddev-sans-first:0.67 16:36:34 INFO - PROCESS | 4982 | Values: 57.0 57.0 57.0 58.0 58.0 58.0 57.0 59.0 58.0 58.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:74.00 stddev:2.17 (2.9%) stddev-sans-first:2.29 16:36:34 INFO - PROCESS | 4982 | Values: 75.0 74.0 72.0 73.0 74.0 80.0 74.0 74.0 75.0 73.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#11] stanford-crypto-ccm Cycles:10 Average:119.30 Median:116.00 stddev:10.18 (8.8%) stddev-sans-first:10.71 16:36:34 INFO - PROCESS | 4982 | Values: 123.0 117.0 114.0 111.0 115.0 112.0 112.0 114.0 137.0 138.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.80 Median:163.00 stddev:2.62 (1.6%) stddev-sans-first:2.69 16:36:34 INFO - PROCESS | 4982 | Values: 162.0 167.0 163.0 162.0 166.0 162.0 161.0 163.0 163.0 169.0 16:36:34 INFO - PROCESS | 4982 | 16:36:34 INFO - PROCESS | 4982 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:66.50 stddev:1.51 (2.3%) stddev-sans-first:1.59 16:36:34 INFO - PROCESS | 4982 | Values: 67.0 66.0 66.0 66.0 66.0 65.0 70.0 68.0 65.0 67.0 16:36:34 INFO - PROCESS | 4982 | -------- Summary: end -------- 16:36:34 INFO - PROCESS | 4982 | 16:36:35 INFO - TEST-INFO | 4982: exit 0 16:36:35 INFO - TEST-OK | kraken | took 60619ms 16:36:35 INFO - SUITE-END | took 1170s 16:36:35 INFO - Completed test suite (00:19:30) 16:36:35 INFO - javascript benchmark 16:36:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3355.647656996016, 3425.5375524304227, 3426.16059065529, 3469.583649962005, 3470.84381202846, 5653.915191272129, 5655.066913959192, 5659.349174844894, 5666.858328541788, 5674.51766599839, 5578.888422231553, 5661.943380566193, 5665.093585026397, 5665.433456654332, 5690.914636280449, 5129.692218466886, 5152.587792976572, 5161.612879034069, 5179.404368497614, 5247.422783493813, 5105.923411148836, 5232.529072383485, 5295.0468915595175, 5304.469553044684, 5330.626856120073, 5224.712640804754, 5245.527902488775, 5287.920681189766, 5289.735513224343, 5292.788288468457, 5242.659227150242, 5250.894982100355, 5281.102212623863, 5282.392524859613, 5286.365636123652, 4978.427480839678, 5091.949080509185, 5153.2270159476, 5170.25031370453, 5231.372235331784, 5510.421405752379, 5532.5573954083575, 5550.084236101038, 5570.470805273492, 5596.972015139898, 6027.216461859974, 6046.0, 6069.211002569682, 6071.787487437919, 6079.331273559905, 6040.154378387029, 6102.389761023862, 6120.14317995481, 6125.050617154348, 6151.384861513858, 5603.2435621191025, 5608.046632072557, 5609.326880774333, 5619.662820230799, 5645.096784514499, 6117.480014198754, 6120.449159575571, 6121.296050954108, 6124.6325220486915, 6127.080937859357, 5895.793647222413, 5896.498797602252, 5940.910886336708, 6007.609505382136, 6028.8794224116145, 5986.970065149646, 5997.880042399215, 6006.519478441714, 6009.06859436788, 6010.939890601126, 5902.291724993029, 5916.497097746654, 5954.3152537458745, 5959.523238140939, 5972.25346831646, 5915.3788852170765, 5939.4357536034, 5999.220101386792, 6014.639121652627, 6020.69896505173, 4575.725456472623, 4596.149712303168, 4598.356230127785, 4603.0, 4633.7683115844075, 4104.384342348671, 4116.5060192777055, 4127.814248358771, 4138.627523522898, 4148.543660197376, 5736.0, 5781.797637082766, 5892.852678683067, 5930.0, 5964.7315870785915, 6005.789797357072, 6064.272287325549, 6065.727042283108, 6068.241469816273, 6110.663913484801, 5745.85635359111, 5800.303963524404, 5805.506531944832, 5825.300963884361, 5837.3870743571315, 5147.893202961382, 5178.6374953752875, 5185.429602743695, 5197.558207552324, 5215.973920130375, 6264.812055638338, 6307.684615769193, 6314.778982735582, 6316.178896743486, 6353.96823015882, 5955.285365756137, 6001.039833626599, 6080.118382834553, 6085.908711369421, 6088.299845517822, 6194.535409844189, 6216.036514340284, 6250.656213908279, 6252.499800015897, 6263.060540918898, 5924.7037648118285, 5944.464998150187, 5954.07711804671, 5954.583179177416, 5962.70186490665, 5930.140129681258, 6046.30467496226, 6064.423879731418, 6068.4538391544975, 6129.203203583684, 5816.214811000461, 5837.241158649348, 5840.795572154954, 5845.473907348359, 5868.823935281948, 5783.971080144573, 5791.160281759037, 5819.243498345188, 5822.854428639318, 5837.066069428871, 5668.178114173505, 5739.713014349182, 5793.478586927196, 5883.264591926009, 5932.436418540232, 5912.763489460373, 5941.58408911372, 5955.076963070731, 5956.702164891824, 5977.641341519523, 5829.795957141479, 5833.241678581757, 5838.0, 5844.1525978732, 5846.181534585076], "value": 5586.424533545796, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.1061041068531, 672.2135101930741, 673.7237732529663, 721.765426236473, 730.4302643937729, 32553.837230813842, 33286.50070248948, 40036.99999999996, 40559.39160912589, 40693.186136277254, 43240.13519729603, 43949.0, 43987.0, 43987.78006109965, 44037.33943990835, 8808.867866981984, 8839.381243312939, 8842.955785221066, 8887.377883548153, 8972.0, 8873.467591944504, 8973.820523589524, 8976.41653292534, 8992.730218093468, 9005.684801031965, 8974.50640214788, 9256.537173141349, 9301.06989301068, 9307.395019323721, 9320.72037838866, 9123.771905702377, 9148.725538233864, 9213.355065145442, 9223.07769223079, 9232.6306947722, 9035.683751068682, 9123.08769123089, 9123.361364704408, 9133.132352426508, 9136.45181289118, 44015.0, 44542.777286113684, 44658.0, 44736.10527789459, 44835.32747008798, 3604.1502939625902, 3648.525691660094, 3677.7793332399874, 3714.1457464783252, 3927.960720392788, 33963.66036339654, 34049.82975085109, 34136.14659633504, 34462.65537344619, 34496.0, 4517.503074661785, 4651.046535460223, 4713.5993440557595, 4735.218688916325, 4771.832985845479, 306898.0, 307852.0, 308947.0, 308978.4551077253, 309097.0, 39747.403788943186, 40422.19155616871, 64998.67500662561, 65453.0, 65565.67217163884, 38405.0, 38651.80674096668, 38656.42015369772, 38750.6124938747, 38780.0, 15969.0, 16118.919405403132, 16134.677306453805, 16146.515604531647, 16192.109433981244, 13173.407196676173, 13196.538121165713, 13305.268210248529, 13348.599542013755, 13362.532311369056, 9219.585118669676, 9475.478848663251, 9523.095305945924, 9547.665831695856, 9579.042095790364, 3605.2789442111684, 3673.4489826526233, 3684.1158122050515, 3693.0582701410967, 3702.8889133326043, 1107.8338249262674, 1108.7172770943357, 1124.245598284207, 1146.8165093584987, 1157.3345326437332, 33678.32643347119, 33860.66139338575, 33924.49113263303, 34048.319033619686, 34496.0, 1075.2043487819114, 1141.5547936304847, 1156.9942150289194, 1158.692946369213, 1249.1630607493003, 17438.825611743974, 17503.47489575315, 17675.40921317062, 17766.46700598984, 18006.0, 221522.0, 505430.0, 516802.0, 519540.0, 520929.0, 56000.71999640057, 56969.43030569641, 57111.28666140023, 57130.428695713344, 57132.42867571354, 1512.2136489025645, 1528.1900592685895, 1529.3041666042147, 1532.1496569403864, 1572.4889410941262, 30961.0, 31243.375132497677, 31498.212544685648, 32214.19464513406, 32365.0, 55937.44062559485, 60804.391956079875, 61940.69029654823, 62005.37994619996, 62103.37896621157, 415515.0, 422864.0, 422885.0, 423682.0, 425163.0, 47524.2871356923, 47560.52439475561, 47860.760696196296, 48193.0, 48332.516674832805, 52406.0, 53316.73341633267, 53615.73192134014, 53629.90331183046, 53820.0, 43226.78386608047, 43684.34473482964, 43746.0, 44250.0, 44937.0, 43718.56281437272, 43917.560824391345, 43960.0, 44300.556994429644, 44826.10347792961, 44120.55879441164, 44225.33661995137, 44496.0, 44709.10581788411, 45239.09521809611, 32764.672353277114, 33169.83415082909, 33499.83250083734, 33559.16102097467, 33844.661553384154, 26563.867180664747, 27235.182944511696, 29470.263243419085, 29723.0, 29881.252968675955], "value": 19805.268015940288, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.9783510824458, 436.14448277229286, 444.6816079686944, 448.3924282597081, 450.2997838361347, 18760.4371868844, 18880.62238755224, 19189.136488858, 19797.109130089146, 19806.10872510737, 17286.222120004597, 17379.73930391045, 18018.819811801877, 18579.0, 18634.0, 5265.76304066318, 5851.15158302046, 6081.118237855509, 6086.908696369558, 6102.206713127287, 5930.555208359368, 5987.491063259628, 6000.459958603747, 6003.6697981611, 6027.9095813562835, 5850.970745146268, 5930.110483427477, 5947.464728174464, 6013.639181649093, 6014.488768454687, 5938.8218353449465, 5949.9107513387335, 5953.404659534055, 5955.255593050869, 5962.672053037081, 5307.628466007381, 5397.433269506724, 5406.54044406226, 5423.945760542383, 5428.782848686048, 23646.76353236463, 24300.149494767775, 24320.270391888273, 24328.27015189547, 24516.632250516257, 2451.7915977141965, 2504.3864253257884, 2518.0431436054364, 2539.1874600127967, 2545.4272788622598, 9394.389364691273, 9926.354786938899, 15081.019733717274, 15081.472148474752, 15091.943563950528, 2016.0927582587894, 2028.2292728763123, 2035.8574899757023, 2055.2498338106634, 2108.8945552722303, 79198.60400698017, 79394.0, 79507.0, 82118.58940705318, 82851.0, 11668.782931243037, 11794.764104717857, 11817.763644727058, 11830.704232394088, 11953.043756499459, 16286.51140465788, 16317.0, 16391.098489583186, 16431.671366572602, 16440.83559164417, 8590.570471476402, 8737.91262087371, 8742.431741936754, 8779.429337093195, 8927.24118449926, 8036.276735093869, 8066.0, 8117.878231826528, 8125.146859579659, 8160.3471722262075, 4580.748058856728, 4667.766611669403, 4799.544043315949, 5129.0, 5135.87160320995, 1970.5369238229005, 1987.8012198780295, 2069.6688529835455, 2141.668041453577, 2201.4276288165192, 1024.257413375312, 1048.9475526223657, 1112.4660163121746, 1145.9484323205477, 1160.512584714422, 18901.0, 19149.425517234504, 19159.0, 19464.890291524058, 19608.607827843647, 1031.128696251666, 1064.1327318235612, 1091.165258577189, 1149.0, 1149.2874417860812, 10915.890841091488, 11057.944710276397, 11059.728131264847, 11062.557497700163, 11116.833247501294, 474914.0, 481726.0, 515704.0, 517777.0, 523647.0, 78750.6062469684, 88646.0, 91854.54072729594, 95700.0, 101667.49166254122, 1121.2711737370773, 1132.4903793293051, 1148.3397046698014, 1157.89578937896, 1167.8832116788424, 17456.214470348612, 17845.196966136813, 17972.460826175236, 18225.726614100535, 18480.075996200405, 27244.31889202786, 37754.8112259437, 41547.0, 42137.36793948032, 42468.36297455479, 60320.39679603148, 61337.0, 61612.0, 61900.38099618946, 62318.0, 8072.59637018135, 8734.868976965223, 8868.86696699537, 9070.365074444726, 9104.90895091067, 12899.871001289866, 13203.933980330037, 13276.601701948957, 13348.93325533366, 14058.367373468014, 11158.44207789591, 11233.32600044, 11438.256513326607, 11564.363959982282, 12015.0, 11596.130290228099, 11788.410579470821, 11831.46758395857, 12005.939970300093, 12105.939470302592, 6371.840703982438, 8439.915600843913, 11536.0, 11968.341741204318, 12424.0681948856, 8945.776355591162, 9119.726408207764, 9127.680531181377, 9214.677486287948, 9230.907690923004, 8488.05307527306, 11613.30320180792, 11638.476268567765, 11674.007709344802, 11866.881331186923], "value": 9525.256922511326, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.8182218133825, 1516.5450364890532, 1697.5840918974852, 1833.7707786526692, 1874.7656542932134, 8456.830863382736, 8630.827383452328, 8661.26379257763, 8725.476471411712, 8812.691555795533, 8752.956235218815, 8789.560521973908, 8792.428492148007, 8834.86747698785, 8853.291736661067, 2427.2354208424335, 2461.950760984779, 2465.0879174705296, 2465.3959779853963, 2466.7779899809016, 2484.4534202475515, 2485.142625794098, 2485.6768620079456, 2501.4496810701617, 2501.774840264376, 2493.488834788873, 2494.2143224884103, 2501.662275592799, 2508.8745562721883, 2517.697876254843, 2464.039024780337, 2483.714372847128, 2491.2650768023386, 2514.6102354135046, 2531.556977528934, 2497.1509686706427, 2517.1315896015844, 2528.683914510686, 2532.430203204283, 2539.5682733935278, 8764.474131552064, 8783.638536026861, 8803.471791692518, 8822.073682263399, 8833.867491987625, 5282.366116066097, 5359.785608575652, 5414.3232095988005, 5415.512603865671, 5416.620836541444, 3535.4520049392127, 3577.105723569108, 3585.8744943926836, 3592.4072528032843, 3663.5786884508357, 1170.5259369955181, 1188.566173346731, 1210.5278941212935, 1212.0182652051867, 1224.4428784902848, 12251.877481225163, 12312.81530777039, 12354.505819767199, 12360.134790564663, 12386.071044671686, 4598.26427771555, 4643.698159619614, 4682.508336624674, 4686.250199968057, 4735.266033764772, 9445.0554944505, 9449.480278584606, 9463.38487998278, 9469.195118414875, 9480.57337419804, 5317.335333083364, 5356.544693701079, 5366.168243922199, 5378.381486129067, 5381.892362152735, 4348.304271316574, 4807.375041244686, 4874.0, 4913.533214344631, 5022.899542009212, 3766.152615661454, 3813.0848596336677, 3826.789526576065, 3850.7882066486613, 3881.990682422534, 1188.8811118888043, 1191.1542804608825, 1191.7318603314186, 1193.6896406934259, 1211.4003568233604, 1031.6131450705984, 1039.0388890276463, 1039.0752230514847, 1059.6132411669685, 1079.7786453776957, 6050.667213303311, 6124.5100391968535, 6310.905336419957, 6337.619742815354, 6405.551611387158, 435.3382858055738, 438.4081489988554, 439.93181056936226, 439.9428074350314, 456.19253920560425, 4218.6414154797185, 4277.230098582285, 4380.6057454829215, 4390.62679672225, 4418.823247070145, 10534.315269507457, 10802.67591972242, 10881.564737410574, 10899.455027248765, 10945.945270273598, 10521.947390263, 10620.681379558626, 10676.199285053497, 10709.143268538499, 10794.46027698612, 8327.750167494985, 8405.201505857056, 8413.915860841436, 8425.620847061897, 8505.61724722389, 5799.362070172362, 5904.822855314347, 5952.494038006645, 6030.1256317832795, 6066.848328791815, 9649.758756031155, 9751.268654851056, 9875.40747555149, 9887.85168222463, 9890.258230632588, 9342.0, 9739.9026009739, 9759.365641233297, 9847.507624618884, 10012.84980725275, 7150.10623672041, 7315.524490908074, 7343.77968660941, 7779.183185765413, 7902.0, 7774.805629859299, 7823.765287041398, 7866.292033716993, 7880.0, 7911.485753426009, 6815.488838337045, 7001.089858318386, 7019.719211231493, 7041.683124259318, 7240.239774823568, 7115.928840711526, 7168.17565979919, 7187.928120718726, 7290.744823931349, 7307.817304567216, 6612.669366531751, 6613.173353330833, 6615.040819081302, 6630.138082049302, 6666.066750655009, 4992.825251116194, 5052.368453943257, 5079.3650793650795, 5087.872803179802, 5194.480551944926, 5114.266585392758, 5119.129747942784, 5126.2054381570915, 5209.6092793041435, 5227.89544209106], "value": 4573.187368819492, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.4061236510298, 351.83287938229347, 357.5959166142262, 358.4104148675427, 366.957799853017, 6585.868282634357, 6604.778116048535, 6609.669516524166, 6653.2681405045405, 6691.799246022621, 6499.480041596673, 6537.248216455109, 6558.376954189344, 6565.934340656592, 6582.96708516458, 4776.092542416938, 4821.180399332105, 4911.582515486188, 4913.410390753114, 4921.876953076184, 4972.129877271481, 4990.950090499085, 4992.151334273164, 5004.374453193351, 5012.598992080625, 4950.183219768752, 4953.331300274471, 4961.826336078238, 4963.975180124095, 4969.900601987958, 4962.280469331927, 4962.925556116661, 4964.379452568429, 4968.925466118011, 4971.37857767779, 4352.586504282088, 4362.781860906974, 4366.235908715962, 4368.7815609219415, 4379.167958087953, 6144.87710245797, 6233.158523599302, 6256.499480041632, 6321.304656487829, 6323.494120470351, 5757.4818266355815, 5793.275840519935, 5795.2176456178295, 5798.855028624318, 5812.796552120655, 6888.896666550006, 6894.172699276119, 6901.482388820858, 6901.792946211622, 6918.100646915919, 4810.542998415145, 4810.831620893252, 4827.51724827518, 4831.0096430231715, 4831.516848315176, 7241.782746517613, 7264.745733899288, 7270.491065625409, 7274.563526188445, 7285.5628662279905, 4809.759512024455, 4948.925766113512, 4949.604031677457, 4963.70217786934, 4964.702117872867, 4994.601511576764, 5019.272205530251, 5022.3219865317715, 5025.698458092526, 5032.370953630796, 5778.942210577925, 5793.362730099686, 5796.768129274866, 5797.188393624896, 5807.157962095473, 6981.127359080115, 6987.26633703464, 6992.895106573405, 7003.5797852129035, 7005.684744186524, 1752.7020406530921, 1778.8221177882367, 1782.8128046554925, 1785.2234278089072, 1787.3297513432462, 1080.2924084724518, 1087.0922779479224, 1088.151242031217, 1180.3508070561156, 1214.4352875912743, 6585.83535411609, 6722.529422940347, 6757.763478278237, 6758.425533829582, 6762.4928130389435, 7087.0, 7124.180719217356, 7124.821879453055, 7129.714811407485, 7134.821629459305, 6889.758858439931, 6902.6203558803745, 6906.896396554056, 6907.447404207652, 6910.930890691029, 3896.103896103912, 3935.1932853765484, 3936.724429289922, 3941.743786653916, 3941.921161576752, 7569.167391586921, 7646.541207527566, 7648.349890259279, 7651.885221721679, 7656.808579785438, 7242.891356629655, 7418.590467811098, 7437.553746775211, 7442.776716698508, 7453.440991925628, 7591.089069311718, 7598.316151546387, 7599.848003039909, 7644.3120119188425, 7653.158152603321, 7216.7474138404905, 7224.385927196037, 7228.31331023552, 7229.385502232369, 7234.348908598251, 7141.642917853983, 7153.463490238149, 7155.284471552803, 7156.892646610409, 7161.176464706626, 7414.295641914009, 7548.773536793905, 7559.2408770797165, 7590.924090759022, 7645.311921926831, 4803.711777293373, 4823.3729615149805, 4825.372701548777, 4844.103840789465, 4846.442659094108, 7218.9893414923, 7231.2768723129375, 7245.311695388929, 7245.818854528679, 7256.89114663291, 6942.132233470817, 6977.686004129725, 6986.6157361343585, 6994.160700715946, 7020.22777494465, 6869.793906182823, 7008.299170083154, 7014.403775679124, 7019.29807019294, 7029.0862187915245, 6844.657767111525, 6861.210960739579, 6878.793636190922, 6878.965605172142, 6896.965515172392, 6978.057962175041, 7031.542949708252, 7051.964740176266, 7055.3650171484815, 7061.0, 6856.24581296067, 6860.451163906975, 6860.794176174722, 6863.691133899087, 6874.037634731041], "value": 5257.191868325002, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [864.7492227254095, 925.6621333213377, 932.105179028133, 933.7712260496183, 944.5371767833764, 14704.191269480174, 14922.925385373072, 15102.244887755596, 15122.0, 15158.166300853449, 14111.647208819784, 15979.520614381587, 16253.18734063298, 16258.674826503462, 16267.10530920799, 889.8932128144631, 908.4730856103445, 911.2072496927649, 911.7538264668535, 912.7489940266447, 760.7565579014718, 883.995580022099, 899.4872922434215, 921.2767977137948, 921.8478950973081, 947.6967370441462, 949.3591825517782, 949.9857502137473, 954.7565370830429, 955.1594596754846, 932.1191474057019, 941.0213378086781, 941.9811603767921, 943.8253923024193, 945.5744914788338, 688.7658196213262, 689.2452764223168, 690.702997710984, 692.1866806502339, 695.8121307247065, 15747.291371888292, 15786.684266314724, 15822.920885395499, 15926.840731592652, 15951.0, 14741.705165896728, 14894.0, 14910.0, 14912.105273683614, 14918.62703432412, 12111.152219344649, 12230.205036672587, 12411.689707757288, 12484.063695222894, 12728.61814145577, 3821.617838216184, 3973.0861901762755, 3994.520657521087, 4060.634542891154, 4165.416841642173, 59652.70173649104, 59859.0, 60026.39973600252, 60476.09285860715, 60637.0, 11338.206325557132, 11529.48117334722, 11544.653660390202, 11684.532618695326, 11713.765724685458, 20159.798402016084, 20386.49033774138, 20460.897695511725, 20483.283085092247, 20526.281580144623, 8540.14598540141, 8664.350173736995, 8708.95645521781, 8760.912390876138, 8824.470531768115, 4649.744264065441, 4667.533246675305, 4717.622590192845, 4718.693284936468, 4786.042791441726, 3698.963010369862, 3759.774413535197, 3762.209935913434, 3790.0714324990554, 3798.9050273742823, 669.8291935556402, 684.2131548719013, 684.2575805251258, 689.6344937183301, 701.5089437393844, 696.4846013949742, 766.4864540757706, 789.3014682006466, 794.7615715285391, 796.653455746752, 9535.570899309549, 9959.502024898871, 9990.950045249727, 10099.495025248854, 10103.19174466041, 719.1226703421817, 734.4968696442953, 761.3680645064583, 766.6703317573497, 804.7424824056246, 4575.5424457554645, 4598.816047358135, 4599.7470139142, 4622.6301895848255, 4726.669133160645, 60120.0, 61576.69211654003, 61824.690876545326, 63897.36102639007, 64314.67842660757, 58625.99999999915, 58851.0, 58882.41117588855, 58910.0, 60181.69909150426, 52854.20718689223, 53648.0, 54442.72778636082, 54445.183322250195, 56295.87408251812, 9968.451735154636, 10275.537600807833, 10372.429516376369, 10413.062824345543, 10569.260151789302, 37555.2488950214, 38446.23107537778, 38506.422403664525, 39837.80081099576, 40886.59113408828, 50942.49057509526, 51420.74289628528, 52029.21956170736, 52139.47860521498, 52629.7368513155, 12607.243565386107, 12642.620721378373, 12821.615351539469, 13163.276019819003, 13293.933530332673, 13541.932290338485, 14037.438502459787, 14068.226247556482, 14219.431222751387, 14299.285035747964, 13526.661833453849, 13706.177629342272, 13947.16317020982, 14001.859981400463, 14101.929490352893, 13166.341682915625, 13166.341682915625, 13215.537456188988, 13226.4048117833, 13484.797728034284, 12786.61640150797, 13231.0, 13309.134906231064, 13665.316734163052, 13702.588922332347, 10734.624288149877, 10959.123270138529, 11154.0, 11158.772535020978, 11301.886981130412, 10766.246362754846, 11185.776284474428, 11329.943350283196, 11405.087592992706, 11639.243449175776], "value": 7295.057620644012, "unit": "score"}], "name": "dromaeo_css", "value": 7547.532707061392, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 108.0, 112.0, 111.0, 110.0, 102.0, 109.0, 110.0, 112.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 127.0, 153.0, 179.0, 127.0, 150.0, 156.0, 127.0, 180.0, 127.0], "value": 148.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 162.0, 165.0, 162.0, 161.0, 163.0, 161.0, 165.0, 161.0, 162.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 180.0, 76.0, 78.0, 74.0, 76.0, 173.0, 76.0], "value": 95.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 166.0, 92.0, 93.0, 92.0, 149.0, 94.0, 92.0, 158.0], "value": 112.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 149.0, 148.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 58.0, 62.0, 60.0, 58.0, 73.0, 56.0, 57.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 58.0, 58.0, 58.0, 57.0, 59.0, 58.0, 58.0], "value": 57.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 72.0, 73.0, 74.0, 80.0, 74.0, 74.0, 75.0, 73.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 117.0, 114.0, 111.0, 115.0, 112.0, 112.0, 114.0, 137.0, 138.0], "value": 119.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 167.0, 163.0, 162.0, 166.0, 162.0, 161.0, 163.0, 163.0, 169.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 66.0, 65.0, 70.0, 68.0, 65.0, 67.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1530.1, "alertThreshold": 2.0}]} 16:36:35 INFO - Return code: 0 16:36:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:36:35 ERROR - # TBPL SUCCESS # 16:36:35 INFO - Running post-action listener: _resource_record_post_action 16:36:35 INFO - Running post-run listener: _resource_record_post_run 16:36:36 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 28672; Write bytes: 222949376; Read time: 232; Write time: 126400 16:36:36 INFO - install - Wall time: 13s; CPU: 13.0%; Read bytes: 0; Write bytes: 4661248; Read time: 0; Write time: 444 16:36:36 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 217751552; Read time: 220; Write time: 125956 16:36:36 INFO - Running post-run listener: _upload_blobber_files 16:36:36 INFO - Blob upload gear active. 16:36:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:36:36 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 16:36:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:36:37 INFO - (blobuploader) - INFO - Open directory for files ... 16:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:36:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:37 INFO - (blobuploader) - INFO - Done attempting. 16:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:36:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:38 INFO - (blobuploader) - INFO - Done attempting. 16:36:38 INFO - (blobuploader) - INFO - Iteration through files over. 16:36:38 INFO - Return code: 0 16:36:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:36:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:36:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0bd5bc5e3475ecebe59ab2f199f56d85812bbb5e35d8f070b21524072a74a758059addba00eb49204220b7352151fed8f03022a0b9d5d72a3da729a91815c2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b92c8160f56a7132ac11109128fc9cd501c8b39a8ee05f3d957e046a3268ba33dd4ee0b261d0bda0c101f42f8884f705dda95eb4c776c728e1301f4053128ca"} 16:36:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:36:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:36:38 INFO - Contents: 16:36:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0bd5bc5e3475ecebe59ab2f199f56d85812bbb5e35d8f070b21524072a74a758059addba00eb49204220b7352151fed8f03022a0b9d5d72a3da729a91815c2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b92c8160f56a7132ac11109128fc9cd501c8b39a8ee05f3d957e046a3268ba33dd4ee0b261d0bda0c101f42f8884f705dda95eb4c776c728e1301f4053128ca"} 16:36:38 INFO - Running post-run listener: copy_logs_to_upload_dir 16:36:38 INFO - Copying logs to upload dir... 16:36:38 INFO - mkdir: /builds/slave/test/build/upload/logs 16:36:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.693088 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-10 16:36:38.553937) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 16:36:38.557972) ========= 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=eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0bd5bc5e3475ecebe59ab2f199f56d85812bbb5e35d8f070b21524072a74a758059addba00eb49204220b7352151fed8f03022a0b9d5d72a3da729a91815c2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b92c8160f56a7132ac11109128fc9cd501c8b39a8ee05f3d957e046a3268ba33dd4ee0b261d0bda0c101f42f8884f705dda95eb4c776c728e1301f4053128ca"} build_url:https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/O1I7Jb2iQ3Om0QKoPwKjfA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0bd5bc5e3475ecebe59ab2f199f56d85812bbb5e35d8f070b21524072a74a758059addba00eb49204220b7352151fed8f03022a0b9d5d72a3da729a91815c2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b92c8160f56a7132ac11109128fc9cd501c8b39a8ee05f3d957e046a3268ba33dd4ee0b261d0bda0c101f42f8884f705dda95eb4c776c728e1301f4053128ca"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 16:36:38.584402) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 16:36:38.584738) ========= 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=eca2ab57546de7b41f256b19000001ef-1468190974.637238-1306721470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 16:36:38.668525) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 16:36:38.668847) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 16:36:38.669167) ========= ========= Total master_lag: 0.17 =========