builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-030 starttime: 1461089749.35 results: success (0) buildid: 20160419102742 builduid: cea07ba26b334d06a1612cfd872f6d79 revision: 3b3c1809c8be95512093a3cf19d1a35e99ad145c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:49.350472) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:49.351496) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:49.352222) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:49.365936) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:49.366252) ========= 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=4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-19 11:15:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.8M=0s 2016-04-19 11:15:49 (50.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123219 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:49.505544) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:49.506249) ========= 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=4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014805 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:49.558423) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 11:15:49.559177) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3b3c1809c8be95512093a3cf19d1a35e99ad145c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 3b3c1809c8be95512093a3cf19d1a35e99ad145c --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=4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-19 11:15:49,721 truncating revision to first 12 chars 2016-04-19 11:15:49,721 Setting DEBUG logging. 2016-04-19 11:15:49,721 attempt 1/10 2016-04-19 11:15:49,721 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3b3c1809c8be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 11:15:50,670 unpacking tar archive at: fx-team-3b3c1809c8be/testing/mozharness/ program finished with exit code 0 elapsedTime=1.278006 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 11:15:50.855798) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:50.856581) ========= script_repo_revision: 3b3c1809c8be95512093a3cf19d1a35e99ad145c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:50.857051) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:50.857354) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 11:15:50.867079) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-04-19 11:15:50.867596) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:15:51 INFO - MultiFileLogger online at 20160419 11:15:51 in /builds/slave/test 11:15:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 11:15:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:15:51 INFO - {'append_to_log': False, 11:15:51 INFO - 'base_work_dir': '/builds/slave/test', 11:15:51 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 11:15:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:15:51 INFO - 'branch': 'Fx-Team-Non-PGO', 11:15:51 INFO - 'buildbot_json_path': 'buildprops.json', 11:15:51 INFO - 'config_files': ('talos/linux_config.py',), 11:15:51 INFO - 'default_actions': ('clobber', 11:15:51 INFO - 'read-buildbot-config', 11:15:51 INFO - 'download-and-extract', 11:15:51 INFO - 'populate-webroot', 11:15:51 INFO - 'create-virtualenv', 11:15:51 INFO - 'install', 11:15:51 INFO - 'run-tests'), 11:15:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:15:51 INFO - 'download_minidump_stackwalk': True, 11:15:51 INFO - 'download_symbols': 'ondemand', 11:15:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:15:51 INFO - 'tooltool.py': '/tools/tooltool.py', 11:15:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:15:51 INFO - '/tools/misc-python/virtualenv.py')}, 11:15:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:15:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:15:51 INFO - 'installer_path': 'installer.exe', 11:15:51 INFO - 'log_level': 'info', 11:15:51 INFO - 'log_name': 'talos', 11:15:51 INFO - 'log_to_console': True, 11:15:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:15:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:15:51 INFO - 'opt_config_files': (), 11:15:51 INFO - 'pip_index': False, 11:15:51 INFO - 'sps_profile': False, 11:15:51 INFO - 'sps_profile_interval': 0, 11:15:51 INFO - 'suite': 'dromaeojs-e10s', 11:15:51 INFO - 'system_bits': '32', 11:15:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:15:51 INFO - 'title': 'talos-linux64-ix-030', 11:15:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:15:51 INFO - 'use_talos_json': True, 11:15:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:15:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:15:51 INFO - 'work_dir': 'build'} 11:15:51 INFO - ##### 11:15:51 INFO - ##### Running clobber step. 11:15:51 INFO - ##### 11:15:51 INFO - Running pre-action listener: _resource_record_pre_action 11:15:51 INFO - Running main action method: clobber 11:15:51 INFO - rmtree: /builds/slave/test/build 11:15:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:15:51 INFO - Running post-action listener: _resource_record_post_action 11:15:51 INFO - ##### 11:15:51 INFO - ##### Running read-buildbot-config step. 11:15:51 INFO - ##### 11:15:51 INFO - Running pre-action listener: _resource_record_pre_action 11:15:51 INFO - Running main action method: read_buildbot_config 11:15:51 INFO - Using buildbot properties: 11:15:51 INFO - { 11:15:51 INFO - "project": "", 11:15:51 INFO - "product": "firefox", 11:15:51 INFO - "script_repo_revision": "production", 11:15:51 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 11:15:51 INFO - "repository": "", 11:15:51 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 11:15:51 INFO - "buildid": "20160419102742", 11:15:51 INFO - "pgo_build": "False", 11:15:51 INFO - "basedir": "/builds/slave/test", 11:15:51 INFO - "buildnumber": 1163, 11:15:51 INFO - "slavename": "talos-linux64-ix-030", 11:15:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:15:51 INFO - "platform": "ubuntu64_hw", 11:15:51 INFO - "scheduler": "tests-fx-team-linux64-talos", 11:15:51 INFO - "branch": "fx-team", 11:15:51 INFO - "repo_path": "integration/fx-team", 11:15:51 INFO - "revision": "3b3c1809c8be95512093a3cf19d1a35e99ad145c", 11:15:51 INFO - "stage_platform": "linux64", 11:15:51 INFO - "builduid": "cea07ba26b334d06a1612cfd872f6d79", 11:15:51 INFO - "slavebuilddir": "test" 11:15:51 INFO - } 11:15:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 11:15:51 INFO - Running post-action listener: _resource_record_post_action 11:15:51 INFO - ##### 11:15:51 INFO - ##### Running download-and-extract step. 11:15:51 INFO - ##### 11:15:51 INFO - Running pre-action listener: _resource_record_pre_action 11:15:51 INFO - Running main action method: download_and_extract 11:15:51 INFO - mkdir: /builds/slave/test/build/tests 11:15:51 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')]} 11:15:51 INFO - https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:15:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 11:15:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 11:15:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 11:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:15:52 INFO - Downloaded 1361 bytes. 11:15:52 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 11:15:52 INFO - Using the following test package requirements: 11:15:52 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 11:15:52 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:52 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:15:52 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:52 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:15:52 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:52 INFO - u'jsshell-linux-x86_64.zip'], 11:15:52 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:52 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:15:52 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 11:15:52 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:52 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:15:52 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:52 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:15:52 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:52 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:15:52 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:52 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:15:52 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:52 INFO - https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:15:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 11:15:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 11:15:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 11:15:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:15:53 INFO - Downloaded 22211033 bytes. 11:15:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:15:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:15:54 INFO - Return code: 0 11:15:54 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:54 INFO - https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:15:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 11:15:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 11:15:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 11:15:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:15:55 INFO - Downloaded 22211033 bytes. 11:15:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:15:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:15:57 INFO - Return code: 0 11:15:57 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')]} 11:15:57 INFO - https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:15:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 11:15:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 11:15:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 11:15:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:15:58 INFO - Downloaded 10845952 bytes. 11:15:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:15:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 11:15:58 INFO - Return code: 0 11:15:58 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')]} 11:15:58 INFO - https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:15:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 11:15:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 11:15:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 11:15:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:16:00 INFO - Downloaded 54458060 bytes. 11:16:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 11:16:00 INFO - mkdir: /builds/slave/test/properties 11:16:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:16:00 INFO - Writing to file /builds/slave/test/properties/build_url 11:16:00 INFO - Contents: 11:16:00 INFO - build_url:https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 11:16:01 INFO - Running post-action listener: _resource_record_post_action 11:16:01 INFO - Running post-action listener: set_extra_try_arguments 11:16:01 INFO - ##### 11:16:01 INFO - ##### Running populate-webroot step. 11:16:01 INFO - ##### 11:16:01 INFO - Running pre-action listener: _resource_record_pre_action 11:16:01 INFO - Running main action method: populate_webroot 11:16:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:16:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:16:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:16:01 INFO - '--tppagecycles', 11:16:01 INFO - '7'], 11:16:01 INFO - 'tests': ['tsvgm']}}, 11:16:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:16:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:16:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:16:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:16:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:16:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:16:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:01 INFO - 'talos_options': ['--disable-e10s'], 11:16:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:16:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:16:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:01 INFO - 'talos_options': ['--disable-e10s'], 11:16:01 INFO - 'tests': ['damp', 'tps']}, 11:16:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:01 INFO - 'tests': ['damp', 'tps']}, 11:16:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:16:01 INFO - 'tests': ['dromaeo_dom']}, 11:16:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:16:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:16:01 INFO - 'tests': ['a11yr', 11:16:01 INFO - 'ts_paint', 11:16:01 INFO - 'tpaint', 11:16:01 INFO - 'sessionrestore', 11:16:01 INFO - 'sessionrestore_no_auto_restore', 11:16:01 INFO - 'tabpaint']}, 11:16:01 INFO - 'other-e10s': {'tests': ['a11yr', 11:16:01 INFO - 'ts_paint', 11:16:01 INFO - 'tpaint', 11:16:01 INFO - 'sessionrestore', 11:16:01 INFO - 'sessionrestore_no_auto_restore', 11:16:01 INFO - 'tabpaint']}, 11:16:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:16:01 INFO - 'ts_paint', 11:16:01 INFO - 'tpaint', 11:16:01 INFO - 'sessionrestore', 11:16:01 INFO - 'sessionrestore_no_auto_restore', 11:16:01 INFO - 'tabpaint']}, 11:16:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:16:01 INFO - 'ts_paint', 11:16:01 INFO - 'tpaint', 11:16:01 INFO - 'sessionrestore', 11:16:01 INFO - 'sessionrestore_no_auto_restore', 11:16:01 INFO - 'tabpaint']}, 11:16:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:16:01 INFO - 'tests': ['a11yr', 11:16:01 INFO - 'ts_paint', 11:16:01 INFO - 'tpaint', 11:16:01 INFO - 'sessionrestore', 11:16:01 INFO - 'sessionrestore_no_auto_restore', 11:16:01 INFO - 'tabpaint']}, 11:16:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:16:01 INFO - 'tests': ['a11yr', 11:16:01 INFO - 'ts_paint', 11:16:01 INFO - 'tpaint', 11:16:01 INFO - 'sessionrestore', 11:16:01 INFO - 'sessionrestore_no_auto_restore', 11:16:01 INFO - 'tabpaint']}, 11:16:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:16:01 INFO - 'tests': ['tsvgx', 11:16:01 INFO - 'tsvgr_opacity', 11:16:01 INFO - 'tart', 11:16:01 INFO - 'tscrollx', 11:16:01 INFO - 'cart']}, 11:16:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:16:01 INFO - 'tsvgr_opacity', 11:16:01 INFO - 'tart', 11:16:01 INFO - 'tscrollx', 11:16:01 INFO - 'cart']}, 11:16:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:01 INFO - 'talos_options': ['--disable-e10s'], 11:16:01 INFO - 'tests': ['tp5o']}, 11:16:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:01 INFO - 'tests': ['tp5o']}, 11:16:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:01 INFO - 'talos_options': ['--disable-e10s', 11:16:01 INFO - '--xperf_path', 11:16:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:16:01 INFO - 'tests': ['tp5n']}, 11:16:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:01 INFO - 'talos_options': ['--xperf_path', 11:16:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:16:01 INFO - 'tests': ['tp5n']}}, 11:16:01 INFO - 'talos.zip': {'path': '', 11:16:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:16:01 INFO - Running post-action listener: _resource_record_post_action 11:16:01 INFO - ##### 11:16:01 INFO - ##### Running create-virtualenv step. 11:16:01 INFO - ##### 11:16:01 INFO - Running pre-action listener: _resource_record_pre_action 11:16:01 INFO - Running main action method: create_virtualenv 11:16:01 INFO - Creating virtualenv /builds/slave/test/build/venv 11:16:01 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 11:16:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:16:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:16:01 INFO - Using real prefix '/usr' 11:16:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:16:01 INFO - Installing distribute.............................................................................................................................................................................................done. 11:16:03 INFO - Installing pip.................done. 11:16:03 INFO - Return code: 0 11:16:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11: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')]} 11:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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')]} 11:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:03 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 0x7f2caaf89f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28885a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2917410>, '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 0x2918310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29187a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', '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 11:16:03 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 11:16:03 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 11:16:03 INFO - Using env: {'DISPLAY': ':0', 11:16:03 INFO - 'HOME': '/home/cltbld', 11:16:03 INFO - 'LANG': 'en_US.UTF-8', 11:16:03 INFO - 'LANGUAGE': 'en_US:en', 11:16:03 INFO - 'LOGNAME': 'cltbld', 11:16:03 INFO - 'MAIL': '/var/mail/cltbld', 11:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:03 INFO - 'MOZ_NO_REMOTE': '1', 11:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:03 INFO - 'NO_EM_RESTART': '1', 11:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:03 INFO - 'PWD': '/builds/slave/test', 11:16:03 INFO - 'SHELL': '/bin/bash', 11:16:03 INFO - 'SHLVL': '1', 11:16:03 INFO - 'TERM': 'linux', 11:16:03 INFO - 'TMOUT': '86400', 11:16:03 INFO - 'USER': 'cltbld', 11:16:03 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', 11:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:03 INFO - '_': '/tools/buildbot/bin/python'} 11:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:04 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 11:16:04 INFO - Cleaning up... 11:16:04 INFO - Return code: 0 11:16:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:04 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 0x7f2caaf89f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28885a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2917410>, '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 0x2918310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29187a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', '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 11:16:04 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 11:16:04 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 11:16:04 INFO - Using env: {'DISPLAY': ':0', 11:16:04 INFO - 'HOME': '/home/cltbld', 11:16:04 INFO - 'LANG': 'en_US.UTF-8', 11:16:04 INFO - 'LANGUAGE': 'en_US:en', 11:16:04 INFO - 'LOGNAME': 'cltbld', 11:16:04 INFO - 'MAIL': '/var/mail/cltbld', 11:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:04 INFO - 'MOZ_NO_REMOTE': '1', 11:16:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:04 INFO - 'NO_EM_RESTART': '1', 11:16:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:04 INFO - 'PWD': '/builds/slave/test', 11:16:04 INFO - 'SHELL': '/bin/bash', 11:16:04 INFO - 'SHLVL': '1', 11:16:04 INFO - 'TERM': 'linux', 11:16:04 INFO - 'TMOUT': '86400', 11:16:04 INFO - 'USER': 'cltbld', 11:16:04 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', 11:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:04 INFO - '_': '/tools/buildbot/bin/python'} 11:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:04 INFO - Downloading/unpacking psutil>=0.7.1 11:16:04 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 11:16:04 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 11:16:04 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 11:16:04 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 11:16:04 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 11:16:04 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 11:16:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:16:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:16:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:16:06 INFO - Installing collected packages: psutil 11:16:06 INFO - Running setup.py install for psutil 11:16:06 INFO - building 'psutil._psutil_linux' extension 11:16:06 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 11:16:06 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 11:16:06 INFO - building 'psutil._psutil_posix' extension 11:16:06 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 11:16:06 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 11:16:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:16:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:16:06 INFO - Successfully installed psutil 11:16:06 INFO - Cleaning up... 11:16:06 INFO - Return code: 0 11:16:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:16:06 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')]} 11:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:06 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')]} 11:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:06 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 0x7f2caaf89f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28885a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2917410>, '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 0x2918310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29187a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', '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 11:16:06 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 11:16:06 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 11:16:06 INFO - Using env: {'DISPLAY': ':0', 11:16:06 INFO - 'HOME': '/home/cltbld', 11:16:06 INFO - 'LANG': 'en_US.UTF-8', 11:16:06 INFO - 'LANGUAGE': 'en_US:en', 11:16:06 INFO - 'LOGNAME': 'cltbld', 11:16:06 INFO - 'MAIL': '/var/mail/cltbld', 11:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:06 INFO - 'MOZ_NO_REMOTE': '1', 11:16:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:06 INFO - 'NO_EM_RESTART': '1', 11:16:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:06 INFO - 'PWD': '/builds/slave/test', 11:16:06 INFO - 'SHELL': '/bin/bash', 11:16:06 INFO - 'SHLVL': '1', 11:16:06 INFO - 'TERM': 'linux', 11:16:06 INFO - 'TMOUT': '86400', 11:16:06 INFO - 'USER': 'cltbld', 11:16:06 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', 11:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:06 INFO - '_': '/tools/buildbot/bin/python'} 11:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:16:07 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 11:16:07 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 11:16:07 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 11:16:07 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 11:16:07 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 11:16:07 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 11:16:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:16:08 INFO - Installing collected packages: mozsystemmonitor 11:16:08 INFO - Running setup.py install for mozsystemmonitor 11:16:09 INFO - Successfully installed mozsystemmonitor 11:16:09 INFO - Cleaning up... 11:16:09 INFO - Return code: 0 11:16:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2caaf89f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28885a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2917410>, '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 0x2918310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29187a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', '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 11:16:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:16:09 INFO - Using env: {'DISPLAY': ':0', 11:16:09 INFO - 'HOME': '/home/cltbld', 11:16:09 INFO - 'LANG': 'en_US.UTF-8', 11:16:09 INFO - 'LANGUAGE': 'en_US:en', 11:16:09 INFO - 'LOGNAME': 'cltbld', 11:16:09 INFO - 'MAIL': '/var/mail/cltbld', 11:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:09 INFO - 'MOZ_NO_REMOTE': '1', 11:16:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:09 INFO - 'NO_EM_RESTART': '1', 11:16:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:09 INFO - 'PWD': '/builds/slave/test', 11:16:09 INFO - 'SHELL': '/bin/bash', 11:16:09 INFO - 'SHLVL': '1', 11:16:09 INFO - 'TERM': 'linux', 11:16:09 INFO - 'TMOUT': '86400', 11:16:09 INFO - 'USER': 'cltbld', 11:16:09 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', 11:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:09 INFO - '_': '/tools/buildbot/bin/python'} 11:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:09 INFO - Downloading/unpacking blobuploader==1.2.4 11:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:11 INFO - Downloading blobuploader-1.2.4.tar.gz 11:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:16:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:16:11 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 11:16:11 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 11:16:11 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 11:16:11 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 11:16:11 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 11:16:11 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 11:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:16:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:16:11 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 11:16:11 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 11:16:11 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 11:16:11 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 11:16:11 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 11:16:11 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 11:16:11 INFO - Downloading docopt-0.6.1.tar.gz 11:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:16:11 INFO - Installing collected packages: blobuploader, requests, docopt 11:16:11 INFO - Running setup.py install for blobuploader 11:16:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:16:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:16:12 INFO - Running setup.py install for requests 11:16:12 INFO - Running setup.py install for docopt 11:16:12 INFO - Successfully installed blobuploader requests docopt 11:16:12 INFO - Cleaning up... 11:16:12 INFO - Return code: 0 11:16:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:12 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 0x7f2caaf89f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28885a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2917410>, '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 0x2918310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29187a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', '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 11:16:12 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 11:16:12 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 11:16:12 INFO - Using env: {'DISPLAY': ':0', 11:16:12 INFO - 'HOME': '/home/cltbld', 11:16:12 INFO - 'LANG': 'en_US.UTF-8', 11:16:12 INFO - 'LANGUAGE': 'en_US:en', 11:16:12 INFO - 'LOGNAME': 'cltbld', 11:16:12 INFO - 'MAIL': '/var/mail/cltbld', 11:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:12 INFO - 'MOZ_NO_REMOTE': '1', 11:16:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:12 INFO - 'NO_EM_RESTART': '1', 11:16:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:12 INFO - 'PWD': '/builds/slave/test', 11:16:12 INFO - 'SHELL': '/bin/bash', 11:16:12 INFO - 'SHLVL': '1', 11:16:12 INFO - 'TERM': 'linux', 11:16:12 INFO - 'TMOUT': '86400', 11:16:12 INFO - 'USER': 'cltbld', 11:16:12 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', 11:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:12 INFO - '_': '/tools/buildbot/bin/python'} 11:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:16:12 INFO - Running setup.py (path:/tmp/pip-y94wPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:16:12 INFO - Running setup.py (path:/tmp/pip-yhY6MU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:16:12 INFO - Running setup.py (path:/tmp/pip-uRlAOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:16:13 INFO - Running setup.py (path:/tmp/pip-lFSNiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:16:13 INFO - Running setup.py (path:/tmp/pip-ejHApY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:16:13 INFO - Running setup.py (path:/tmp/pip-g7vHeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:16:13 INFO - Running setup.py (path:/tmp/pip-hX_Ifx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:16:13 INFO - Running setup.py (path:/tmp/pip-_ZCmww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:16:13 INFO - Running setup.py (path:/tmp/pip-HJR5Kg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:16:13 INFO - Running setup.py (path:/tmp/pip-R5pRAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:16:13 INFO - Running setup.py (path:/tmp/pip-wyW6o1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:16:13 INFO - Running setup.py (path:/tmp/pip-VHHUew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:16:13 INFO - Running setup.py (path:/tmp/pip-RoFvch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:16:13 INFO - Running setup.py (path:/tmp/pip-8TQwE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:13 INFO - Running setup.py (path:/tmp/pip-fEmwid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:16:13 INFO - Running setup.py (path:/tmp/pip-PJGpHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:16:13 INFO - Running setup.py (path:/tmp/pip-kKQoyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:16:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:16:14 INFO - Running setup.py install for manifestparser 11:16:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:16:14 INFO - Running setup.py install for mozcrash 11:16:14 INFO - Running setup.py install for mozdebug 11:16:14 INFO - Running setup.py install for mozdevice 11:16:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:16:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:16:14 INFO - Running setup.py install for mozfile 11:16:14 INFO - Running setup.py install for mozhttpd 11:16:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:16:14 INFO - Running setup.py install for mozinfo 11:16:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:16:14 INFO - Running setup.py install for mozInstall 11:16:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:16:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:16:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:16:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:16:14 INFO - Running setup.py install for mozleak 11:16:14 INFO - Running setup.py install for mozlog 11:16:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:16:15 INFO - Running setup.py install for moznetwork 11:16:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:16:15 INFO - Running setup.py install for mozprocess 11:16:15 INFO - Running setup.py install for mozprofile 11:16:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:16:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:16:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:16:15 INFO - Running setup.py install for mozrunner 11:16:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:16:15 INFO - Running setup.py install for mozscreenshot 11:16:15 INFO - Running setup.py install for moztest 11:16:15 INFO - Running setup.py install for mozversion 11:16:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:16:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:16:15 INFO - Cleaning up... 11:16:15 INFO - Return code: 0 11:16:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16:15 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')]} 11:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:15 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')]} 11:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:15 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 0x7f2caaf89f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28885a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2917410>, '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 0x2918310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29187a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', '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 11:16:15 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 11:16:15 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 11:16:15 INFO - Using env: {'DISPLAY': ':0', 11:16:15 INFO - 'HOME': '/home/cltbld', 11:16:15 INFO - 'LANG': 'en_US.UTF-8', 11:16:15 INFO - 'LANGUAGE': 'en_US:en', 11:16:15 INFO - 'LOGNAME': 'cltbld', 11:16:15 INFO - 'MAIL': '/var/mail/cltbld', 11:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:15 INFO - 'MOZ_NO_REMOTE': '1', 11:16:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:15 INFO - 'NO_EM_RESTART': '1', 11:16:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:15 INFO - 'PWD': '/builds/slave/test', 11:16:15 INFO - 'SHELL': '/bin/bash', 11:16:15 INFO - 'SHLVL': '1', 11:16:15 INFO - 'TERM': 'linux', 11:16:15 INFO - 'TMOUT': '86400', 11:16:15 INFO - 'USER': 'cltbld', 11:16:15 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', 11:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:15 INFO - '_': '/tools/buildbot/bin/python'} 11:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:16:16 INFO - Running setup.py (path:/tmp/pip-O_GG6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:16:16 INFO - Running setup.py (path:/tmp/pip-VKyPBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:16:16 INFO - Running setup.py (path:/tmp/pip-5E_0Ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:16:16 INFO - Running setup.py (path:/tmp/pip-krDGGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:16:16 INFO - Running setup.py (path:/tmp/pip-iZSP53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:16:16 INFO - Running setup.py (path:/tmp/pip-ibG8JS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:16:16 INFO - Running setup.py (path:/tmp/pip-Lls_Va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:16:16 INFO - Running setup.py (path:/tmp/pip-y9nTIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:16:16 INFO - Running setup.py (path:/tmp/pip-8NhA2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:16:16 INFO - Running setup.py (path:/tmp/pip-dID8Uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:16:16 INFO - Running setup.py (path:/tmp/pip-4QHa0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:16:16 INFO - Running setup.py (path:/tmp/pip-dSqTv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:16:16 INFO - Running setup.py (path:/tmp/pip-Yk2o2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:16:16 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)) 11:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:16:16 INFO - Running setup.py (path:/tmp/pip-wYuZFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:16:17 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)) 11:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:17 INFO - Running setup.py (path:/tmp/pip-JwmioS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:17 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)) 11:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:16:17 INFO - Running setup.py (path:/tmp/pip-YCLc5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:16:17 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)) 11:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:16:17 INFO - Running setup.py (path:/tmp/pip-9LI04A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:16:17 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)) 11:16:17 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)) 11:16:17 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)) 11:16:17 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)) 11:16:17 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)) 11:16:17 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)) 11:16:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:16:17 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 11:16:17 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 11:16:17 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 11:16:17 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 11:16:17 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 11:16:17 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 11:16:19 INFO - Downloading blessings-1.6.tar.gz 11:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:16:19 INFO - Installing collected packages: blessings 11:16:19 INFO - Running setup.py install for blessings 11:16:19 INFO - Successfully installed blessings 11:16:19 INFO - Cleaning up... 11:16:19 INFO - Return code: 0 11:16:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:16:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:16:19 INFO - Reading from file tmpfile_stdout 11:16:19 INFO - Current package versions: 11:16:19 INFO - argparse == 1.2.1 11:16:19 INFO - blessings == 1.6 11:16:19 INFO - blobuploader == 1.2.4 11:16:19 INFO - docopt == 0.6.1 11:16:19 INFO - manifestparser == 1.1 11:16:19 INFO - mozInstall == 1.12 11:16:19 INFO - mozcrash == 0.17 11:16:19 INFO - mozdebug == 0.1 11:16:19 INFO - mozdevice == 0.48 11:16:19 INFO - mozfile == 1.2 11:16:19 INFO - mozhttpd == 0.7 11:16:19 INFO - mozinfo == 0.9 11:16:19 INFO - mozleak == 0.1 11:16:19 INFO - mozlog == 3.1 11:16:19 INFO - moznetwork == 0.27 11:16:19 INFO - mozprocess == 0.22 11:16:19 INFO - mozprofile == 0.28 11:16:19 INFO - mozrunner == 6.11 11:16:19 INFO - mozscreenshot == 0.1 11:16:19 INFO - mozsystemmonitor == 0.0 11:16:19 INFO - moztest == 0.7 11:16:19 INFO - mozversion == 1.4 11:16:19 INFO - psutil == 3.1.1 11:16:19 INFO - requests == 1.2.3 11:16:19 INFO - wsgiref == 0.1.2 11:16:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:19 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 0x7f2caaf89f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28885a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2917410>, '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 0x2918310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29187a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', '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 11:16:19 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 11:16:19 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 11:16:19 INFO - Using env: {'DISPLAY': ':0', 11:16:19 INFO - 'HOME': '/home/cltbld', 11:16:19 INFO - 'LANG': 'en_US.UTF-8', 11:16:19 INFO - 'LANGUAGE': 'en_US:en', 11:16:19 INFO - 'LOGNAME': 'cltbld', 11:16:19 INFO - 'MAIL': '/var/mail/cltbld', 11:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:19 INFO - 'MOZ_NO_REMOTE': '1', 11:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:19 INFO - 'NO_EM_RESTART': '1', 11:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:19 INFO - 'PWD': '/builds/slave/test', 11:16:19 INFO - 'SHELL': '/bin/bash', 11:16:19 INFO - 'SHLVL': '1', 11:16:19 INFO - 'TERM': 'linux', 11:16:19 INFO - 'TMOUT': '86400', 11:16:19 INFO - 'USER': 'cltbld', 11:16:19 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', 11:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:19 INFO - '_': '/tools/buildbot/bin/python'} 11:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:19 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)) 11:16:19 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)) 11:16:19 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)) 11:16:19 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)) 11:16:19 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)) 11:16:19 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)) 11:16:19 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)) 11:16:19 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)) 11:16:19 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)) 11:16:19 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)) 11:16:19 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)) 11:16:19 INFO - Cleaning up... 11:16:19 INFO - Return code: 0 11:16:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:19 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 0x7f2caaf89f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28885a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2917410>, '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 0x2918310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29187a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', '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 11:16:19 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 11:16:19 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 11:16:19 INFO - Using env: {'DISPLAY': ':0', 11:16:19 INFO - 'HOME': '/home/cltbld', 11:16:19 INFO - 'LANG': 'en_US.UTF-8', 11:16:19 INFO - 'LANGUAGE': 'en_US:en', 11:16:19 INFO - 'LOGNAME': 'cltbld', 11:16:19 INFO - 'MAIL': '/var/mail/cltbld', 11:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:19 INFO - 'MOZ_NO_REMOTE': '1', 11:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:19 INFO - 'NO_EM_RESTART': '1', 11:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:19 INFO - 'PWD': '/builds/slave/test', 11:16:19 INFO - 'SHELL': '/bin/bash', 11:16:19 INFO - 'SHLVL': '1', 11:16:19 INFO - 'TERM': 'linux', 11:16:19 INFO - 'TMOUT': '86400', 11:16:19 INFO - 'USER': 'cltbld', 11:16:19 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', 11:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:19 INFO - '_': '/tools/buildbot/bin/python'} 11:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:20 INFO - Downloading/unpacking jsonschema 11:16:20 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 11:16:20 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 11:16:20 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 11:16:20 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 11:16:20 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 11:16:20 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 11:16:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:16:21 INFO - Downloading/unpacking functools32 (from jsonschema) 11:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:22 INFO - Downloading functools32-3.2.3-2.tar.gz 11:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:16:22 INFO - warning: no files found matching '*.txt' 11:16:22 INFO - no previously-included directories found matching 'build' 11:16:22 INFO - no previously-included directories found matching 'dist' 11:16:22 INFO - no previously-included directories found matching '.git*' 11:16:22 INFO - Installing collected packages: jsonschema, functools32 11:16:22 INFO - Running setup.py install for functools32 11:16:22 INFO - warning: no files found matching '*.txt' 11:16:22 INFO - no previously-included directories found matching 'build' 11:16:22 INFO - no previously-included directories found matching 'dist' 11:16:22 INFO - no previously-included directories found matching '.git*' 11:16:22 INFO - Successfully installed jsonschema functools32 11:16:22 INFO - Cleaning up... 11:16:22 INFO - Return code: 0 11:16:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11: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')]} 11:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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')]} 11:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:22 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 0x7f2caaf89f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28885a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2917410>, '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 0x2918310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29187a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', '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 11:16:22 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 11:16:22 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 11:16:22 INFO - Using env: {'DISPLAY': ':0', 11:16:22 INFO - 'HOME': '/home/cltbld', 11:16:22 INFO - 'LANG': 'en_US.UTF-8', 11:16:22 INFO - 'LANGUAGE': 'en_US:en', 11:16:22 INFO - 'LOGNAME': 'cltbld', 11:16:22 INFO - 'MAIL': '/var/mail/cltbld', 11:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:22 INFO - 'MOZ_NO_REMOTE': '1', 11:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:22 INFO - 'NO_EM_RESTART': '1', 11:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:22 INFO - 'PWD': '/builds/slave/test', 11:16:22 INFO - 'SHELL': '/bin/bash', 11:16:22 INFO - 'SHLVL': '1', 11:16:22 INFO - 'TERM': 'linux', 11:16:22 INFO - 'TMOUT': '86400', 11:16:22 INFO - 'USER': 'cltbld', 11:16:22 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', 11:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:22 INFO - '_': '/tools/buildbot/bin/python'} 11:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:22 INFO - Downloading/unpacking flake8 11:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:16:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:16:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:16:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:16:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:16:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:16:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:16:25 INFO - no previously-included directories found matching 'docs/_build' 11:16:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:16:25 INFO - Running setup.py install for pep8 11:16:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:16:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:16:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:16:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:16:25 INFO - no previously-included directories found matching 'docs/_build' 11:16:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:16:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:16:25 INFO - Cleaning up... 11:16:25 INFO - Return code: 0 11:16:25 INFO - Running post-action listener: _resource_record_post_action 11:16:25 INFO - Running post-action listener: _start_resource_monitoring 11:16:25 INFO - Starting resource monitoring. 11:16:25 INFO - ##### 11:16:25 INFO - ##### Running install step. 11:16:25 INFO - ##### 11:16:25 INFO - Running pre-action listener: _resource_record_pre_action 11:16:25 INFO - Running main action method: install 11:16:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:16:25 INFO - Reading from file tmpfile_stdout 11:16:25 INFO - Detecting whether we're running mozinstall >=1.0... 11:16:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:16:25 INFO - Reading from file tmpfile_stdout 11:16:25 INFO - Output received: 11:16:25 INFO - Usage: mozinstall [options] installer 11:16:25 INFO - Options: 11:16:25 INFO - -h, --help show this help message and exit 11:16:25 INFO - -d DEST, --destination=DEST 11:16:25 INFO - Directory to install application into. [default: 11:16:25 INFO - "/builds/slave/test"] 11:16:25 INFO - --app=APP Application being installed. [default: firefox] 11:16:25 INFO - mkdir: /builds/slave/test/build/application 11:16:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:16:41 INFO - Reading from file tmpfile_stdout 11:16:41 INFO - Output received: 11:16:41 INFO - /builds/slave/test/build/application/firefox/firefox 11:16:41 INFO - Running post-action listener: _resource_record_post_action 11:16:41 INFO - ##### 11:16:41 INFO - ##### Running run-tests step. 11:16:41 INFO - ##### 11:16:41 INFO - Running pre-action listener: _resource_record_pre_action 11:16:41 INFO - Running main action method: run_tests 11:16:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:16:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:16:41 INFO - Python 2.7.3 11:16:41 INFO - Return code: 0 11:16:41 INFO - grabbing minidump binary from tooltool 11:16:41 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')]} 11:16:41 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 0x2917410>, '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 0x2918310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29187a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:16:41 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 11:16:41 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 11:16:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:16:41 INFO - Return code: 0 11:16:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:16:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:16:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:16:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:16:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:16:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:16:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:16:42 INFO - Return code: 0 11:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 11:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 11:16:42 INFO - Using env: {'DISPLAY': ':0', 11:16:42 INFO - 'HOME': '/home/cltbld', 11:16:42 INFO - 'LANG': 'en_US.UTF-8', 11:16:42 INFO - 'LANGUAGE': 'en_US:en', 11:16:42 INFO - 'LOGNAME': 'cltbld', 11:16:42 INFO - 'MAIL': '/var/mail/cltbld', 11:16:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:16:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:42 INFO - 'MOZ_NO_REMOTE': '1', 11:16:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:16:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:42 INFO - 'NO_EM_RESTART': '1', 11:16:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:42 INFO - 'PWD': '/builds/slave/test', 11:16:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:16:42 INFO - 'SHELL': '/bin/bash', 11:16:42 INFO - 'SHLVL': '1', 11:16:42 INFO - 'TERM': 'linux', 11:16:42 INFO - 'TMOUT': '86400', 11:16:42 INFO - 'USER': 'cltbld', 11:16:42 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985', 11:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:42 INFO - '_': '/tools/buildbot/bin/python'} 11:16:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 11:16:42 INFO - mozversion application_buildid: 20160419102742 11:16:42 INFO - mozversion application_changeset: 3b3c1809c8be95512093a3cf19d1a35e99ad145c 11:16:42 INFO - mozversion application_display_name: Nightly 11:16:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:16:42 INFO - mozversion application_name: Firefox 11:16:42 INFO - mozversion application_remotingname: firefox 11:16:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:16:42 INFO - mozversion application_vendor: Mozilla 11:16:42 INFO - mozversion application_version: 48.0a1 11:16:42 INFO - mozversion platform_buildid: 20160419102742 11:16:42 INFO - mozversion platform_changeset: 3b3c1809c8be95512093a3cf19d1a35e99ad145c 11:16:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:16:42 INFO - mozversion platform_version: 48.0a1 11:16:42 INFO - using testdate: 1461089802 11:16:42 INFO - actual date: 1461089802 11:16:42 INFO - starting webserver on 'localhost:56179' 11:16:42 INFO - SUITE-START | Running 2 tests 11:16:42 INFO - TEST-START | dromaeo_css 11:16:42 INFO - operating with platform_type : linux_ 11:16:42 INFO - Initialising browser for dromaeo_css test... 11:16:42 INFO - TEST-INFO | started process 12479 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRAzQvO/profile http://localhost:56179/getInfo.html) 11:16:46 INFO - PROCESS | 12479 | 11:16:46 INFO - PROCESS | 12479 | (process:12545): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 11:16:47 INFO - PROCESS | 12479 | __metrics Screen width/height:1600/1200 11:16:47 INFO - PROCESS | 12479 | colorDepth:24 11:16:47 INFO - PROCESS | 12479 | Browser inner width/height: 1024/697 11:16:47 INFO - PROCESS | 12479 | __metrics 11:16:48 ERROR - PROCESS | 12479 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 11:16:49 INFO - TEST-INFO | 12479: exit 0 11:16:49 INFO - Browser initialized. 11:16:49 INFO - Running cycle 1/1 for dromaeo_css test... 11:16:49 INFO - TEST-INFO | started process 12596 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRAzQvO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:16:51 INFO - PROCESS | 12596 | 11:16:51 INFO - PROCESS | 12596 | (process:12655): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 11:17:01 INFO - PROCESS | 12596 | RSS: Main: 164814848 11:17:01 INFO - PROCESS | 12596 | 11:17:50 INFO - PROCESS | 12596 | 1461089870686 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] 11:20:02 INFO - PROCESS | 12596 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56179/tests/dromaeo/cssquery-ext.html) 11:20:03 INFO - PROCESS | 12596 | RSS: Main: 169320448 11:20:03 INFO - PROCESS | 12596 | 11:23:05 INFO - PROCESS | 12596 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-ext.html (next: http://localhost:56179/tests/dromaeo/cssquery-jquery.html) 11:23:06 INFO - PROCESS | 12596 | RSS: Main: 164925440 11:23:06 INFO - PROCESS | 12596 | 11:26:08 INFO - PROCESS | 12596 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56179/tests/dromaeo/cssquery-mootools.html) 11:26:08 INFO - PROCESS | 12596 | RSS: Main: 166641664 11:26:08 INFO - PROCESS | 12596 | 11:29:10 INFO - PROCESS | 12596 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56179/tests/dromaeo/cssquery-prototype.html) 11:29:11 INFO - PROCESS | 12596 | RSS: Main: 164372480 11:29:11 INFO - PROCESS | 12596 | 11:32:13 INFO - PROCESS | 12596 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56179/tests/dromaeo/cssquery-yui.html) 11:32:14 INFO - PROCESS | 12596 | RSS: Main: 164999168 11:32:14 INFO - PROCESS | 12596 | 11:35:16 INFO - PROCESS | 12596 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-yui.html (next: http://localhost:56179/tests/dromaeo/cssquery-dojo.html) 11:35:17 INFO - PROCESS | 12596 | RSS: Main: 167698432 11:35:17 INFO - PROCESS | 12596 | 11:35:17 INFO - PROCESS | 12596 | __start_tp_report 11:35:17 INFO - PROCESS | 12596 | _x_x_mozilla_page_load 11:35:17 INFO - PROCESS | 12596 | _x_x_mozilla_page_load_details 11:35:17 INFO - PROCESS | 12596 | |i|pagename|runs| 11:35:17 INFO - PROCESS | 12596 | |0;dojo.html;2609.282447326985;2689.32766808298;2696.177665811928;2698.9325266868336;2702.9864850675744;4741.4073240844855;4769.761511924407;4800.327954086426;4806.4232292125;4840.806367745286;4643.396358473394;4784.5933095686905;4845.466998630148;4846.079244943458;4860.4896485869;4412.183746006991;4464.508904020555;4510.594046535811;4512.142692888341;4552.704074235181;4424.4690637123595;4500.279955207167;4526.0495295987885;4543.8182472701055;4545.386372839674;4297.505786834491;4350.651947844165;4394;4431.534688857671;4500.79746411409;4365.148795984785;4406.713563618355;4407.427034485529;4411.470623525165;4449.688521803475;4365.017870979036;4366.8471603494045;4367.410399596046;4372.584604462571;4393.406890069831;4648.070385922795;4679.15775160471;4716.599089077398;4716.952830471686;4726.787294571753;5120.820771272987;5120.871978200537;5128.1282182029145;5134.871628209287;5154;5119.129747942821;5126.5898728101665;5129.230615407719;5134.717590532519;5151.150060240056;4763.071201115784;4788;4790.0419916016235;4791.592714619227;4844.612431005476;5237.973810130925;5262.342207224097;5265.420803711665;5274.7626356814035;5276.604254680914;5032.018756342478;5051.595872330205;5086.3896332440345;5138.97430512845;5173.948260517422;5157.535821776059;5201.375834899836;5218.556422704112;5220.869478263074;5229.058769421541;4936.2102063669645;5026.698398096126;5029.17018691919;5073.746312684425;5120.950205207923;4913.729744863996;5086.491350864884;5094.057599344133;5094.2358646203365;5162.948370516322;4387.934180987288;4392;4400.911981760347;4445.4220951276775;4446.688731788744;3821.159344944108;3842.269968705937;3860.749051311656;3870.9225815483533;3922.0391004204153;4237.597428244312;4412.911741765147;4435.556444355539;4483.955160448354;4495.325701144789;5158.432572417031;5160.354955630546;5163.715995620202;5180.093483640423;5245.842624721265;4451.643868490513;4452.354408610733;4457.531959144243;4458.108378324283;4500.819967201341;4476.686631935799;4618.722876627413;4641.721496710208;4674.322223277673;4692.296155576691;4794.856154315376;4802.831900883522;4814.325994360863;4817.542333478314;4877.95122048775;5005.824796132118;5173.508516690908;5196.4283928767045;5196.636235463481;5258;4811.831585894477;4827.444843843003;4828.613710903049;4862.70823750562;4871.5128487151005;5003.44962054181;5063.518965698253;5068;5073.162928116967;5124.077666020152;5122.359705036871;5142.614303927146;5149.356330458692;5153.99559513163;5171.405288391883;4739.810407583658;4890.168671325812;5005.099082165245;5060.139776238122;5076;4976.129177394014;4992.900141997067;4996.750162491933;5007.173816320266;5039.017391608601;4665.370175026464;4873.975630121827;4919.065377578248;4935.580475659609;4940.036692844861;5114.71869047206;5118.616103792156;5134.486551344836;5141.177411614124;5180.171172612364;4969.652124351261;4974.726390048582;4988.600911926965;4990.950090499048;5002.624803139706 11:35:17 INFO - PROCESS | 12596 | |1;ext.html;587.7325816753375;595.2767387624036;623.2520974830203;639.4053530216897;646.3601034975372;33780;34857.825710871475;34914.825425872834;34951.475727864105;34980.82509587448;37059.81470092646;37870.81064594673;38021.23957520855;38045.23909521808;38075;7573.5834529100875;7724.343430808388;7746.690132394698;7761.650725717356;7797.415193860455;7634.694612215505;7773.572453515055;7786.454948153631;7867.645955931968;7941.245581669732;7830.804229894241;7913.327367173768;7936.642851071716;7981.321587665056;7995.360371170293;7853.136155022914;7864.842703145933;7954.284114429729;7958.522488650699;7965.163657815933;7731.7680469586;7921.762347129596;7922.762317130495;7929.5242285463055;7936.087349954713;37103.4434483483;37157.442638360444;37206.44190337147;37310.25379492422;37420;2505.2859934918642;2920.1385591250632;2921.2061433005056;3248.870045198189;3705.370087085202;24508.877455612786;24975.750242497525;24995.750042499523;25105.49789004228;25414;3233.692799184097;3242.5460435539044;3252.6003342396;3281.5898012748407;3284.1617214357207;178534;179575.10212448984;180195;180430;180802;31908.40457977092;32116.197095072344;32164.19589510281;32195.195120122185;33337.49993750096;33311.66688333086;33637.32725345528;33719.32561348759;33721.49417758736;33802;12251.816222756666;12298.938505307595;12404.62786116418;12432.316222607662;12502.49990000408;9960.452175130438;9969.604255404249;10060.798784024424;10060.949695251624;10104.848427273597;7995.960020199978;8130.512169269863;8136.674533018731;8175.3459723222;8284.792880177924;3186.3627274545183;3257.527658489506;3279.3769183854993;3283.4582293921235;3306.8677252909615;910.4218821048681;918.7381596245039;973.87339645847;1072.3351522056375;1093.6117678224116;27678.584821227698;27967.300817479725;28099.719002810118;28402.43195136127;28736.281592959924;874.2175752701297;883.5140672630027;898.7842917699705;931.5854444772011;1016.5933626549291;14580.19808910499;15266.244614873573;15327.31027103783;15406.22968851553;15508.224588770516;315977;322160;322870;323992;324512;49300.26049609259;50119.49880501221;50154.24768628473;50511.49488505068;50522.74738626283;1236.6722818453118;1268.2770820632152;1270.4092596942467;1284.2358796516057;1364.1678576068591;27125.321866953484;28470.43059138847;28523;28894.566581500872;29090.27274318159;42891.356629649956;43132.13735725205;43305;43428.348574770775;43641.34537981996;304885;309622;310722;310971;311611;25765.484690306464;26145.73854261509;26383.472330553665;26415.867920660272;26530.204093877215;42459.93850153771;42650.78674606607;43268.13463730771;44385.557474345034;44994.550054499035;34684.65315346814;34718;35215;35357.646423535436;35773.46339804957;34561.30877382388;34603.826980864935;34805.825970869984;35090.47364289487;35199;30740.309282988757;35158.472622910165;35651.64348356483;36890.16175868968;37898.81050594839;28427.43145137127;29945;30262.243443914078;31808.522872156474;31951;24327.27018189457;25060.12289569856;25309.240722778348;25326.493470130863;25512.489750205263 11:35:17 INFO - PROCESS | 12596 | |2;jquery.html;383.5340061824884;383.77549133756753;385.7550455460782;394.0640977678013;402.3220872829281;15233.923830380847;16381.361863813612;16686.916565417156;16888.493345199648;16902.91548542257;15112.244387780594;16258.268377923021;16667.41664041759;16775.580610484743;16812;4638.257878739402;4789.257665061904;4829;4833.130036593412;4839.4918533554;3434.107132145635;5073.898522029557;5376.542993845508;5383.300170977767;5393.1370980643105;5320.441353657869;5322;5337.386200586924;5363.865903352428;5369.973150134244;5036.6222533309965;5304.920426193572;5361.249425080492;5378.220158077076;5378.489043540877;5024.221245706921;5191.454897235812;5202.557782588447;5215.765290561933;5217.713025783581;11629.534818607246;11723.531058757639;11739.060875130055;14104.71790564183;20078.09648565818;1897.8291953724092;1922.9423117306503;1947.2795065825594;1964.1357802566968;2052.6202652509332;8760.649574017032;8932.106789321017;9174.174324310845;11692.532298708042;13355.599332020054;1804.7202683584067;1814.201751229468;1833.5599456130567;1900.078461945955;1945.7567804024497;64623.676881615764;66511;68538;69254.99999999949;69497;9978.550965206583;9982.80034399308;10098.242631802497;10224.182065434747;10305.330153539995;14139.575812725634;14167.71664566703;14194.14835109876;14207.71584568303;14241.216733079573;6919.411849992807;6932.618705971119;6949.930500694928;7068.293170682993;7119.679614417364;6399.520035997319;6410.294867564564;6420.229572451242;6505.3819887111595;6565.934340656628;4400.955990440055;4401.691881568259;4410.1400226956075;4417.955820441819;4470.351798989128;1592.9964122602687;1799.640071985608;1836.6510363030982;1854.7588813454165;1877.7089551119598;883.6995421556702;883.7481317824389;919.866619340192;920.2867777472378;990.4156547636928;16276.267567959234;16333.346666133459;16485.670286594202;16843.91578042102;17012.48962531126;911.8267529169569;918.42598376015;927.8515437529962;930.50683137937;943.9197668198143;9454.385464944757;9455.29085318604;9465.621375145054;9488.573014214377;9652.372595781251;356616;356973;360967;362173;367389;73063.63468182625;73922;74237;75600.62199688966;77835.61082194666;918.0812102705511;941.8445956417252;942.086176408896;946.0728486083676;946.654471118037;14660.486882959045;14773.261336933325;14902.403903844159;14935.253237337873;14963.027403218755;29991.400171996873;32870.67129328676;33374.49938250977;33592.32815343728;33980.49029264513;45887.311690324;46316.536834631224;46533.76733116448;46592.301115482616;46712.53287467218;6845.281245469154;7169;7208.783736487913;7349.4855360124275;7611.467197296136;8313.6674533018;11310.717232068935;11430.599929002445;11828.172027958304;11987.700307492383;10336.689899303034;10386.584536618451;10490.370577765358;10575.153987680813;10577.31247468912;10145.746356341151;10341.741456463347;10352.275340726379;10422.530986105492;10596.523156457824;9951.701448956543;10033.949830250802;10221.212030584727;10261.486925653538;10448.738781530523;7708.267714567107;7717.421193410629;7867.134615192439;7962.601869906597;8040.075391330072;7449.5902725350625;9444.197243234126;10459.267851250339;10500.842487362543;10631 11:35:17 INFO - PROCESS | 12596 | |3;mootools.html;1469.5885152157393;1479.1864474539002;1653.437831137413;1787.2761531579715;1806.115003648212;6575.342465753434;7644.388448924086;7749.651265693044;7890.250426209508;7901.446898717092;7825.060992680887;7844.8038799030055;7858.410619203554;7884.132745398016;7902.288794008538;2227.1982086448825;2265.716785401825;2268.886555672218;2272.0570963050313;2274.8862556872173;2226.977730222701;2245.62947113726;2246.9887650561805;2248.6289762189217;2260.7513173550897;2221.3224966385246;2244.461329280969;2254.0420321363436;2263.151318255654;2273.897674604647;2159.686845407415;2173.804357607823;2174.423777698911;2175.3908905506482;2183.4868805830697;2231.464448532357;2231.6317807561895;2242.5963326601204;2243.955120897573;2250.932472025842;7865.803354916173;7942.166072562355;7969.083555391147;7977.641006154737;8012.479188852706;4589.426321709786;4676.55572720591;4690.0854333405;4690.437147542282;4728.5507876752;2578.561644520436;2750.642416485864;2785.623940768011;2786.1084452975056;2815.563587643898;882.8322618702426;902.1790170944474;909.3134683314056;914.8170365926841;918.2516249256835;10675.092617127553;10737.141028717684;10766;10781.514831832508;10785;4193.727407718549;4321.330193819963;4321.805518751664;4344.131173765197;4356.302991521405;8276.8344633107;8313.958430207811;8337.874931876027;8372.455790373728;8398.790030249293;4583.587477127075;4609.884752881205;4616.1229366420275;4624.815007399734;4654.743989080633;3794.4877441545036;4281.507626622916;4291.570842915684;4345.044090300192;4346.587074227943;3559.270349578382;3588.4796704477703;3613.439916812898;3646.5077214575695;3714.405695088773;1128.5203788389902;1131.1911982932243;1151.5278735718316;1158.09089864456;1158.3513232589776;793.2384910485998;870.3646338173169;888.8000199954959;914.4604683236922;925.167349385545;5290.92063619046;5464.508194262536;5563.276774019432;5703.344115426696;5806.390329015477;916.3402350307767;936.9250459963323;944.4758159221698;951.7573018880141;964.8359778837614;3499.3876071687355;3597.226596281812;3614.861318684606;3701.5188025556504;3763.247350529905;8995.190432861074;9533.618655253851;9593.808123837623;9648.903510964801;9655.468949207721;9743.220542356594;9830.164436023018;9831.262655300881;9836.409815411098;9847.50762461874;7341.522801017917;7436;7458.366038886756;7483.738069167553;7527.096748390229;5193.76628051731;5222.451642577626;5241.056609810271;5392.784288628411;5413.864653383697;8760.299176066026;9013.459192448474;9069.365144439826;9091.954540227256;9200.493972831558;9119.316051296311;9196.310276729138;9310.255179585482;9322.906770932475;9437.103475169848;6152.200213972155;6783.491238157059;6966.338197871194;6988.720451181895;7106.147262328554;7507.061617297838;7527.322540971339;7528.473006889465;7539.132999704885;7587.810304742426;6710.66446677674;6890;6969.024336592983;6995.895061573979;7092.538984966167;6761.121054262914;6775.322467753383;6800.761973330909;6830.419414349637;6919.308069193242;6427.710753016032;6437.645929473924;6504.60972341661;6514.218293804774;6528.281888992112;4606.516315786794;4614;4624.930626040679;4660.6271498279375;4702;4571.26859702446;4581.450225972904;4607.285870690049;4688.695234809727;4693.178693728652 11:35:17 INFO - PROCESS | 12596 | |4;prototype.html;318.63357139289815;320.22824991769835;326.92153883068056;328.1075474708791;329.5748484455052;5368.516833484986;5413.1068373718335;5442.210879422481;5444.8366549003595;5470.5350045246205;5300.390455097665;5330.653507522008;5331.840044798652;5349.304590403242;5367.570594352443;3933.4099885017263;3988.321985262498;4118.711690181688;4137.634580588398;4145.129522800215;4108.77401742904;4123.7938103094875;4149.273877071516;4151.979240103795;4156.127213285214;4070.9389359159636;4087.591240875918;4096.6927480438935;4106.3635136553885;4111.52717437494;4037.152198038401;4049.1294371710223;4108.363203703401;4116.320807066861;4116.485439320085;3577.481265216529;3602.4596310553366;3619.800910949923;3621.311950729353;3622.7826330420257;5064.696118232918;5092.675904264918;5094.465081166498;5101.69389836611;5126.871828204287;4592.678512504126;4721.693089949143;4764.976175119137;4767.308740232682;4768.475467698585;5504.504594586507;5557;5558.055130627763;5565.916511252334;5588.413216612238;3986.3023970804993;4001.139754952699;4001.2597669431248;4009.037830920587;4020.5376381716183;5751.511121554673;5917.940820591782;5920.648323482237;5939.46544810969;5970.731317090698;4085.22380747657;4102.077032667686;4105.2405305018665;4110.23960567296;4118.485189351331;4160.167966406731;4164.541900390955;4165.000399904062;4167.770772607536;4187.853425130167;4637.86086417408;4732.2428411454;4745.5966242869745;4761.9285660726755;4785.904281914342;5591.776328946878;5605.5795815313195;5626.493615574618;5639.210510528529;5655.943440565542;1447.4427345471859;1474.955751327462;1475.1149310413666;1479.2012313350886;1480.6520467690084;907.1880666803174;975.2490582251627;990.6978371596732;1003.4631472162358;1021.4841505039907;4805.8558243252755;4807.495213002654;4831.4202295724735;4840.370751802243;4845.75771211438;5729.169270455761;5732.627379220421;5757.193992841006;5763.394843541368;5788.82633520995;4637.698549594265;4837.467878533429;4845.394325709286;4850.248211527219;4925.482824303468;3120.7971481853606;3141.52877068437;3151.7163455288674;3152.542881282201;3156.479180935166;5480.945190548123;5485.588580856452;5493.533049690741;5494.450554944474;5497.120460726344;5896.498797602252;6021.337652858182;6049.21360223177;6059.484943779828;6063.181470501514;5445.918311225335;5446.891062178814;5449.155380915964;5450.100733379028;5469.261649677323;5704.201411802268;5816.185733997159;5838.912416313844;5850.414958504116;5858.297004359504;5753.741081651252;5757.65454072757;5773.364929857628;5778.855528611818;5786.768529258782;5447.700376479332;5474.5894057945015;5480.492484809804;5498.340199176125;5505.119180931032;3941.758346120963;4006.779627120536;4040;4043.8989025274605;4055.371417430303;5876.735546900314;5886.411358864079;5897.321807991964;5897.64614123154;5902.6458412495385;5666.744996475251;5686.800961966312;5689.800856969986;5715.256676795245;5718.285214348207;5492.286002819608;5657.5473962082115;5683.318001839806;5689.516391106802;5692.31692196939;5327.627066105335;5530.170474428868;5549.7780088796;5561.777528898799;5570.721463926869;5449.918251226314;5645.125005624135;5663.603547751618;5671.262735844479;5693.715314234355;5541.695206763667;5548.500634942875;5555.611107222456;5565.6104072714525;5580.051391263414 11:35:17 INFO - PROCESS | 12596 | |5;yui.html;633.9683015849206;675.6385333846391;676.617710993289;695.9652017399129;715.502725605704;11314.773704525904;11482.196246262765;11611.767764644712;11776.411179441026;12462.252264864115;13930.930345348284;13979.650508737286;13992.440302387919;13996.440142394318;14025.36885840137;636.907648390983;811.2860682599304;813.158381075584;817.190980928882;817.4032955942166;790.76277116865;811.3509192645889;811.7321283976273;811.7808191788214;812.5368539932241;845.8223773007676;847.3517758914436;849.2017503546647;849.3884403229665;850.086157380815;830.7341650671788;832.2343444031505;832.9791755206107;839.0434904209206;844.1136806353335;618.2086928731186;621.4407033669688;621.909823075656;624.403694471783;624.7032659486707;12389.008879289635;12409.441575129142;12428.067894907828;12435.626931192079;12463.937680311632;12109.394530273452;12264.38678066102;12393.93803030988;12438.937805311007;12482;9274.304427167923;9313.953430232874;9314.394564353295;9509.90490095104;9530.857037144448;2899.594056832045;2970.5395663672166;3005.1285127313054;3029.8788048478254;3048.1465189746905;45497.54502454966;46259.768701156616;46296.537034629895;46674.06651866977;46886;9822.410655360702;9865.901340986642;9909.405435673883;9960.850587241197;10436.843447348296;17244.655106897793;17263.3957811476;17295.56761080983;17326.74009889853;17347;5990.490808281258;6059.0608455689435;6096.847578810476;6139.539534534928;6254.9687251563455;3208.246062175387;3254.284057507392;3297.7361811055057;3303.653116422789;3393.898183054512;2657.6412184354863;2774.0984180141536;2776.88892444304;2779.63864697588;2781.15174871662;489.4420360788704;495.1112752609075;502.56277038975594;504.07502233401385;504.66187654271727;510.86972821930914;531.131599834266;559.8768270980378;566.0546886699223;569.9772009119589;7374.151972523121;7387.1135463744695;7582.772516824504;7597.658105385271;7639.69441222356;452.7419370958522;571.3714913595095;578.713536799287;581.7934633205235;585.0726598341591;3526.8765593204116;3638.774672069941;3699.389600715852;3715.108373990223;3737.1591391936595;44323.33514997278;45826.77086614545;52024.47975520272;52303.47696523062;52596.474035259154;38396;38512;38553.421698674545;38783.806080969414;42881;36567.4514882277;37053.629463705554;39280.60719392826;40484.59515404867;40653.390199147034;7525.924740752523;7806.336461400618;7868.645910934136;8249.422540422353;8436.282915952213;26688.332791680365;26971.46057078808;26972.32569185786;27353.45293094167;27603.30991725223;32368;35941.46087808633;36047.45928811018;37387;37440;10352.741181470523;10440.738981525219;10547.261691681508;10670.413127278074;10739.140868730326;11073.833892491459;11256;11714.355710436008;11844.940775296069;11937.701557461132;10194.133498652698;10297.227707922086;10301.227407944583;10360.326578772356;10377.533011014371;9809.901900980898;9893.505324733878;10066.798664026825;10100.444475553915;10539.209559282932;9987.800243995225;10248.846267305848;10386.324888882198;10499.317544359592;10515.948405159423;9405.623775048922;9420.670276540288;9593.952030239805;9613.807723845344;9823.852642210515;8942.46345219289;9072.591733371883;9251.537423128952;9286.746289251136;9299.72100836976 11:35:17 INFO - PROCESS | 12596 | __end_tp_report 11:35:17 INFO - PROCESS | 12596 | __start_cc_report 11:35:17 INFO - PROCESS | 12596 | _x_x_mozilla_cycle_collect,357 11:35:17 INFO - PROCESS | 12596 | __end_cc_report 11:35:17 INFO - PROCESS | 12596 | __startTimestamp1461090917242__endTimestamp 11:35:17 INFO - PROCESS | 12596 | 11:35:17 INFO - PROCESS | 12596 | ------- Summary: start ------- 11:35:17 INFO - PROCESS | 12596 | Number of tests: 6 11:35:17 INFO - PROCESS | 12596 | 11:35:17 INFO - PROCESS | 12596 | [#0] dojo.html Cycles:165 Average:4752.07 Median:4846.08 stddev:494.87 (10.2%) stddev-sans-first:466.96 11:35:17 INFO - PROCESS | 12596 | Values: 2609.3 2689.3 2696.2 2698.9 2703.0 4741.4 4769.8 4800.3 4806.4 4840.8 4643.4 4784.6 4845.5 4846.1 4860.5 4412.2 4464.5 4510.6 4512.1 4552.7 4424.5 4500.3 4526.0 4543.8 4545.4 4297.5 4350.7 4394.0 4431.5 4500.8 4365.1 4406.7 4407.4 4411.5 4449.7 4365.0 4366.8 4367.4 4372.6 4393.4 4648.1 4679.2 4716.6 4717.0 4726.8 5120.8 5120.9 5128.1 5134.9 5154.0 5119.1 5126.6 5129.2 5134.7 5151.2 4763.1 4788.0 4790.0 4791.6 4844.6 5238.0 5262.3 5265.4 5274.8 5276.6 5032.0 5051.6 5086.4 5139.0 5173.9 5157.5 5201.4 5218.6 5220.9 5229.1 4936.2 5026.7 5029.2 5073.7 5121.0 4913.7 5086.5 5094.1 5094.2 5162.9 4387.9 4392.0 4400.9 4445.4 4446.7 3821.2 3842.3 3860.7 3870.9 3922.0 4237.6 4412.9 4435.6 4484.0 4495.3 5158.4 5160.4 5163.7 5180.1 5245.8 4451.6 4452.4 4457.5 4458.1 4500.8 4476.7 4618.7 4641.7 4674.3 4692.3 4794.9 4802.8 4814.3 4817.5 4878.0 5005.8 5173.5 5196.4 5196.6 5258.0 4811.8 4827.4 4828.6 4862.7 4871.5 5003.4 5063.5 5068.0 5073.2 5124.1 5122.4 5142.6 5149.4 5154.0 5171.4 4739.8 4890.2 5005.1 5060.1 5076.0 4976.1 4992.9 4996.8 5007.2 5039.0 4665.4 4874.0 4919.1 4935.6 4940.0 5114.7 5118.6 5134.5 5141.2 5180.2 4969.7 4974.7 4988.6 4991.0 5002.6 11:35:17 INFO - PROCESS | 12596 | 11:35:17 INFO - PROCESS | 12596 | [#1] ext.html Cycles:180 Average:41421.99 Median:33791.00 stddev:73160.73 (216.5%) stddev-sans-first:73301.73 11:35:17 INFO - PROCESS | 12596 | Values: 587.7 595.3 623.3 639.4 646.4 33780.0 34857.8 34914.8 34951.5 34980.8 37059.8 37870.8 38021.2 38045.2 38075.0 7573.6 7724.3 7746.7 7761.7 7797.4 7634.7 7773.6 7786.5 7867.6 7941.2 7830.8 7913.3 7936.6 7981.3 7995.4 7853.1 7864.8 7954.3 7958.5 7965.2 7731.8 7921.8 7922.8 7929.5 7936.1 37103.4 37157.4 37206.4 37310.3 37420.0 2505.3 2920.1 2921.2 3248.9 3705.4 24508.9 24975.8 24995.8 25105.5 25414.0 3233.7 3242.5 3252.6 3281.6 3284.2 178534.0 179575.1 180195.0 180430.0 180802.0 31908.4 32116.2 32164.2 32195.2 33337.5 33311.7 33637.3 33719.3 33721.5 33802.0 12251.8 12298.9 12404.6 12432.3 12502.5 9960.5 9969.6 10060.8 10060.9 10104.8 7996.0 8130.5 8136.7 8175.3 8284.8 3186.4 3257.5 3279.4 3283.5 3306.9 910.4 918.7 973.9 1072.3 1093.6 27678.6 27967.3 28099.7 28402.4 28736.3 874.2 883.5 898.8 931.6 1016.6 14580.2 15266.2 15327.3 15406.2 15508.2 315977.0 322160.0 322870.0 323992.0 324512.0 49300.3 50119.5 50154.2 50511.5 50522.7 1236.7 1268.3 1270.4 1284.2 1364.2 27125.3 28470.4 28523.0 28894.6 29090.3 42891.4 43132.1 43305.0 43428.3 43641.3 304885.0 309622.0 310722.0 310971.0 311611.0 25765.5 26145.7 26383.5 26415.9 26530.2 42459.9 42650.8 43268.1 44385.6 44994.6 34684.7 34718.0 35215.0 35357.6 35773.5 34561.3 34603.8 34805.8 35090.5 35199.0 30740.3 35158.5 35651.6 36890.2 37898.8 28427.4 29945.0 30262.2 31808.5 31951.0 24327.3 25060.1 25309.2 25326.5 25512.5 11:35:17 INFO - PROCESS | 12596 | 11:35:17 INFO - PROCESS | 12596 | [#2] jquery.html Cycles:180 Average:22883.94 Median:4444.15 stddev:59663.00 (1342.5%) stddev-sans-first:59806.45 11:35:17 INFO - PROCESS | 12596 | Values: 383.5 383.8 385.8 394.1 402.3 15233.9 16381.4 16686.9 16888.5 16902.9 15112.2 16258.3 16667.4 16775.6 16812.0 4638.3 4789.3 4829.0 4833.1 4839.5 3434.1 5073.9 5376.5 5383.3 5393.1 5320.4 5322.0 5337.4 5363.9 5370.0 5036.6 5304.9 5361.2 5378.2 5378.5 5024.2 5191.5 5202.6 5215.8 5217.7 11629.5 11723.5 11739.1 14104.7 20078.1 1897.8 1922.9 1947.3 1964.1 2052.6 8760.6 8932.1 9174.2 11692.5 13355.6 1804.7 1814.2 1833.6 1900.1 1945.8 64623.7 66511.0 68538.0 69255.0 69497.0 9978.6 9982.8 10098.2 10224.2 10305.3 14139.6 14167.7 14194.1 14207.7 14241.2 6919.4 6932.6 6949.9 7068.3 7119.7 6399.5 6410.3 6420.2 6505.4 6565.9 4401.0 4401.7 4410.1 4418.0 4470.4 1593.0 1799.6 1836.7 1854.8 1877.7 883.7 883.7 919.9 920.3 990.4 16276.3 16333.3 16485.7 16843.9 17012.5 911.8 918.4 927.9 930.5 943.9 9454.4 9455.3 9465.6 9488.6 9652.4 356616.0 356973.0 360967.0 362173.0 367389.0 73063.6 73922.0 74237.0 75600.6 77835.6 918.1 941.8 942.1 946.1 946.7 14660.5 14773.3 14902.4 14935.3 14963.0 29991.4 32870.7 33374.5 33592.3 33980.5 45887.3 46316.5 46533.8 46592.3 46712.5 6845.3 7169.0 7208.8 7349.5 7611.5 8313.7 11310.7 11430.6 11828.2 11987.7 10336.7 10386.6 10490.4 10575.2 10577.3 10145.7 10341.7 10352.3 10422.5 10596.5 9951.7 10033.9 10221.2 10261.5 10448.7 7708.3 7717.4 7867.1 7962.6 8040.1 7449.6 9444.2 10459.3 10500.8 10631.0 11:35:17 INFO - PROCESS | 12596 | 11:35:17 INFO - PROCESS | 12596 | [#3] mootools.html Cycles:180 Average:5186.40 Median:5439.19 stddev:2871.81 (52.8%) stddev-sans-first:2866.28 11:35:17 INFO - PROCESS | 12596 | Values: 1469.6 1479.2 1653.4 1787.3 1806.1 6575.3 7644.4 7749.7 7890.3 7901.4 7825.1 7844.8 7858.4 7884.1 7902.3 2227.2 2265.7 2268.9 2272.1 2274.9 2227.0 2245.6 2247.0 2248.6 2260.8 2221.3 2244.5 2254.0 2263.2 2273.9 2159.7 2173.8 2174.4 2175.4 2183.5 2231.5 2231.6 2242.6 2244.0 2250.9 7865.8 7942.2 7969.1 7977.6 8012.5 4589.4 4676.6 4690.1 4690.4 4728.6 2578.6 2750.6 2785.6 2786.1 2815.6 882.8 902.2 909.3 914.8 918.3 10675.1 10737.1 10766.0 10781.5 10785.0 4193.7 4321.3 4321.8 4344.1 4356.3 8276.8 8314.0 8337.9 8372.5 8398.8 4583.6 4609.9 4616.1 4624.8 4654.7 3794.5 4281.5 4291.6 4345.0 4346.6 3559.3 3588.5 3613.4 3646.5 3714.4 1128.5 1131.2 1151.5 1158.1 1158.4 793.2 870.4 888.8 914.5 925.2 5290.9 5464.5 5563.3 5703.3 5806.4 916.3 936.9 944.5 951.8 964.8 3499.4 3597.2 3614.9 3701.5 3763.2 8995.2 9533.6 9593.8 9648.9 9655.5 9743.2 9830.2 9831.3 9836.4 9847.5 7341.5 7436.0 7458.4 7483.7 7527.1 5193.8 5222.5 5241.1 5392.8 5413.9 8760.3 9013.5 9069.4 9092.0 9200.5 9119.3 9196.3 9310.3 9322.9 9437.1 6152.2 6783.5 6966.3 6988.7 7106.1 7507.1 7527.3 7528.5 7539.1 7587.8 6710.7 6890.0 6969.0 6995.9 7092.5 6761.1 6775.3 6800.8 6830.4 6919.3 6427.7 6437.6 6504.6 6514.2 6528.3 4606.5 4614.0 4624.9 4660.6 4702.0 4571.3 4581.5 4607.3 4688.7 4693.2 11:35:17 INFO - PROCESS | 12596 | 11:35:17 INFO - PROCESS | 12596 | [#4] prototype.html Cycles:180 Average:4665.21 Median:5340.57 stddev:1366.22 (25.6%) stddev-sans-first:1330.54 11:35:17 INFO - PROCESS | 12596 | Values: 318.6 320.2 326.9 328.1 329.6 5368.5 5413.1 5442.2 5444.8 5470.5 5300.4 5330.7 5331.8 5349.3 5367.6 3933.4 3988.3 4118.7 4137.6 4145.1 4108.8 4123.8 4149.3 4152.0 4156.1 4070.9 4087.6 4096.7 4106.4 4111.5 4037.2 4049.1 4108.4 4116.3 4116.5 3577.5 3602.5 3619.8 3621.3 3622.8 5064.7 5092.7 5094.5 5101.7 5126.9 4592.7 4721.7 4765.0 4767.3 4768.5 5504.5 5557.0 5558.1 5565.9 5588.4 3986.3 4001.1 4001.3 4009.0 4020.5 5751.5 5917.9 5920.6 5939.5 5970.7 4085.2 4102.1 4105.2 4110.2 4118.5 4160.2 4164.5 4165.0 4167.8 4187.9 4637.9 4732.2 4745.6 4761.9 4785.9 5591.8 5605.6 5626.5 5639.2 5655.9 1447.4 1475.0 1475.1 1479.2 1480.7 907.2 975.2 990.7 1003.5 1021.5 4805.9 4807.5 4831.4 4840.4 4845.8 5729.2 5732.6 5757.2 5763.4 5788.8 4637.7 4837.5 4845.4 4850.2 4925.5 3120.8 3141.5 3151.7 3152.5 3156.5 5480.9 5485.6 5493.5 5494.5 5497.1 5896.5 6021.3 6049.2 6059.5 6063.2 5445.9 5446.9 5449.2 5450.1 5469.3 5704.2 5816.2 5838.9 5850.4 5858.3 5753.7 5757.7 5773.4 5778.9 5786.8 5447.7 5474.6 5480.5 5498.3 5505.1 3941.8 4006.8 4040.0 4043.9 4055.4 5876.7 5886.4 5897.3 5897.6 5902.6 5666.7 5686.8 5689.8 5715.3 5718.3 5492.3 5657.5 5683.3 5689.5 5692.3 5327.6 5530.2 5549.8 5561.8 5570.7 5449.9 5645.1 5663.6 5671.3 5693.7 5541.7 5548.5 5555.6 5565.6 5580.1 11:35:17 INFO - PROCESS | 12596 | 11:35:17 INFO - PROCESS | 12596 | [#5] yui.html Cycles:180 Average:12114.84 Median:45662.16 stddev:13445.60 (29.4%) stddev-sans-first:13455.67 11:35:17 INFO - PROCESS | 12596 | Values: 634.0 675.6 676.6 696.0 715.5 11314.8 11482.2 11611.8 11776.4 12462.3 13930.9 13979.7 13992.4 13996.4 14025.4 636.9 811.3 813.2 817.2 817.4 790.8 811.4 811.7 811.8 812.5 845.8 847.4 849.2 849.4 850.1 830.7 832.2 833.0 839.0 844.1 618.2 621.4 621.9 624.4 624.7 12389.0 12409.4 12428.1 12435.6 12463.9 12109.4 12264.4 12393.9 12438.9 12482.0 9274.3 9314.0 9314.4 9509.9 9530.9 2899.6 2970.5 3005.1 3029.9 3048.1 45497.5 46259.8 46296.5 46674.1 46886.0 9822.4 9865.9 9909.4 9960.9 10436.8 17244.7 17263.4 17295.6 17326.7 17347.0 5990.5 6059.1 6096.8 6139.5 6255.0 3208.2 3254.3 3297.7 3303.7 3393.9 2657.6 2774.1 2776.9 2779.6 2781.2 489.4 495.1 502.6 504.1 504.7 510.9 531.1 559.9 566.1 570.0 7374.2 7387.1 7582.8 7597.7 7639.7 452.7 571.4 578.7 581.8 585.1 3526.9 3638.8 3699.4 3715.1 3737.2 44323.3 45826.8 52024.5 52303.5 52596.5 38396.0 38512.0 38553.4 38783.8 42881.0 36567.5 37053.6 39280.6 40484.6 40653.4 7525.9 7806.3 7868.6 8249.4 8436.3 26688.3 26971.5 26972.3 27353.5 27603.3 32368.0 35941.5 36047.5 37387.0 37440.0 10352.7 10440.7 10547.3 10670.4 10739.1 11073.8 11256.0 11714.4 11844.9 11937.7 10194.1 10297.2 10301.2 10360.3 10377.5 9809.9 9893.5 10066.8 10100.4 10539.2 9987.8 10248.8 10386.3 10499.3 10515.9 9405.6 9420.7 9594.0 9613.8 9823.9 8942.5 9072.6 9251.5 9286.7 9299.7 11:35:17 INFO - PROCESS | 12596 | -------- Summary: end -------- 11:35:17 INFO - PROCESS | 12596 | 11:35:17 INFO - TEST-INFO | 12596: exit 0 11:35:17 INFO - TEST-OK | dromaeo_css | took 1115248ms 11:35:17 INFO - TEST-START | kraken 11:35:17 INFO - operating with platform_type : linux_ 11:35:17 INFO - Initialising browser for kraken test... 11:35:17 INFO - TEST-INFO | started process 12831 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Zgcbt/profile http://localhost:56179/getInfo.html) 11:35:21 INFO - PROCESS | 12831 | 11:35:21 INFO - PROCESS | 12831 | (process:12900): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 11:35:22 INFO - PROCESS | 12831 | __metrics Screen width/height:1600/1200 11:35:22 INFO - PROCESS | 12831 | colorDepth:24 11:35:22 INFO - PROCESS | 12831 | Browser inner width/height: 1024/697 11:35:22 INFO - PROCESS | 12831 | __metrics 11:35:23 ERROR - PROCESS | 12831 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 11:35:24 INFO - TEST-INFO | 12831: exit 0 11:35:24 INFO - Browser initialized. 11:35:24 INFO - Running cycle 1/1 for kraken test... 11:35:24 INFO - TEST-INFO | started process 12951 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Zgcbt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:35:26 INFO - PROCESS | 12951 | 11:35:26 INFO - PROCESS | 12951 | (process:13009): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 11:35:36 INFO - PROCESS | 12951 | RSS: Main: 164466688 11:35:36 INFO - PROCESS | 12951 | 11:35:38 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56179/tests/kraken/driver.html?testName=audio-beat-detection) 11:35:39 INFO - PROCESS | 12951 | RSS: Main: 168808448 11:35:39 INFO - PROCESS | 12951 | 11:35:42 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56179/tests/kraken/driver.html?testName=audio-dft) 11:35:43 INFO - PROCESS | 12951 | RSS: Main: 172675072 11:35:43 INFO - PROCESS | 12951 | 11:35:46 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56179/tests/kraken/driver.html?testName=audio-fft) 11:35:46 INFO - PROCESS | 12951 | RSS: Main: 164392960 11:35:46 INFO - PROCESS | 12951 | 11:35:48 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56179/tests/kraken/driver.html?testName=audio-oscillator) 11:35:49 INFO - PROCESS | 12951 | RSS: Main: 164720640 11:35:49 INFO - PROCESS | 12951 | 11:35:51 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56179/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:35:52 INFO - PROCESS | 12951 | RSS: Main: 164913152 11:35:52 INFO - PROCESS | 12951 | 11:35:55 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56179/tests/kraken/driver.html?testName=imaging-darkroom) 11:35:56 INFO - PROCESS | 12951 | RSS: Main: 165261312 11:35:56 INFO - PROCESS | 12951 | 11:35:59 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56179/tests/kraken/driver.html?testName=imaging-desaturate) 11:36:00 INFO - PROCESS | 12951 | RSS: Main: 164544512 11:36:00 INFO - PROCESS | 12951 | 11:36:03 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56179/tests/kraken/driver.html?testName=json-parse-financial) 11:36:03 INFO - PROCESS | 12951 | RSS: Main: 165535744 11:36:03 INFO - PROCESS | 12951 | 11:36:05 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56179/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:36:06 INFO - PROCESS | 12951 | RSS: Main: 165429248 11:36:06 INFO - PROCESS | 12951 | 11:36:08 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:36:08 INFO - PROCESS | 12951 | RSS: Main: 165535744 11:36:08 INFO - PROCESS | 12951 | 11:36:10 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:36:11 INFO - PROCESS | 12951 | RSS: Main: 166567936 11:36:11 INFO - PROCESS | 12951 | 11:36:14 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:36:15 INFO - PROCESS | 12951 | RSS: Main: 166125568 11:36:15 INFO - PROCESS | 12951 | 11:36:17 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:36:18 INFO - PROCESS | 12951 | RSS: Main: 166051840 11:36:18 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56179/tests/kraken/driver.html?testName=ai-astar) 11:36:20 INFO - PROCESS | 12951 | RSS: Main: 167751680 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | __start_tp_report 11:36:20 INFO - PROCESS | 12951 | _x_x_mozilla_page_load 11:36:20 INFO - PROCESS | 12951 | _x_x_mozilla_page_load_details 11:36:20 INFO - PROCESS | 12951 | |i|pagename|runs| 11:36:20 INFO - PROCESS | 12951 | |0;ai-astar;115;106;115;115;113;125;114;112;114;112 11:36:20 INFO - PROCESS | 12951 | |1;audio-beat-detection;159;131;165;182;132;162;157;149;189;135 11:36:20 INFO - PROCESS | 12951 | |2;audio-dft;163;163;163;165;167;162;163;160;166;162 11:36:20 INFO - PROCESS | 12951 | |3;audio-fft;74;74;72;158;77;77;76;74;162;77 11:36:20 INFO - PROCESS | 12951 | |4;audio-oscillator;94;94;155;94;94;93;145;94;95;140 11:36:20 INFO - PROCESS | 12951 | |5;imaging-gaussian-blur;117;118;117;117;117;117;119;117;117;118 11:36:20 INFO - PROCESS | 12951 | |6;imaging-darkroom;150;150;149;150;149;150;150;150;150;148 11:36:20 INFO - PROCESS | 12951 | |7;imaging-desaturate;95;95;96;96;96;95;96;95;95;95 11:36:20 INFO - PROCESS | 12951 | |8;json-parse-financial;64;65;66;64;65;64;64;66;66;66 11:36:20 INFO - PROCESS | 12951 | |9;json-stringify-tinderbox;65;65;64;65;65;65;64;67;65;64 11:36:20 INFO - PROCESS | 12951 | |10;stanford-crypto-aes;79;79;84;79;79;80;81;82;119;78 11:36:20 INFO - PROCESS | 12951 | |11;stanford-crypto-ccm;135;156;146;146;141;136;137;136;144;135 11:36:20 INFO - PROCESS | 12951 | |12;stanford-crypto-pbkdf2;182;177;177;177;176;173;172;178;171;168 11:36:20 INFO - PROCESS | 12951 | |13;stanford-crypto-sha256-iterative;76;78;74;73;73;71;71;71;73;71 11:36:20 INFO - PROCESS | 12951 | __end_tp_report 11:36:20 INFO - PROCESS | 12951 | __start_cc_report 11:36:20 INFO - PROCESS | 12951 | _x_x_mozilla_cycle_collect,917 11:36:20 INFO - PROCESS | 12951 | __end_cc_report 11:36:20 INFO - PROCESS | 12951 | __startTimestamp1461090980666__endTimestamp 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | ------- Summary: start ------- 11:36:20 INFO - PROCESS | 12951 | Number of tests: 14 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.50 stddev:4.68 (4.1%) stddev-sans-first:4.95 11:36:20 INFO - PROCESS | 12951 | Values: 115.0 106.0 115.0 115.0 113.0 125.0 114.0 112.0 114.0 112.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#1] audio-beat-detection Cycles:10 Average:156.10 Median:160.50 stddev:19.95 (12.4%) stddev-sans-first:21.14 11:36:20 INFO - PROCESS | 12951 | Values: 159.0 131.0 165.0 182.0 132.0 162.0 157.0 149.0 189.0 135.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#2] audio-dft Cycles:10 Average:163.40 Median:163.00 stddev:2.07 (1.3%) stddev-sans-first:2.19 11:36:20 INFO - PROCESS | 12951 | Values: 163.0 163.0 163.0 165.0 167.0 162.0 163.0 160.0 166.0 162.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#3] audio-fft Cycles:10 Average:92.10 Median:75.00 stddev:35.84 (47.8%) stddev-sans-first:37.41 11:36:20 INFO - PROCESS | 12951 | Values: 74.0 74.0 72.0 158.0 77.0 77.0 76.0 74.0 162.0 77.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#4] audio-oscillator Cycles:10 Average:109.80 Median:94.00 stddev:25.70 (27.3%) stddev-sans-first:26.61 11:36:20 INFO - PROCESS | 12951 | Values: 94.0 94.0 155.0 94.0 94.0 93.0 145.0 94.0 95.0 140.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 11:36:20 INFO - PROCESS | 12951 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 119.0 117.0 117.0 118.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:150.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 11:36:20 INFO - PROCESS | 12951 | Values: 150.0 150.0 149.0 150.0 149.0 150.0 150.0 150.0 150.0 148.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 11:36:20 INFO - PROCESS | 12951 | Values: 95.0 95.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.50 stddev:0.94 (1.4%) stddev-sans-first:0.93 11:36:20 INFO - PROCESS | 12951 | Values: 64.0 65.0 66.0 64.0 65.0 64.0 64.0 66.0 66.0 66.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 11:36:20 INFO - PROCESS | 12951 | Values: 65.0 65.0 64.0 65.0 65.0 65.0 64.0 67.0 65.0 64.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:79.50 stddev:12.43 (15.6%) stddev-sans-first:13.05 11:36:20 INFO - PROCESS | 12951 | Values: 79.0 79.0 84.0 79.0 79.0 80.0 81.0 82.0 119.0 78.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:142.50 stddev:6.84 (4.8%) stddev-sans-first:6.88 11:36:20 INFO - PROCESS | 12951 | Values: 135.0 156.0 146.0 146.0 141.0 136.0 137.0 136.0 144.0 135.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:177.00 stddev:4.07 (2.3%) stddev-sans-first:3.46 11:36:20 INFO - PROCESS | 12951 | Values: 182.0 177.0 177.0 177.0 176.0 173.0 172.0 178.0 171.0 168.0 11:36:20 INFO - PROCESS | 12951 | 11:36:20 INFO - PROCESS | 12951 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:73.00 stddev:2.38 (3.3%) stddev-sans-first:2.28 11:36:20 INFO - PROCESS | 12951 | Values: 76.0 78.0 74.0 73.0 73.0 71.0 71.0 71.0 73.0 71.0 11:36:20 INFO - PROCESS | 12951 | -------- Summary: end -------- 11:36:20 INFO - PROCESS | 12951 | 11:36:21 INFO - TEST-INFO | 12951: exit 0 11:36:21 INFO - TEST-OK | kraken | took 63742ms 11:36:21 INFO - SUITE-END | took 1178s 11:36:21 INFO - Completed test suite (00:19:39) 11:36:21 INFO - javascript benchmark 11:36:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2609.282447326985, 2689.32766808298, 2696.177665811928, 2698.9325266868336, 2702.9864850675744, 4741.4073240844855, 4769.761511924407, 4800.327954086426, 4806.4232292125, 4840.806367745286, 4643.396358473394, 4784.5933095686905, 4845.466998630148, 4846.079244943458, 4860.4896485869, 4412.183746006991, 4464.508904020555, 4510.594046535811, 4512.142692888341, 4552.704074235181, 4424.4690637123595, 4500.279955207167, 4526.0495295987885, 4543.8182472701055, 4545.386372839674, 4297.505786834491, 4350.651947844165, 4394.0, 4431.534688857671, 4500.79746411409, 4365.148795984785, 4406.713563618355, 4407.427034485529, 4411.470623525165, 4449.688521803475, 4365.017870979036, 4366.8471603494045, 4367.410399596046, 4372.584604462571, 4393.406890069831, 4648.070385922795, 4679.15775160471, 4716.599089077398, 4716.952830471686, 4726.787294571753, 5120.820771272987, 5120.871978200537, 5128.1282182029145, 5134.871628209287, 5154.0, 5119.129747942821, 5126.5898728101665, 5129.230615407719, 5134.717590532519, 5151.150060240056, 4763.071201115784, 4788.0, 4790.0419916016235, 4791.592714619227, 4844.612431005476, 5237.973810130925, 5262.342207224097, 5265.420803711665, 5274.7626356814035, 5276.604254680914, 5032.018756342478, 5051.595872330205, 5086.3896332440345, 5138.97430512845, 5173.948260517422, 5157.535821776059, 5201.375834899836, 5218.556422704112, 5220.869478263074, 5229.058769421541, 4936.2102063669645, 5026.698398096126, 5029.17018691919, 5073.746312684425, 5120.950205207923, 4913.729744863996, 5086.491350864884, 5094.057599344133, 5094.2358646203365, 5162.948370516322, 4387.934180987288, 4392.0, 4400.911981760347, 4445.4220951276775, 4446.688731788744, 3821.159344944108, 3842.269968705937, 3860.749051311656, 3870.9225815483533, 3922.0391004204153, 4237.597428244312, 4412.911741765147, 4435.556444355539, 4483.955160448354, 4495.325701144789, 5158.432572417031, 5160.354955630546, 5163.715995620202, 5180.093483640423, 5245.842624721265, 4451.643868490513, 4452.354408610733, 4457.531959144243, 4458.108378324283, 4500.819967201341, 4476.686631935799, 4618.722876627413, 4641.721496710208, 4674.322223277673, 4692.296155576691, 4794.856154315376, 4802.831900883522, 4814.325994360863, 4817.542333478314, 4877.95122048775, 5005.824796132118, 5173.508516690908, 5196.4283928767045, 5196.636235463481, 5258.0, 4811.831585894477, 4827.444843843003, 4828.613710903049, 4862.70823750562, 4871.5128487151005, 5003.44962054181, 5063.518965698253, 5068.0, 5073.162928116967, 5124.077666020152, 5122.359705036871, 5142.614303927146, 5149.356330458692, 5153.99559513163, 5171.405288391883, 4739.810407583658, 4890.168671325812, 5005.099082165245, 5060.139776238122, 5076.0, 4976.129177394014, 4992.900141997067, 4996.750162491933, 5007.173816320266, 5039.017391608601, 4665.370175026464, 4873.975630121827, 4919.065377578248, 4935.580475659609, 4940.036692844861, 5114.71869047206, 5118.616103792156, 5134.486551344836, 5141.177411614124, 5180.171172612364, 4969.652124351261, 4974.726390048582, 4988.600911926965, 4990.950090499048, 5002.624803139706], "value": 4720.6786484188715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.7325816753375, 595.2767387624036, 623.2520974830203, 639.4053530216897, 646.3601034975372, 33780.0, 34857.825710871475, 34914.825425872834, 34951.475727864105, 34980.82509587448, 37059.81470092646, 37870.81064594673, 38021.23957520855, 38045.23909521808, 38075.0, 7573.5834529100875, 7724.343430808388, 7746.690132394698, 7761.650725717356, 7797.415193860455, 7634.694612215505, 7773.572453515055, 7786.454948153631, 7867.645955931968, 7941.245581669732, 7830.804229894241, 7913.327367173768, 7936.642851071716, 7981.321587665056, 7995.360371170293, 7853.136155022914, 7864.842703145933, 7954.284114429729, 7958.522488650699, 7965.163657815933, 7731.7680469586, 7921.762347129596, 7922.762317130495, 7929.5242285463055, 7936.087349954713, 37103.4434483483, 37157.442638360444, 37206.44190337147, 37310.25379492422, 37420.0, 2505.2859934918642, 2920.1385591250632, 2921.2061433005056, 3248.870045198189, 3705.370087085202, 24508.877455612786, 24975.750242497525, 24995.750042499523, 25105.49789004228, 25414.0, 3233.692799184097, 3242.5460435539044, 3252.6003342396, 3281.5898012748407, 3284.1617214357207, 178534.0, 179575.10212448984, 180195.0, 180430.0, 180802.0, 31908.40457977092, 32116.197095072344, 32164.19589510281, 32195.195120122185, 33337.49993750096, 33311.66688333086, 33637.32725345528, 33719.32561348759, 33721.49417758736, 33802.0, 12251.816222756666, 12298.938505307595, 12404.62786116418, 12432.316222607662, 12502.49990000408, 9960.452175130438, 9969.604255404249, 10060.798784024424, 10060.949695251624, 10104.848427273597, 7995.960020199978, 8130.512169269863, 8136.674533018731, 8175.3459723222, 8284.792880177924, 3186.3627274545183, 3257.527658489506, 3279.3769183854993, 3283.4582293921235, 3306.8677252909615, 910.4218821048681, 918.7381596245039, 973.87339645847, 1072.3351522056375, 1093.6117678224116, 27678.584821227698, 27967.300817479725, 28099.719002810118, 28402.43195136127, 28736.281592959924, 874.2175752701297, 883.5140672630027, 898.7842917699705, 931.5854444772011, 1016.5933626549291, 14580.19808910499, 15266.244614873573, 15327.31027103783, 15406.22968851553, 15508.224588770516, 315977.0, 322160.0, 322870.0, 323992.0, 324512.0, 49300.26049609259, 50119.49880501221, 50154.24768628473, 50511.49488505068, 50522.74738626283, 1236.6722818453118, 1268.2770820632152, 1270.4092596942467, 1284.2358796516057, 1364.1678576068591, 27125.321866953484, 28470.43059138847, 28523.0, 28894.566581500872, 29090.27274318159, 42891.356629649956, 43132.13735725205, 43305.0, 43428.348574770775, 43641.34537981996, 304885.0, 309622.0, 310722.0, 310971.0, 311611.0, 25765.484690306464, 26145.73854261509, 26383.472330553665, 26415.867920660272, 26530.204093877215, 42459.93850153771, 42650.78674606607, 43268.13463730771, 44385.557474345034, 44994.550054499035, 34684.65315346814, 34718.0, 35215.0, 35357.646423535436, 35773.46339804957, 34561.30877382388, 34603.826980864935, 34805.825970869984, 35090.47364289487, 35199.0, 30740.309282988757, 35158.472622910165, 35651.64348356483, 36890.16175868968, 37898.81050594839, 28427.43145137127, 29945.0, 30262.243443914078, 31808.522872156474, 31951.0, 24327.27018189457, 25060.12289569856, 25309.240722778348, 25326.493470130863, 25512.489750205263], "value": 15881.828768815958, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.5340061824884, 383.77549133756753, 385.7550455460782, 394.0640977678013, 402.3220872829281, 15233.923830380847, 16381.361863813612, 16686.916565417156, 16888.493345199648, 16902.91548542257, 15112.244387780594, 16258.268377923021, 16667.41664041759, 16775.580610484743, 16812.0, 4638.257878739402, 4789.257665061904, 4829.0, 4833.130036593412, 4839.4918533554, 3434.107132145635, 5073.898522029557, 5376.542993845508, 5383.300170977767, 5393.1370980643105, 5320.441353657869, 5322.0, 5337.386200586924, 5363.865903352428, 5369.973150134244, 5036.6222533309965, 5304.920426193572, 5361.249425080492, 5378.220158077076, 5378.489043540877, 5024.221245706921, 5191.454897235812, 5202.557782588447, 5215.765290561933, 5217.713025783581, 11629.534818607246, 11723.531058757639, 11739.060875130055, 14104.71790564183, 20078.09648565818, 1897.8291953724092, 1922.9423117306503, 1947.2795065825594, 1964.1357802566968, 2052.6202652509332, 8760.649574017032, 8932.106789321017, 9174.174324310845, 11692.532298708042, 13355.599332020054, 1804.7202683584067, 1814.201751229468, 1833.5599456130567, 1900.078461945955, 1945.7567804024497, 64623.676881615764, 66511.0, 68538.0, 69254.99999999949, 69497.0, 9978.550965206583, 9982.80034399308, 10098.242631802497, 10224.182065434747, 10305.330153539995, 14139.575812725634, 14167.71664566703, 14194.14835109876, 14207.71584568303, 14241.216733079573, 6919.411849992807, 6932.618705971119, 6949.930500694928, 7068.293170682993, 7119.679614417364, 6399.520035997319, 6410.294867564564, 6420.229572451242, 6505.3819887111595, 6565.934340656628, 4400.955990440055, 4401.691881568259, 4410.1400226956075, 4417.955820441819, 4470.351798989128, 1592.9964122602687, 1799.640071985608, 1836.6510363030982, 1854.7588813454165, 1877.7089551119598, 883.6995421556702, 883.7481317824389, 919.866619340192, 920.2867777472378, 990.4156547636928, 16276.267567959234, 16333.346666133459, 16485.670286594202, 16843.91578042102, 17012.48962531126, 911.8267529169569, 918.42598376015, 927.8515437529962, 930.50683137937, 943.9197668198143, 9454.385464944757, 9455.29085318604, 9465.621375145054, 9488.573014214377, 9652.372595781251, 356616.0, 356973.0, 360967.0, 362173.0, 367389.0, 73063.63468182625, 73922.0, 74237.0, 75600.62199688966, 77835.61082194666, 918.0812102705511, 941.8445956417252, 942.086176408896, 946.0728486083676, 946.654471118037, 14660.486882959045, 14773.261336933325, 14902.403903844159, 14935.253237337873, 14963.027403218755, 29991.400171996873, 32870.67129328676, 33374.49938250977, 33592.32815343728, 33980.49029264513, 45887.311690324, 46316.536834631224, 46533.76733116448, 46592.301115482616, 46712.53287467218, 6845.281245469154, 7169.0, 7208.783736487913, 7349.4855360124275, 7611.467197296136, 8313.6674533018, 11310.717232068935, 11430.599929002445, 11828.172027958304, 11987.700307492383, 10336.689899303034, 10386.584536618451, 10490.370577765358, 10575.153987680813, 10577.31247468912, 10145.746356341151, 10341.741456463347, 10352.275340726379, 10422.530986105492, 10596.523156457824, 9951.701448956543, 10033.949830250802, 10221.212030584727, 10261.486925653538, 10448.738781530523, 7708.267714567107, 7717.421193410629, 7867.134615192439, 7962.601869906597, 8040.075391330072, 7449.5902725350625, 9444.197243234126, 10459.267851250339, 10500.842487362543, 10631.0], "value": 8010.805134422878, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1469.5885152157393, 1479.1864474539002, 1653.437831137413, 1787.2761531579715, 1806.115003648212, 6575.342465753434, 7644.388448924086, 7749.651265693044, 7890.250426209508, 7901.446898717092, 7825.060992680887, 7844.8038799030055, 7858.410619203554, 7884.132745398016, 7902.288794008538, 2227.1982086448825, 2265.716785401825, 2268.886555672218, 2272.0570963050313, 2274.8862556872173, 2226.977730222701, 2245.62947113726, 2246.9887650561805, 2248.6289762189217, 2260.7513173550897, 2221.3224966385246, 2244.461329280969, 2254.0420321363436, 2263.151318255654, 2273.897674604647, 2159.686845407415, 2173.804357607823, 2174.423777698911, 2175.3908905506482, 2183.4868805830697, 2231.464448532357, 2231.6317807561895, 2242.5963326601204, 2243.955120897573, 2250.932472025842, 7865.803354916173, 7942.166072562355, 7969.083555391147, 7977.641006154737, 8012.479188852706, 4589.426321709786, 4676.55572720591, 4690.0854333405, 4690.437147542282, 4728.5507876752, 2578.561644520436, 2750.642416485864, 2785.623940768011, 2786.1084452975056, 2815.563587643898, 882.8322618702426, 902.1790170944474, 909.3134683314056, 914.8170365926841, 918.2516249256835, 10675.092617127553, 10737.141028717684, 10766.0, 10781.514831832508, 10785.0, 4193.727407718549, 4321.330193819963, 4321.805518751664, 4344.131173765197, 4356.302991521405, 8276.8344633107, 8313.958430207811, 8337.874931876027, 8372.455790373728, 8398.790030249293, 4583.587477127075, 4609.884752881205, 4616.1229366420275, 4624.815007399734, 4654.743989080633, 3794.4877441545036, 4281.507626622916, 4291.570842915684, 4345.044090300192, 4346.587074227943, 3559.270349578382, 3588.4796704477703, 3613.439916812898, 3646.5077214575695, 3714.405695088773, 1128.5203788389902, 1131.1911982932243, 1151.5278735718316, 1158.09089864456, 1158.3513232589776, 793.2384910485998, 870.3646338173169, 888.8000199954959, 914.4604683236922, 925.167349385545, 5290.92063619046, 5464.508194262536, 5563.276774019432, 5703.344115426696, 5806.390329015477, 916.3402350307767, 936.9250459963323, 944.4758159221698, 951.7573018880141, 964.8359778837614, 3499.3876071687355, 3597.226596281812, 3614.861318684606, 3701.5188025556504, 3763.247350529905, 8995.190432861074, 9533.618655253851, 9593.808123837623, 9648.903510964801, 9655.468949207721, 9743.220542356594, 9830.164436023018, 9831.262655300881, 9836.409815411098, 9847.50762461874, 7341.522801017917, 7436.0, 7458.366038886756, 7483.738069167553, 7527.096748390229, 5193.76628051731, 5222.451642577626, 5241.056609810271, 5392.784288628411, 5413.864653383697, 8760.299176066026, 9013.459192448474, 9069.365144439826, 9091.954540227256, 9200.493972831558, 9119.316051296311, 9196.310276729138, 9310.255179585482, 9322.906770932475, 9437.103475169848, 6152.200213972155, 6783.491238157059, 6966.338197871194, 6988.720451181895, 7106.147262328554, 7507.061617297838, 7527.322540971339, 7528.473006889465, 7539.132999704885, 7587.810304742426, 6710.66446677674, 6890.0, 6969.024336592983, 6995.895061573979, 7092.538984966167, 6761.121054262914, 6775.322467753383, 6800.761973330909, 6830.419414349637, 6919.308069193242, 6427.710753016032, 6437.645929473924, 6504.60972341661, 6514.218293804774, 6528.281888992112, 4606.516315786794, 4614.0, 4624.930626040679, 4660.6271498279375, 4702.0, 4571.26859702446, 4581.450225972904, 4607.285870690049, 4688.695234809727, 4693.178693728652], "value": 4199.378643167135, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.63357139289815, 320.22824991769835, 326.92153883068056, 328.1075474708791, 329.5748484455052, 5368.516833484986, 5413.1068373718335, 5442.210879422481, 5444.8366549003595, 5470.5350045246205, 5300.390455097665, 5330.653507522008, 5331.840044798652, 5349.304590403242, 5367.570594352443, 3933.4099885017263, 3988.321985262498, 4118.711690181688, 4137.634580588398, 4145.129522800215, 4108.77401742904, 4123.7938103094875, 4149.273877071516, 4151.979240103795, 4156.127213285214, 4070.9389359159636, 4087.591240875918, 4096.6927480438935, 4106.3635136553885, 4111.52717437494, 4037.152198038401, 4049.1294371710223, 4108.363203703401, 4116.320807066861, 4116.485439320085, 3577.481265216529, 3602.4596310553366, 3619.800910949923, 3621.311950729353, 3622.7826330420257, 5064.696118232918, 5092.675904264918, 5094.465081166498, 5101.69389836611, 5126.871828204287, 4592.678512504126, 4721.693089949143, 4764.976175119137, 4767.308740232682, 4768.475467698585, 5504.504594586507, 5557.0, 5558.055130627763, 5565.916511252334, 5588.413216612238, 3986.3023970804993, 4001.139754952699, 4001.2597669431248, 4009.037830920587, 4020.5376381716183, 5751.511121554673, 5917.940820591782, 5920.648323482237, 5939.46544810969, 5970.731317090698, 4085.22380747657, 4102.077032667686, 4105.2405305018665, 4110.23960567296, 4118.485189351331, 4160.167966406731, 4164.541900390955, 4165.000399904062, 4167.770772607536, 4187.853425130167, 4637.86086417408, 4732.2428411454, 4745.5966242869745, 4761.9285660726755, 4785.904281914342, 5591.776328946878, 5605.5795815313195, 5626.493615574618, 5639.210510528529, 5655.943440565542, 1447.4427345471859, 1474.955751327462, 1475.1149310413666, 1479.2012313350886, 1480.6520467690084, 907.1880666803174, 975.2490582251627, 990.6978371596732, 1003.4631472162358, 1021.4841505039907, 4805.8558243252755, 4807.495213002654, 4831.4202295724735, 4840.370751802243, 4845.75771211438, 5729.169270455761, 5732.627379220421, 5757.193992841006, 5763.394843541368, 5788.82633520995, 4637.698549594265, 4837.467878533429, 4845.394325709286, 4850.248211527219, 4925.482824303468, 3120.7971481853606, 3141.52877068437, 3151.7163455288674, 3152.542881282201, 3156.479180935166, 5480.945190548123, 5485.588580856452, 5493.533049690741, 5494.450554944474, 5497.120460726344, 5896.498797602252, 6021.337652858182, 6049.21360223177, 6059.484943779828, 6063.181470501514, 5445.918311225335, 5446.891062178814, 5449.155380915964, 5450.100733379028, 5469.261649677323, 5704.201411802268, 5816.185733997159, 5838.912416313844, 5850.414958504116, 5858.297004359504, 5753.741081651252, 5757.65454072757, 5773.364929857628, 5778.855528611818, 5786.768529258782, 5447.700376479332, 5474.5894057945015, 5480.492484809804, 5498.340199176125, 5505.119180931032, 3941.758346120963, 4006.779627120536, 4040.0, 4043.8989025274605, 4055.371417430303, 5876.735546900314, 5886.411358864079, 5897.321807991964, 5897.64614123154, 5902.6458412495385, 5666.744996475251, 5686.800961966312, 5689.800856969986, 5715.256676795245, 5718.285214348207, 5492.286002819608, 5657.5473962082115, 5683.318001839806, 5689.516391106802, 5692.31692196939, 5327.627066105335, 5530.170474428868, 5549.7780088796, 5561.777528898799, 5570.721463926869, 5449.918251226314, 5645.125005624135, 5663.603547751618, 5671.262735844479, 5693.715314234355, 5541.695206763667, 5548.500634942875, 5555.611107222456, 5565.6104072714525, 5580.051391263414], "value": 4233.043785002228, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.9683015849206, 675.6385333846391, 676.617710993289, 695.9652017399129, 715.502725605704, 11314.773704525904, 11482.196246262765, 11611.767764644712, 11776.411179441026, 12462.252264864115, 13930.930345348284, 13979.650508737286, 13992.440302387919, 13996.440142394318, 14025.36885840137, 636.907648390983, 811.2860682599304, 813.158381075584, 817.190980928882, 817.4032955942166, 790.76277116865, 811.3509192645889, 811.7321283976273, 811.7808191788214, 812.5368539932241, 845.8223773007676, 847.3517758914436, 849.2017503546647, 849.3884403229665, 850.086157380815, 830.7341650671788, 832.2343444031505, 832.9791755206107, 839.0434904209206, 844.1136806353335, 618.2086928731186, 621.4407033669688, 621.909823075656, 624.403694471783, 624.7032659486707, 12389.008879289635, 12409.441575129142, 12428.067894907828, 12435.626931192079, 12463.937680311632, 12109.394530273452, 12264.38678066102, 12393.93803030988, 12438.937805311007, 12482.0, 9274.304427167923, 9313.953430232874, 9314.394564353295, 9509.90490095104, 9530.857037144448, 2899.594056832045, 2970.5395663672166, 3005.1285127313054, 3029.8788048478254, 3048.1465189746905, 45497.54502454966, 46259.768701156616, 46296.537034629895, 46674.06651866977, 46886.0, 9822.410655360702, 9865.901340986642, 9909.405435673883, 9960.850587241197, 10436.843447348296, 17244.655106897793, 17263.3957811476, 17295.56761080983, 17326.74009889853, 17347.0, 5990.490808281258, 6059.0608455689435, 6096.847578810476, 6139.539534534928, 6254.9687251563455, 3208.246062175387, 3254.284057507392, 3297.7361811055057, 3303.653116422789, 3393.898183054512, 2657.6412184354863, 2774.0984180141536, 2776.88892444304, 2779.63864697588, 2781.15174871662, 489.4420360788704, 495.1112752609075, 502.56277038975594, 504.07502233401385, 504.66187654271727, 510.86972821930914, 531.131599834266, 559.8768270980378, 566.0546886699223, 569.9772009119589, 7374.151972523121, 7387.1135463744695, 7582.772516824504, 7597.658105385271, 7639.69441222356, 452.7419370958522, 571.3714913595095, 578.713536799287, 581.7934633205235, 585.0726598341591, 3526.8765593204116, 3638.774672069941, 3699.389600715852, 3715.108373990223, 3737.1591391936595, 44323.33514997278, 45826.77086614545, 52024.47975520272, 52303.47696523062, 52596.474035259154, 38396.0, 38512.0, 38553.421698674545, 38783.806080969414, 42881.0, 36567.4514882277, 37053.629463705554, 39280.60719392826, 40484.59515404867, 40653.390199147034, 7525.924740752523, 7806.336461400618, 7868.645910934136, 8249.422540422353, 8436.282915952213, 26688.332791680365, 26971.46057078808, 26972.32569185786, 27353.45293094167, 27603.30991725223, 32368.0, 35941.46087808633, 36047.45928811018, 37387.0, 37440.0, 10352.741181470523, 10440.738981525219, 10547.261691681508, 10670.413127278074, 10739.140868730326, 11073.833892491459, 11256.0, 11714.355710436008, 11844.940775296069, 11937.701557461132, 10194.133498652698, 10297.227707922086, 10301.227407944583, 10360.326578772356, 10377.533011014371, 9809.901900980898, 9893.505324733878, 10066.798664026825, 10100.444475553915, 10539.209559282932, 9987.800243995225, 10248.846267305848, 10386.324888882198, 10499.317544359592, 10515.948405159423, 9405.623775048922, 9420.670276540288, 9593.952030239805, 9613.807723845344, 9823.852642210515, 8942.46345219289, 9072.591733371883, 9251.537423128952, 9286.746289251136, 9299.72100836976], "value": 5704.728069308124, "unit": "score"}], "name": "dromaeo_css", "value": 6272.620618929566, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 106.0, 115.0, 115.0, 113.0, 125.0, 114.0, 112.0, 114.0, 112.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 131.0, 165.0, 182.0, 132.0, 162.0, 157.0, 149.0, 189.0, 135.0], "value": 156.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 165.0, 167.0, 162.0, 163.0, 160.0, 166.0, 162.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 72.0, 158.0, 77.0, 77.0, 76.0, 74.0, 162.0, 77.0], "value": 92.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 155.0, 94.0, 94.0, 93.0, 145.0, 94.0, 95.0, 140.0], "value": 109.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 119.0, 117.0, 117.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 149.0, 150.0, 149.0, 150.0, 150.0, 150.0, 150.0, 148.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 64.0, 65.0, 64.0, 64.0, 66.0, 66.0, 66.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 65.0, 65.0, 65.0, 64.0, 67.0, 65.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 84.0, 79.0, 79.0, 80.0, 81.0, 82.0, 119.0, 78.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 156.0, 146.0, 146.0, 141.0, 136.0, 137.0, 136.0, 144.0, 135.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 177.0, 177.0, 177.0, 176.0, 173.0, 172.0, 178.0, 171.0, 168.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 74.0, 73.0, 73.0, 71.0, 71.0, 71.0, 73.0, 71.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1601.2, "alertThreshold": 2.0}]} 11:36:21 INFO - Return code: 0 11:36:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:36:21 ERROR - # TBPL SUCCESS # 11:36:21 INFO - Running post-action listener: _resource_record_post_action 11:36:21 INFO - Running post-run listener: _resource_record_post_run 11:36:23 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 12288; Write bytes: 523640832; Read time: 312; Write time: 683680 11:36:23 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 245555200; Read time: 0; Write time: 354188 11:36:23 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 12288; Write bytes: 278085632; Read time: 312; Write time: 329492 11:36:23 INFO - Running post-run listener: _upload_blobber_files 11:36:23 INFO - Blob upload gear active. 11:36:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:36:23 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 11:36:23 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:36:23 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:36:23 INFO - (blobuploader) - INFO - Open directory for files ... 11:36:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:36:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:36:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:36:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:36:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:36:24 INFO - (blobuploader) - INFO - Done attempting. 11:36:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:36:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:36:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:36:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:36:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:36:25 INFO - (blobuploader) - INFO - Done attempting. 11:36:25 INFO - (blobuploader) - INFO - Iteration through files over. 11:36:25 INFO - Return code: 0 11:36:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:36:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:36:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1320c103cad6424bf9d13ca81577d1928f628a840a59367e7178100cd855d61f9ce2b5ecc073deb75d13e6780aff33ad9e4ec7d3c40e4b0a51fbf45ccc60625c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d1d05236654338c1a8e06b8bc6f5e7ec06257aed01d6e761b4769ee4613c3595f7b9f7235df35362564691b39bc661002ae75df691fe6ad98071d02468eec399"} 11:36:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:36:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:36:25 INFO - Contents: 11:36:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1320c103cad6424bf9d13ca81577d1928f628a840a59367e7178100cd855d61f9ce2b5ecc073deb75d13e6780aff33ad9e4ec7d3c40e4b0a51fbf45ccc60625c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d1d05236654338c1a8e06b8bc6f5e7ec06257aed01d6e761b4769ee4613c3595f7b9f7235df35362564691b39bc661002ae75df691fe6ad98071d02468eec399"} 11:36:25 INFO - Running post-run listener: copy_logs_to_upload_dir 11:36:25 INFO - Copying logs to upload dir... 11:36:25 INFO - mkdir: /builds/slave/test/build/upload/logs 11:36:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.121937 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-04-19 11:36:25.014060) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 11:36:25.017415) ========= 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=4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985 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/Fx-Team-Non-PGO/sha512/1320c103cad6424bf9d13ca81577d1928f628a840a59367e7178100cd855d61f9ce2b5ecc073deb75d13e6780aff33ad9e4ec7d3c40e4b0a51fbf45ccc60625c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d1d05236654338c1a8e06b8bc6f5e7ec06257aed01d6e761b4769ee4613c3595f7b9f7235df35362564691b39bc661002ae75df691fe6ad98071d02468eec399"} build_url:https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011134 build_url: 'https://queue.taskcluster.net/v1/task/SEUL0vZLR_CVTcJPC9k-aw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1320c103cad6424bf9d13ca81577d1928f628a840a59367e7178100cd855d61f9ce2b5ecc073deb75d13e6780aff33ad9e4ec7d3c40e4b0a51fbf45ccc60625c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d1d05236654338c1a8e06b8bc6f5e7ec06257aed01d6e761b4769ee4613c3595f7b9f7235df35362564691b39bc661002ae75df691fe6ad98071d02468eec399"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 11:36:25.042471) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 11:36:25.042804) ========= 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=4ac8d263c7293b36a1be1aed000001be-1461089748.805495-1913619985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004400 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 11:36:25.127003) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 11:36:25.127317) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 11:36:25.127616) ========= ========= Total master_lag: 0.19 =========