builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-034 starttime: 1461859181.7 results: success (0) buildid: 20160428073712 builduid: 13f3b14acfca46618e0c13fc413cc1df revision: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.699451) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.700137) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.700427) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.728821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.729146) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-28 08:59:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 20.3M=0.001s 2016-04-28 08:59:42 (20.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.404552 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.149533) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.149827) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017417 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.200788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.201151) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 --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 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 --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=d6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-28 08:59:42,866 truncating revision to first 12 chars 2016-04-28 08:59:42,866 Setting DEBUG logging. 2016-04-28 08:59:42,866 attempt 1/10 2016-04-28 08:59:42,866 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/45e67d6b5a61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 08:59:43,156 unpacking tar archive at: fx-team-45e67d6b5a61/testing/mozharness/ program finished with exit code 0 elapsedTime=0.575542 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.789697) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.790025) ========= script_repo_revision: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.790419) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.790688) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.799340) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-04-28 08:59:42.799719) ========= /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=d6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:59:43 INFO - MultiFileLogger online at 20160428 08:59:43 in /builds/slave/test 08:59:43 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 08:59:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:43 INFO - {'append_to_log': False, 08:59:43 INFO - 'base_work_dir': '/builds/slave/test', 08:59:43 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 08:59:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:43 INFO - 'branch': 'Fx-Team-Non-PGO', 08:59:43 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:43 INFO - 'config_files': ('talos/linux_config.py',), 08:59:43 INFO - 'default_actions': ('clobber', 08:59:43 INFO - 'read-buildbot-config', 08:59:43 INFO - 'download-and-extract', 08:59:43 INFO - 'populate-webroot', 08:59:43 INFO - 'create-virtualenv', 08:59:43 INFO - 'install', 08:59:43 INFO - 'run-tests'), 08:59:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:43 INFO - 'download_minidump_stackwalk': True, 08:59:43 INFO - 'download_symbols': 'ondemand', 08:59:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:43 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:43 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:43 INFO - 'installer_path': 'installer.exe', 08:59:43 INFO - 'log_level': 'info', 08:59:43 INFO - 'log_name': 'talos', 08:59:43 INFO - 'log_to_console': True, 08:59:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:59:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:59:43 INFO - 'opt_config_files': (), 08:59:43 INFO - 'pip_index': False, 08:59:43 INFO - 'sps_profile': False, 08:59:43 INFO - 'sps_profile_interval': 0, 08:59:43 INFO - 'suite': 'dromaeojs-e10s', 08:59:43 INFO - 'system_bits': '32', 08:59:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:43 INFO - 'title': 'talos-linux64-ix-034', 08:59:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:43 INFO - 'use_talos_json': True, 08:59:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:43 INFO - 'work_dir': 'build'} 08:59:43 INFO - ##### 08:59:43 INFO - ##### Running clobber step. 08:59:43 INFO - ##### 08:59:43 INFO - Running pre-action listener: _resource_record_pre_action 08:59:43 INFO - Running main action method: clobber 08:59:43 INFO - rmtree: /builds/slave/test/build 08:59:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:44 INFO - Running post-action listener: _resource_record_post_action 08:59:44 INFO - ##### 08:59:44 INFO - ##### Running read-buildbot-config step. 08:59:44 INFO - ##### 08:59:44 INFO - Running pre-action listener: _resource_record_pre_action 08:59:44 INFO - Running main action method: read_buildbot_config 08:59:44 INFO - Using buildbot properties: 08:59:44 INFO - { 08:59:44 INFO - "project": "", 08:59:44 INFO - "product": "firefox", 08:59:44 INFO - "script_repo_revision": "production", 08:59:44 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 08:59:44 INFO - "repository": "", 08:59:44 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 08:59:44 INFO - "buildid": "20160428073712", 08:59:44 INFO - "pgo_build": "False", 08:59:44 INFO - "basedir": "/builds/slave/test", 08:59:44 INFO - "buildnumber": 1232, 08:59:44 INFO - "slavename": "talos-linux64-ix-034", 08:59:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:59:44 INFO - "platform": "ubuntu64_hw", 08:59:44 INFO - "scheduler": "tests-fx-team-linux64-talos", 08:59:44 INFO - "branch": "fx-team", 08:59:44 INFO - "repo_path": "integration/fx-team", 08:59:44 INFO - "revision": "45e67d6b5a6137898898c23ae1fe60ddfcd59ed1", 08:59:44 INFO - "stage_platform": "linux64", 08:59:44 INFO - "builduid": "13f3b14acfca46618e0c13fc413cc1df", 08:59:44 INFO - "slavebuilddir": "test" 08:59:44 INFO - } 08:59:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 08:59:44 INFO - Running post-action listener: _resource_record_post_action 08:59:44 INFO - ##### 08:59:44 INFO - ##### Running download-and-extract step. 08:59:44 INFO - ##### 08:59:44 INFO - Running pre-action listener: _resource_record_pre_action 08:59:44 INFO - Running main action method: download_and_extract 08:59:44 INFO - mkdir: /builds/slave/test/build/tests 08:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:44 INFO - https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:59:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:59:53 INFO - Downloaded 1361 bytes. 08:59:53 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:53 INFO - Using the following test package requirements: 08:59:53 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:59:53 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:59:53 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:59:53 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:53 INFO - u'jsshell-linux-x86_64.zip'], 08:59:53 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:59:53 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:59:53 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:59:53 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:59:53 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:59:53 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:59:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:59:53 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')]} 08:59:53 INFO - https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:59:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:59:54 INFO - Downloaded 22300839 bytes. 08:59:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:59:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:59:55 INFO - Return code: 0 08:59:55 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:55 INFO - https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:59:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:00:05 INFO - Downloaded 22300839 bytes. 09:00:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:00:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:00:07 INFO - Return code: 0 09:00:07 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')]} 09:00:07 INFO - https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:00:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:00:07 INFO - Downloaded 10840145 bytes. 09:00:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:00:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:00:08 INFO - Return code: 0 09:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:08 INFO - https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:00:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:00:09 INFO - Downloaded 54848449 bytes. 09:00:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:09 INFO - mkdir: /builds/slave/test/properties 09:00:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:09 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:09 INFO - Contents: 09:00:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:10 INFO - Running post-action listener: _resource_record_post_action 09:00:10 INFO - Running post-action listener: set_extra_try_arguments 09:00:10 INFO - ##### 09:00:10 INFO - ##### Running populate-webroot step. 09:00:10 INFO - ##### 09:00:10 INFO - Running pre-action listener: _resource_record_pre_action 09:00:10 INFO - Running main action method: populate_webroot 09:00:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:10 INFO - '--tppagecycles', 09:00:10 INFO - '7'], 09:00:10 INFO - 'tests': ['tsvgm']}}, 09:00:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:10 INFO - 'talos_options': ['--disable-e10s'], 09:00:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:10 INFO - 'talos_options': ['--disable-e10s'], 09:00:10 INFO - 'tests': ['damp', 'tps']}, 09:00:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:10 INFO - 'tests': ['damp', 'tps']}, 09:00:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:10 INFO - 'tests': ['dromaeo_dom']}, 09:00:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:10 INFO - 'tests': ['a11yr', 09:00:10 INFO - 'ts_paint', 09:00:10 INFO - 'tpaint', 09:00:10 INFO - 'sessionrestore', 09:00:10 INFO - 'sessionrestore_no_auto_restore', 09:00:10 INFO - 'tabpaint']}, 09:00:10 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:10 INFO - 'ts_paint', 09:00:10 INFO - 'tpaint', 09:00:10 INFO - 'sessionrestore', 09:00:10 INFO - 'sessionrestore_no_auto_restore', 09:00:10 INFO - 'tabpaint']}, 09:00:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:10 INFO - 'ts_paint', 09:00:10 INFO - 'tpaint', 09:00:10 INFO - 'sessionrestore', 09:00:10 INFO - 'sessionrestore_no_auto_restore', 09:00:10 INFO - 'tabpaint']}, 09:00:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:10 INFO - 'ts_paint', 09:00:10 INFO - 'tpaint', 09:00:10 INFO - 'sessionrestore', 09:00:10 INFO - 'sessionrestore_no_auto_restore', 09:00:10 INFO - 'tabpaint']}, 09:00:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:10 INFO - 'tests': ['a11yr', 09:00:10 INFO - 'ts_paint', 09:00:10 INFO - 'tpaint', 09:00:10 INFO - 'sessionrestore', 09:00:10 INFO - 'sessionrestore_no_auto_restore', 09:00:10 INFO - 'tabpaint']}, 09:00:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:10 INFO - 'tests': ['a11yr', 09:00:10 INFO - 'ts_paint', 09:00:10 INFO - 'tpaint', 09:00:10 INFO - 'sessionrestore', 09:00:10 INFO - 'sessionrestore_no_auto_restore', 09:00:10 INFO - 'tabpaint']}, 09:00:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:10 INFO - 'tests': ['tsvgx', 09:00:10 INFO - 'tsvgr_opacity', 09:00:10 INFO - 'tart', 09:00:10 INFO - 'tscrollx', 09:00:10 INFO - 'cart']}, 09:00:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:10 INFO - 'tsvgr_opacity', 09:00:10 INFO - 'tart', 09:00:10 INFO - 'tscrollx', 09:00:10 INFO - 'cart']}, 09:00:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:10 INFO - 'talos_options': ['--disable-e10s'], 09:00:10 INFO - 'tests': ['tp5o']}, 09:00:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:10 INFO - 'tests': ['tp5o']}, 09:00:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:10 INFO - 'talos_options': ['--disable-e10s', 09:00:10 INFO - '--xperf_path', 09:00:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:10 INFO - 'tests': ['tp5n']}, 09:00:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:10 INFO - 'talos_options': ['--xperf_path', 09:00:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:10 INFO - 'tests': ['tp5n']}}, 09:00:10 INFO - 'talos.zip': {'path': '', 09:00:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:10 INFO - Running post-action listener: _resource_record_post_action 09:00:10 INFO - ##### 09:00:10 INFO - ##### Running create-virtualenv step. 09:00:10 INFO - ##### 09:00:10 INFO - Running pre-action listener: _resource_record_pre_action 09:00:10 INFO - Running main action method: create_virtualenv 09:00:10 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:10 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 09:00:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:10 INFO - Using real prefix '/usr' 09:00:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:11 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:13 INFO - Installing pip.................done. 09:00:13 INFO - Return code: 0 09:00:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:13 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')]} 09:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:13 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')]} 09:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:13 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 0x7f6046ca1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4410>, '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 0x23c5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c57a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', '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 09:00:13 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 09:00:13 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 09:00:13 INFO - Using env: {'DISPLAY': ':0', 09:00:13 INFO - 'HOME': '/home/cltbld', 09:00:13 INFO - 'LANG': 'en_US.UTF-8', 09:00:13 INFO - 'LANGUAGE': 'en_US:en', 09:00:13 INFO - 'LOGNAME': 'cltbld', 09:00:13 INFO - 'MAIL': '/var/mail/cltbld', 09:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:13 INFO - 'MOZ_NO_REMOTE': '1', 09:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:13 INFO - 'NO_EM_RESTART': '1', 09:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:13 INFO - 'PWD': '/builds/slave/test', 09:00:13 INFO - 'SHELL': '/bin/bash', 09:00:13 INFO - 'SHLVL': '1', 09:00:13 INFO - 'TERM': 'linux', 09:00:13 INFO - 'TMOUT': '86400', 09:00:13 INFO - 'USER': 'cltbld', 09:00:13 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', 09:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:13 INFO - '_': '/tools/buildbot/bin/python'} 09:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:13 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 09:00:13 INFO - Cleaning up... 09:00:13 INFO - Return code: 0 09:00:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:00:13 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')]} 09:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:13 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')]} 09:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:13 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 0x7f6046ca1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4410>, '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 0x23c5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c57a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', '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 09:00:13 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 09:00:13 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 09:00:13 INFO - Using env: {'DISPLAY': ':0', 09:00:13 INFO - 'HOME': '/home/cltbld', 09:00:13 INFO - 'LANG': 'en_US.UTF-8', 09:00:13 INFO - 'LANGUAGE': 'en_US:en', 09:00:13 INFO - 'LOGNAME': 'cltbld', 09:00:13 INFO - 'MAIL': '/var/mail/cltbld', 09:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:13 INFO - 'MOZ_NO_REMOTE': '1', 09:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:13 INFO - 'NO_EM_RESTART': '1', 09:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:13 INFO - 'PWD': '/builds/slave/test', 09:00:13 INFO - 'SHELL': '/bin/bash', 09:00:13 INFO - 'SHLVL': '1', 09:00:13 INFO - 'TERM': 'linux', 09:00:13 INFO - 'TMOUT': '86400', 09:00:13 INFO - 'USER': 'cltbld', 09:00:13 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', 09:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:13 INFO - '_': '/tools/buildbot/bin/python'} 09:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:13 INFO - Downloading/unpacking psutil>=0.7.1 09:00:13 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 09:00:13 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 09:00:13 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 09:00:13 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 09:00:13 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 09:00:13 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 09:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:15 INFO - Installing collected packages: psutil 09:00:15 INFO - Running setup.py install for psutil 09:00:15 INFO - building 'psutil._psutil_linux' extension 09:00:15 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 09:00:15 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 09:00:15 INFO - building 'psutil._psutil_posix' extension 09:00:15 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 09:00:15 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 09:00:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:15 INFO - Successfully installed psutil 09:00:15 INFO - Cleaning up... 09:00:15 INFO - Return code: 0 09:00:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:00: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')]} 09:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 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 0x7f6046ca1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4410>, '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 0x23c5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c57a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', '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 09:00:16 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 09:00:16 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 09:00:16 INFO - Using env: {'DISPLAY': ':0', 09:00:16 INFO - 'HOME': '/home/cltbld', 09:00:16 INFO - 'LANG': 'en_US.UTF-8', 09:00:16 INFO - 'LANGUAGE': 'en_US:en', 09:00:16 INFO - 'LOGNAME': 'cltbld', 09:00:16 INFO - 'MAIL': '/var/mail/cltbld', 09:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:16 INFO - 'MOZ_NO_REMOTE': '1', 09:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:16 INFO - 'NO_EM_RESTART': '1', 09:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:16 INFO - 'PWD': '/builds/slave/test', 09:00:16 INFO - 'SHELL': '/bin/bash', 09:00:16 INFO - 'SHLVL': '1', 09:00:16 INFO - 'TERM': 'linux', 09:00:16 INFO - 'TMOUT': '86400', 09:00:16 INFO - 'USER': 'cltbld', 09:00:16 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', 09:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:16 INFO - '_': '/tools/buildbot/bin/python'} 09:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:00:18 INFO - Installing collected packages: mozsystemmonitor 09:00:18 INFO - Running setup.py install for mozsystemmonitor 09:00:18 INFO - Successfully installed mozsystemmonitor 09:00:18 INFO - Cleaning up... 09:00:18 INFO - Return code: 0 09:00:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:18 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 0x7f6046ca1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4410>, '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 0x23c5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c57a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', '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 09:00:18 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 09:00:18 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 09:00:18 INFO - Using env: {'DISPLAY': ':0', 09:00:18 INFO - 'HOME': '/home/cltbld', 09:00:18 INFO - 'LANG': 'en_US.UTF-8', 09:00:18 INFO - 'LANGUAGE': 'en_US:en', 09:00:18 INFO - 'LOGNAME': 'cltbld', 09:00:18 INFO - 'MAIL': '/var/mail/cltbld', 09:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:18 INFO - 'MOZ_NO_REMOTE': '1', 09:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:18 INFO - 'NO_EM_RESTART': '1', 09:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:18 INFO - 'PWD': '/builds/slave/test', 09:00:18 INFO - 'SHELL': '/bin/bash', 09:00:18 INFO - 'SHLVL': '1', 09:00:18 INFO - 'TERM': 'linux', 09:00:18 INFO - 'TMOUT': '86400', 09:00:18 INFO - 'USER': 'cltbld', 09:00:18 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', 09:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:18 INFO - '_': '/tools/buildbot/bin/python'} 09:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:18 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 09:00:18 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 09:00:18 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 09:00:18 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 09:00:18 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 09:00:18 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 09:00:20 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00:21 INFO - Downloading docopt-0.6.1.tar.gz 09:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:21 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:21 INFO - Running setup.py install for blobuploader 09:00:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:21 INFO - Running setup.py install for requests 09:00:21 INFO - Running setup.py install for docopt 09:00:21 INFO - Successfully installed blobuploader requests docopt 09:00:21 INFO - Cleaning up... 09:00:21 INFO - Return code: 0 09:00:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:21 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 0x7f6046ca1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4410>, '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 0x23c5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c57a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', '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 09:00:21 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 09:00:21 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 09:00:21 INFO - Using env: {'DISPLAY': ':0', 09:00:21 INFO - 'HOME': '/home/cltbld', 09:00:21 INFO - 'LANG': 'en_US.UTF-8', 09:00:21 INFO - 'LANGUAGE': 'en_US:en', 09:00:21 INFO - 'LOGNAME': 'cltbld', 09:00:21 INFO - 'MAIL': '/var/mail/cltbld', 09:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:21 INFO - 'MOZ_NO_REMOTE': '1', 09:00:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:21 INFO - 'NO_EM_RESTART': '1', 09:00:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:21 INFO - 'PWD': '/builds/slave/test', 09:00:21 INFO - 'SHELL': '/bin/bash', 09:00:21 INFO - 'SHLVL': '1', 09:00:21 INFO - 'TERM': 'linux', 09:00:21 INFO - 'TMOUT': '86400', 09:00:21 INFO - 'USER': 'cltbld', 09:00:21 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', 09:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:21 INFO - '_': '/tools/buildbot/bin/python'} 09:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:21 INFO - Running setup.py (path:/tmp/pip-WdoKLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:21 INFO - Running setup.py (path:/tmp/pip-Td3ywW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:22 INFO - Running setup.py (path:/tmp/pip-E8vWFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:22 INFO - Running setup.py (path:/tmp/pip-Lbb7KL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:22 INFO - Running setup.py (path:/tmp/pip-WZET89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:22 INFO - Running setup.py (path:/tmp/pip-ztlL9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:22 INFO - Running setup.py (path:/tmp/pip-a1OsGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:22 INFO - Running setup.py (path:/tmp/pip-VhZXAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:22 INFO - Running setup.py (path:/tmp/pip-BkPZxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:22 INFO - Running setup.py (path:/tmp/pip-mgRo4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:22 INFO - Running setup.py (path:/tmp/pip-csG15K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:22 INFO - Running setup.py (path:/tmp/pip-bYRX8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:22 INFO - Running setup.py (path:/tmp/pip-hxw3gQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:22 INFO - Running setup.py (path:/tmp/pip-1y0kk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:22 INFO - Running setup.py (path:/tmp/pip-nIvcmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:23 INFO - Running setup.py (path:/tmp/pip-cPMyz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:23 INFO - Running setup.py (path:/tmp/pip-n4Ak2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:23 INFO - Running setup.py install for manifestparser 09:00:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozcrash 09:00:23 INFO - Running setup.py install for mozdebug 09:00:23 INFO - Running setup.py install for mozdevice 09:00:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozfile 09:00:23 INFO - Running setup.py install for mozhttpd 09:00:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozinfo 09:00:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozInstall 09:00:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozleak 09:00:24 INFO - Running setup.py install for mozlog 09:00:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Running setup.py install for moznetwork 09:00:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Running setup.py install for mozprocess 09:00:24 INFO - Running setup.py install for mozprofile 09:00:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Running setup.py install for mozrunner 09:00:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Running setup.py install for mozscreenshot 09:00:24 INFO - Running setup.py install for moztest 09:00:24 INFO - Running setup.py install for mozversion 09:00:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:24 INFO - Cleaning up... 09:00:24 INFO - Return code: 0 09:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:24 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 0x7f6046ca1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4410>, '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 0x23c5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c57a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', '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 09:00:24 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 09:00:24 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 09:00:24 INFO - Using env: {'DISPLAY': ':0', 09:00:24 INFO - 'HOME': '/home/cltbld', 09:00:24 INFO - 'LANG': 'en_US.UTF-8', 09:00:24 INFO - 'LANGUAGE': 'en_US:en', 09:00:24 INFO - 'LOGNAME': 'cltbld', 09:00:24 INFO - 'MAIL': '/var/mail/cltbld', 09:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:24 INFO - 'MOZ_NO_REMOTE': '1', 09:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:24 INFO - 'NO_EM_RESTART': '1', 09:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:24 INFO - 'PWD': '/builds/slave/test', 09:00:24 INFO - 'SHELL': '/bin/bash', 09:00:24 INFO - 'SHLVL': '1', 09:00:24 INFO - 'TERM': 'linux', 09:00:24 INFO - 'TMOUT': '86400', 09:00:24 INFO - 'USER': 'cltbld', 09:00:24 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', 09:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:24 INFO - '_': '/tools/buildbot/bin/python'} 09:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:25 INFO - Running setup.py (path:/tmp/pip-q2VSl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:25 INFO - Running setup.py (path:/tmp/pip-L5p_mG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:25 INFO - Running setup.py (path:/tmp/pip-1RpmyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:25 INFO - Running setup.py (path:/tmp/pip-d27pgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:25 INFO - Running setup.py (path:/tmp/pip-NBXyJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:25 INFO - Running setup.py (path:/tmp/pip-MHzljU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:25 INFO - Running setup.py (path:/tmp/pip-xQLcB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:25 INFO - Running setup.py (path:/tmp/pip-YP4Ulh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:25 INFO - Running setup.py (path:/tmp/pip-Y8rDJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:25 INFO - Running setup.py (path:/tmp/pip-tZX9SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:25 INFO - Running setup.py (path:/tmp/pip-kf4MJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:25 INFO - Running setup.py (path:/tmp/pip-wnVho3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:26 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)) 09:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:26 INFO - Running setup.py (path:/tmp/pip-R4zEIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:26 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)) 09:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:26 INFO - Running setup.py (path:/tmp/pip-jbYpNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:26 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)) 09:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:26 INFO - Running setup.py (path:/tmp/pip-XNaWdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:26 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)) 09:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:26 INFO - Running setup.py (path:/tmp/pip-srOIrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:26 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)) 09:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:26 INFO - Running setup.py (path:/tmp/pip-w2LtSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:26 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)) 09:00:26 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)) 09:00:26 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)) 09:00:26 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)) 09:00:26 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)) 09:00:26 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)) 09:00:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:28 INFO - Downloading blessings-1.6.tar.gz 09:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:28 INFO - Installing collected packages: blessings 09:00:28 INFO - Running setup.py install for blessings 09:00:28 INFO - Successfully installed blessings 09:00:28 INFO - Cleaning up... 09:00:28 INFO - Return code: 0 09:00:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:28 INFO - Reading from file tmpfile_stdout 09:00:28 INFO - Current package versions: 09:00:28 INFO - argparse == 1.2.1 09:00:28 INFO - blessings == 1.6 09:00:28 INFO - blobuploader == 1.2.4 09:00:28 INFO - docopt == 0.6.1 09:00:28 INFO - manifestparser == 1.1 09:00:28 INFO - mozInstall == 1.12 09:00:28 INFO - mozcrash == 0.17 09:00:28 INFO - mozdebug == 0.1 09:00:28 INFO - mozdevice == 0.48 09:00:28 INFO - mozfile == 1.2 09:00:28 INFO - mozhttpd == 0.7 09:00:28 INFO - mozinfo == 0.9 09:00:28 INFO - mozleak == 0.1 09:00:28 INFO - mozlog == 3.1 09:00:28 INFO - moznetwork == 0.27 09:00:28 INFO - mozprocess == 0.22 09:00:28 INFO - mozprofile == 0.28 09:00:28 INFO - mozrunner == 6.11 09:00:28 INFO - mozscreenshot == 0.1 09:00:28 INFO - mozsystemmonitor == 0.0 09:00:28 INFO - moztest == 0.7 09:00:28 INFO - mozversion == 1.4 09:00:28 INFO - psutil == 3.1.1 09:00:28 INFO - requests == 1.2.3 09:00:28 INFO - wsgiref == 0.1.2 09:00:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:28 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')]} 09:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:28 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')]} 09:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:28 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 0x7f6046ca1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4410>, '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 0x23c5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c57a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', '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 09:00:28 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 09:00:28 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 09:00:28 INFO - Using env: {'DISPLAY': ':0', 09:00:28 INFO - 'HOME': '/home/cltbld', 09:00:28 INFO - 'LANG': 'en_US.UTF-8', 09:00:28 INFO - 'LANGUAGE': 'en_US:en', 09:00:28 INFO - 'LOGNAME': 'cltbld', 09:00:28 INFO - 'MAIL': '/var/mail/cltbld', 09:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:28 INFO - 'MOZ_NO_REMOTE': '1', 09:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:28 INFO - 'NO_EM_RESTART': '1', 09:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:28 INFO - 'PWD': '/builds/slave/test', 09:00:28 INFO - 'SHELL': '/bin/bash', 09:00:28 INFO - 'SHLVL': '1', 09:00:28 INFO - 'TERM': 'linux', 09:00:28 INFO - 'TMOUT': '86400', 09:00:28 INFO - 'USER': 'cltbld', 09:00:28 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', 09:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:28 INFO - '_': '/tools/buildbot/bin/python'} 09:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:28 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)) 09:00:28 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)) 09:00:28 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)) 09:00:28 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)) 09:00:28 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)) 09:00:28 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)) 09:00:28 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)) 09:00:28 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)) 09:00:28 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)) 09:00:28 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)) 09:00:28 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)) 09:00:28 INFO - Cleaning up... 09:00:29 INFO - Return code: 0 09:00:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:29 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 0x7f6046ca1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4410>, '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 0x23c5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c57a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', '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 09:00:29 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 09:00:29 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 09:00:29 INFO - Using env: {'DISPLAY': ':0', 09:00:29 INFO - 'HOME': '/home/cltbld', 09:00:29 INFO - 'LANG': 'en_US.UTF-8', 09:00:29 INFO - 'LANGUAGE': 'en_US:en', 09:00:29 INFO - 'LOGNAME': 'cltbld', 09:00:29 INFO - 'MAIL': '/var/mail/cltbld', 09:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:29 INFO - 'MOZ_NO_REMOTE': '1', 09:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:29 INFO - 'NO_EM_RESTART': '1', 09:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:29 INFO - 'PWD': '/builds/slave/test', 09:00:29 INFO - 'SHELL': '/bin/bash', 09:00:29 INFO - 'SHLVL': '1', 09:00:29 INFO - 'TERM': 'linux', 09:00:29 INFO - 'TMOUT': '86400', 09:00:29 INFO - 'USER': 'cltbld', 09:00:29 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', 09:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:29 INFO - '_': '/tools/buildbot/bin/python'} 09:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:29 INFO - Downloading/unpacking jsonschema 09:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:31 INFO - Downloading/unpacking functools32 (from jsonschema) 09:00:31 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 09:00:31 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 09:00:31 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 09:00:31 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 09:00:31 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 09:00:31 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 09:00:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:31 INFO - warning: no files found matching '*.txt' 09:00:31 INFO - no previously-included directories found matching 'build' 09:00:31 INFO - no previously-included directories found matching 'dist' 09:00:31 INFO - no previously-included directories found matching '.git*' 09:00:31 INFO - Installing collected packages: jsonschema, functools32 09:00:31 INFO - Running setup.py install for functools32 09:00:31 INFO - warning: no files found matching '*.txt' 09:00:31 INFO - no previously-included directories found matching 'build' 09:00:31 INFO - no previously-included directories found matching 'dist' 09:00:31 INFO - no previously-included directories found matching '.git*' 09:00:31 INFO - Successfully installed jsonschema functools32 09:00:31 INFO - Cleaning up... 09:00:31 INFO - Return code: 0 09:00:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:00:31 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')]} 09:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 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')]} 09:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 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 0x7f6046ca1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23355a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4410>, '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 0x23c5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c57a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', '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 09:00:31 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 09:00:31 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 09:00:31 INFO - Using env: {'DISPLAY': ':0', 09:00:31 INFO - 'HOME': '/home/cltbld', 09:00:31 INFO - 'LANG': 'en_US.UTF-8', 09:00:31 INFO - 'LANGUAGE': 'en_US:en', 09:00:31 INFO - 'LOGNAME': 'cltbld', 09:00:31 INFO - 'MAIL': '/var/mail/cltbld', 09:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:31 INFO - 'MOZ_NO_REMOTE': '1', 09:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:31 INFO - 'NO_EM_RESTART': '1', 09:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:31 INFO - 'PWD': '/builds/slave/test', 09:00:31 INFO - 'SHELL': '/bin/bash', 09:00:31 INFO - 'SHLVL': '1', 09:00:31 INFO - 'TERM': 'linux', 09:00:31 INFO - 'TMOUT': '86400', 09:00:31 INFO - 'USER': 'cltbld', 09:00:31 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', 09:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:31 INFO - '_': '/tools/buildbot/bin/python'} 09:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:31 INFO - Downloading/unpacking flake8 09:00:31 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 09:00:31 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 09:00:31 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 09:00:31 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 09:00:31 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 09:00:31 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 09:00:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:00:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:00:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:00:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:34 INFO - no previously-included directories found matching 'docs/_build' 09:00:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:00:34 INFO - Running setup.py install for pep8 09:00:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:34 INFO - no previously-included directories found matching 'docs/_build' 09:00:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:00:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:00:34 INFO - Cleaning up... 09:00:34 INFO - Return code: 0 09:00:34 INFO - Running post-action listener: _resource_record_post_action 09:00:34 INFO - Running post-action listener: _start_resource_monitoring 09:00:34 INFO - Starting resource monitoring. 09:00:34 INFO - ##### 09:00:34 INFO - ##### Running install step. 09:00:34 INFO - ##### 09:00:34 INFO - Running pre-action listener: _resource_record_pre_action 09:00:34 INFO - Running main action method: install 09:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:34 INFO - Reading from file tmpfile_stdout 09:00:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:34 INFO - Reading from file tmpfile_stdout 09:00:34 INFO - Output received: 09:00:34 INFO - Usage: mozinstall [options] installer 09:00:34 INFO - Options: 09:00:34 INFO - -h, --help show this help message and exit 09:00:34 INFO - -d DEST, --destination=DEST 09:00:34 INFO - Directory to install application into. [default: 09:00:34 INFO - "/builds/slave/test"] 09:00:34 INFO - --app=APP Application being installed. [default: firefox] 09:00:34 INFO - mkdir: /builds/slave/test/build/application 09:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:00:50 INFO - Reading from file tmpfile_stdout 09:00:50 INFO - Output received: 09:00:50 INFO - /builds/slave/test/build/application/firefox/firefox 09:00:50 INFO - Running post-action listener: _resource_record_post_action 09:00:50 INFO - ##### 09:00:50 INFO - ##### Running run-tests step. 09:00:50 INFO - ##### 09:00:50 INFO - Running pre-action listener: _resource_record_pre_action 09:00:50 INFO - Running main action method: run_tests 09:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:50 INFO - Python 2.7.3 09:00:50 INFO - Return code: 0 09:00:50 INFO - grabbing minidump binary from tooltool 09:00:50 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')]} 09:00:50 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 0x23c4410>, '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 0x23c5310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c57a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:50 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 09:00:50 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 09:00:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:50 INFO - Return code: 0 09:00:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:00:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:00:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:00:51 INFO - Return code: 0 09:00:51 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/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 09:00:51 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/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 09:00:51 INFO - Using env: {'DISPLAY': ':0', 09:00:51 INFO - 'HOME': '/home/cltbld', 09:00:51 INFO - 'LANG': 'en_US.UTF-8', 09:00:51 INFO - 'LANGUAGE': 'en_US:en', 09:00:51 INFO - 'LOGNAME': 'cltbld', 09:00:51 INFO - 'MAIL': '/var/mail/cltbld', 09:00:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:51 INFO - 'MOZ_NO_REMOTE': '1', 09:00:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:51 INFO - 'NO_EM_RESTART': '1', 09:00:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:51 INFO - 'PWD': '/builds/slave/test', 09:00:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:51 INFO - 'SHELL': '/bin/bash', 09:00:51 INFO - 'SHLVL': '1', 09:00:51 INFO - 'TERM': 'linux', 09:00:51 INFO - 'TMOUT': '86400', 09:00:51 INFO - 'USER': 'cltbld', 09:00:51 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755', 09:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:51 INFO - '_': '/tools/buildbot/bin/python'} 09:00:51 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/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 09:00:51 INFO - mozversion application_buildid: 20160428073712 09:00:51 INFO - mozversion application_changeset: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 09:00:51 INFO - mozversion application_display_name: Nightly 09:00:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:51 INFO - mozversion application_name: Firefox 09:00:51 INFO - mozversion application_remotingname: firefox 09:00:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:00:51 INFO - mozversion application_vendor: Mozilla 09:00:51 INFO - mozversion application_version: 49.0a1 09:00:51 INFO - mozversion platform_buildid: 20160428073712 09:00:51 INFO - mozversion platform_changeset: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 09:00:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:00:51 INFO - mozversion platform_version: 49.0a1 09:00:51 INFO - using testdate: 1461859251 09:00:51 INFO - actual date: 1461859251 09:00:51 INFO - starting webserver on 'localhost:54355' 09:00:51 INFO - SUITE-START | Running 2 tests 09:00:51 INFO - TEST-START | dromaeo_css 09:00:51 INFO - operating with platform_type : linux_ 09:00:51 INFO - Initialising browser for dromaeo_css test... 09:00:52 INFO - TEST-INFO | started process 6614 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYmBPv7/profile http://localhost:54355/getInfo.html) 09:00:56 INFO - PROCESS | 6614 | 09:00:56 INFO - PROCESS | 6614 | (process:6681): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:00:57 INFO - PROCESS | 6614 | __metrics Screen width/height:1600/1200 09:00:57 INFO - PROCESS | 6614 | colorDepth:24 09:00:57 INFO - PROCESS | 6614 | Browser inner width/height: 1024/697 09:00:57 INFO - PROCESS | 6614 | __metrics 09:00:57 ERROR - PROCESS | 6614 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:00:58 INFO - TEST-INFO | 6614: exit 0 09:00:58 INFO - Browser initialized. 09:00:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:00:58 INFO - TEST-INFO | started process 6732 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYmBPv7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:00 INFO - PROCESS | 6732 | 09:01:00 INFO - PROCESS | 6732 | (process:6791): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:01:10 INFO - PROCESS | 6732 | RSS: Main: 166227968 09:01:10 INFO - PROCESS | 6732 | 09:02:00 INFO - PROCESS | 6732 | 1461859320149 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] 09:04:13 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:54355/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54355/tests/dromaeo/cssquery-ext.html) 09:04:14 INFO - PROCESS | 6732 | RSS: Main: 167112704 09:04:14 INFO - PROCESS | 6732 | 09:07:16 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:54355/tests/dromaeo/cssquery-ext.html (next: http://localhost:54355/tests/dromaeo/cssquery-jquery.html) 09:07:17 INFO - PROCESS | 6732 | RSS: Main: 166346752 09:07:17 INFO - PROCESS | 6732 | 09:10:19 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:54355/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54355/tests/dromaeo/cssquery-mootools.html) 09:10:19 INFO - PROCESS | 6732 | RSS: Main: 167989248 09:10:19 INFO - PROCESS | 6732 | 09:13:21 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:54355/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54355/tests/dromaeo/cssquery-prototype.html) 09:13:22 INFO - PROCESS | 6732 | RSS: Main: 165683200 09:13:22 INFO - PROCESS | 6732 | 09:16:25 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:54355/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54355/tests/dromaeo/cssquery-yui.html) 09:16:25 INFO - PROCESS | 6732 | RSS: Main: 166785024 09:16:25 INFO - PROCESS | 6732 | 09:19:28 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:54355/tests/dromaeo/cssquery-yui.html (next: http://localhost:54355/tests/dromaeo/cssquery-dojo.html) 09:19:28 INFO - PROCESS | 6732 | RSS: Main: 168611840 09:19:28 INFO - PROCESS | 6732 | 09:19:28 INFO - PROCESS | 6732 | __start_tp_report 09:19:28 INFO - PROCESS | 6732 | _x_x_mozilla_page_load 09:19:28 INFO - PROCESS | 6732 | _x_x_mozilla_page_load_details 09:19:28 INFO - PROCESS | 6732 | |i|pagename|runs| 09:19:28 INFO - PROCESS | 6732 | |0;dojo.html;2607;2676.330917270682;2679.8258113222637;2696.973030269698;2711.8237314574553;4664.090502352038;4737.644676649248;4739.383880095587;4747.857564273073;4793.233082706767;4671.719696818193;4739.597134243593;4772.284157376395;4781.139394908915;4837.661363704542;4371.060222052258;4430.760660336539;4484.529124441936;4491.483479399862;4522.570355816192;493.9449963171354;4281;4323.437953066097;4350.912981740347;4391.494978077514;4343.630791382736;4405.03089320349;4415.8233670653135;4444.9554354726615;4464.687471876938;4345.500267469219;4373.584509471627;4407.382966384709;4414;4425.491068527129;4317.201317756225;4334.674899382528;4351.73889566627;4364.694471387004;4375.824967001348;4656.976715116402;4662.323963025342;4662.72023678577;4667.13657973276;4693.131770622445;4837.395325584302;4962.478939711351;5066.41736200338;5095.464976177485;5097.439281679012;5124.1801311790305;5142.51146141116;5144.639875208737;5144.6655967362;5198.428172900978;4722.031983443386;4780.95219047812;4784.043191361672;4791.976040119777;4831.637627177975;5120.769565369567;5162.92255616166;5236.345456817898;5250.921236181461;5299.788008479694;5035.874103147451;5054.368203974504;5056.241563765465;5069.1635880079375;5094.057599344133;5088.185890257541;5095.515925987025;5113.207452844815;5113.923291150636;5130.820421285237;4860.562549370503;5048.3184770056305;5060;5120.92318615221;5130.615203859726;4960.727160006162;4989.775460104304;4997.30037794709;5017.67385119966;5029.547340739351;4264.616184543406;4273.786310684453;4277.871663850027;4290.948717564217;4324.610785029299;3649.4525821127045;3664.486971823947;3748.981255093762;3803.2963901677726;3837.194189220295;4278;4332.35014747784;4334.349847522897;4337.175936572041;4370.191514569815;5082.720450375265;5114.411842638145;5116.513931176532;5122.334096567497;5131.56381707559;4390.53899340571;4397.098594788061;4397.1865204937185;4421.889452763707;4447.666425018137;4507.256302710016;4541.250693635513;4589.013362127092;4590.816367345269;4594.931076033862;4667.603253723471;4682.719036857799;4692.7888245029735;4763.071201115749;4818.060478206716;5038.345015148007;5053.823116190915;5054.140796064751;5079.64442489037;5127.66670166438;4772.7136371817805;4780.259059845729;4780.306855506002;4785.856424307276;4829.154897892784;4853.369062022056;5045.293658887832;5053.772580233825;5123.001014802077;5135.92296115566;5076.314697515788;5091.541761241506;5099.413567439792;5108.489151084862;5153.561947234377;4804.135255654016;4937;4945.2582112683385;4951.232558953368;4959.454460009469;4904.779284932115;4916.950830491649;4938.75306234694;4952.900941981212;4978.701277923336;4879.048585525788;4885.877853053702;4899.632527560519;4912.70523768575;4914.950850491449;4853.975730121468;4958.181899986462;5004;5013.423456302572;5056.570191533614;4808.63935204868;4856.1016212000895;4869.342638743866;4897.289892965428;4931.679440836334 09:19:28 INFO - PROCESS | 6732 | |1;ext.html;562.805831987964;595.9880802383952;607.6688204928314;620.9782657606985;625.9217597800275;33668.48491817868;34588.308233835305;34596.82701586495;34726.652733472656;34751.47872781907;37956.24087518255;38569.421458678145;38772;38835.22329553407;38901.610983890154;7633.885491717642;7813.9609301953415;7843.176466471024;7866.724664636738;7868.52788832669;7728.227177282283;7840.4511684182135;7893.210678932118;8011.559364234965;8012.599370031504;7986.121526632066;8039.799005024863;8078;8095.5142691438405;8109.675612975474;7896.131425543185;8013.2387423194705;8017.3586113111105;8020.318272946777;8048.396370272195;7820.687172513092;7885.684572617146;7901.96049019757;7920.445568810185;7946.0464744230485;38563.42154867679;38767;38829.805850970566;39080.21839563221;39252.803735981426;2921.6347956505438;3057.908262752121;3109.5024796032753;3185.4106990206656;3326.334733053399;29251.85374073116;29469.115926522238;29475.115746527637;29921;30045.098647040624;3154.716075553188;3194.7603929705133;3202.96797032029;3225.161458020908;3267.1668724475344;251317;252254.99999999817;253296;253479;254212;53561.46438535565;54443;54740.726296368266;54769;54810.45189548133;51596.22605660918;52662.21006684903;52844;53027;53068.20397694038;13315;13410.463581456632;13510.459581616822;13549.119307244997;13638;10248.333858299187;10254.794904101876;10271.332363396356;10275.280730348804;10280.074793268492;17846.821531784775;18374.63250734978;18534.25862965493;18543.35098271581;18629;6821.426788477839;6984.720611175598;7031.820135757931;7210.60341681202;7290.8177295566975;840.8864803251605;902.4044130873592;942.4533770413144;992.7369247149367;1006.642641862143;27054.1883743488;28468.288292792433;28594.857025715155;28631;29508.114756557337;942.7878727286305;955.6129767444057;979.5738853598707;988.3773222869689;999.1856636841096;14144.22206778637;14197.432102715982;14284.785728214085;14509.782353264709;14773.704525909421;391825;399564;400593;400708;400983;48752.26871596929;49760.25359619609;50036.24945625746;50174;50250;1187.7565099154654;1189.9107566932341;1212.3271584270638;1216.7019080325376;1228.103484456352;26654.20037398881;27218;27526.449471010066;27818.30454238579;27992.440151196453;41270.58729412667;41774.791126044176;41903.1619367617;42079.36880946727;42099;347783;348050;348687;353616;354946;25497.872510637328;26073;26122.216333510027;26177.214683559523;26349.34126646849;42152;42442.78778606211;42562.14875702531;43637.34543981779;43740.56259437365;35707.10732231715;36315.636843631226;36531.26937461289;36898.26203475969;37055.8147209273;36634;36710.63289367072;36888;36940.63059369372;37205;35718;35915.28169436649;36537.26925461529;36542.4518632226;36661.45007824938;30947.381052378376;32541.186470337485;32951.340973179926;32958;33102.668973309956;25571.744282556938;25830.225093247234;26039.73960260373;26137;26311 09:19:28 INFO - PROCESS | 6732 | |2;jquery.html;390.62695126154506;391.2117084075587;393.54545499947534;393.7224256898886;397.70967193948417;12946.676333091678;15853.128077955707;15997;16567.420140295093;16589.917050414762;15820.920895395508;16098.919505402486;16412.83587164128;16832.410865619706;16885.071321077336;5165.9225111623355;5237.926225123841;5277.5514081302945;5364.141737322029;5367.221752845835;5281.023010743005;5288.629795914288;5309.177077552986;5334.813281535147;5339.119045357531;5159.948400516003;5279.656822306538;5285.127953887604;5293.761780719858;5300.893982120355;5399;5403.108487099624;5412;5433.4566543345745;5449.863753406157;5112.667676601046;5475.4524547545325;5491.697956612385;5495.450454954513;5516.282883225155;11854.703632409173;12136.757264854654;12223.877761222451;17345.913270433568;20114.195432182696;1891.9621607567908;1892.3944337811906;1980.5345743750206;1984.6725290327079;2052.989735051315;8527.786805329917;9032.458052516804;9191.080891910822;9280.675176368863;9615.951920240354;1756.7364895265684;1772.97340539892;1810;1812.8459080978132;1880.5298675331167;70962.29037709608;74169;74580.25419745788;75177.24822751757;75369.62315188444;9626.614935402506;10055.497225138713;10201.94899025515;10256.743581410525;10584.947075264574;12379;13134.40895159701;14272;14361.856381436051;14368.928155359365;6853.965730171317;6887.242403335629;6929.203141638675;6936;7045.225025247219;6462.192225971754;6470.676466176672;6604.471642268607;6627.86744265112;6631.933680663228;4177.289860723684;4314.9784251078545;4345.630621397154;4349.434573505424;4409.338599210144;1851.231738828398;1867.1317837205765;1892.233645373626;1895.9620807584047;1907.7519922410274;865.7446053414131;959.9568019439142;964.1419136967963;981.0973904008187;1005.0652892809611;16180.75728864068;16569;16841.494755157364;16866.83133168677;17296.567585810204;888.9422187557917;901.9052999435096;905.922996545301;943.556528431636;960.8750862387844;8905.243054340324;9017.774555636162;9355.90644093564;9422.199113075465;9481.24150067993;326674;333347;335653;393725;394259;75078.62460687662;78594;79733.60133199296;85803;89585.55207223922;986.3736527305213;997.6009596161593;1005.4821766790109;1046.3826342457987;1048.051513380392;15379.692406152039;15381.461648842236;15471.066116735408;15648.4523041693;15848.366065357717;36240.27519449649;36559.90320290396;37101.628983709816;37906.81046594749;38375.23249534938;47974;52620.73689631528;52825.735871320394;53290.73354633202;53295.73352133214;7517.586532740752;7660.961695191489;7705.499142555716;7733.226677332222;7789.961050194939;10534.841977370499;11202.21584489078;11282.661520154408;11307.943460282646;11502.654920352403;9373.343865929319;9485.762855928657;9499.38254013487;9725;9772.071653192936;9430.52847357643;9489.3502183928;10033.44816035125;10093.798124037332;10147.949260253652;8857.11428857109;9470.384425012353;9520;9980.650677226262;10302.22733294991;6999.545029573062;7615.581143036965;7624.618769061413;7657.6554055066545;7783.805404864924;9640.325177237526;9666.613335466502;9940.502974851084;9975.20198384113;10076.143527799926 09:19:28 INFO - PROCESS | 6732 | |3;mootools.html;1479.9704005919878;1496.9027013244142;1646.9258883350249;1778.3153485907922;1831.9863824009506;6974.128233970754;7660.540367577943;7712.614369281528;7998.480098793581;8002.959985200073;7754.883676744839;7769.61151942402;7865.528068315891;7894.644740986655;7907;2213.612617791884;2242.955140897181;2245.640697488394;2247.3932038349635;2252.774722527742;2168.436206586291;2209.7458792238936;2231.297141400462;2235.5417139486367;2238.888055597222;2183.727034120735;2186.409669389264;2207.6467765157577;2214.4574579228033;2248.5840119577847;2171.913123475059;2174.5324755177635;2188.365374041526;2191.4521369657664;2194.3855720398315;2106.8314534837327;2145.9034343454427;2152.0853637204127;2164.5346250556204;2171.565686862618;7686.5772382518935;7687.0391201099865;7721.7683469496005;7790.298873101448;7802.063752349754;4294.7423154610515;4437.5784300491405;4440.555944405566;4444.844430444951;4447.68793206005;2715.7555819976296;2805.6773471050888;2840.133759203442;2864.427114577093;2887.422515496909;821.1911267401605;872.5637181409295;873.8470767615705;875.6672464463463;877.1579283887476;10355.741106472324;10374.740631484197;10389.480525973671;10403.791924161475;10413.947930260452;4046.6964977626794;4137.2552940471005;4231.026863821338;4271.380649805823;4325.113351762881;8873.911260887438;8937.374383793072;8949.970753363315;8960.68637597681;8970.327225458117;4621.560951709582;4639.652026098056;4645.210314246586;4664.463586687507;4676.08816280822;3686.3733165361955;4116.835326586963;4198.3702444633545;4245.257080010986;4277.165952639268;3619.2580520993133;3664.8167591620313;3675.1547144156993;3713.090292878208;3788.3938569828697;1217.0689422591724;1219.7865373559769;1241.9813702794465;1259.030546479206;1260.7289432771997;780.2080887898787;822.761399194239;905.3119629081945;908.4821651658623;913.3287034029983;5069.44236134025;5425.9186112208345;5459.235707000944;5470.5350045245805;5584.274044374287;879.4723166100416;930.7719608695913;934.5280633280286;937.2689302344182;938.7746940734174;3577.6422357764013;3746.0822098586023;3772.86794962175;3815.3323168445413;3898.9805050975133;9102.681406150752;9507.952460237655;9538.856917146248;9567.952160239294;9616;9445.952770236105;9470.289728270269;9481.198572271247;9485.051494850597;9495.905040949501;6931.341522555349;7089.468289878279;7107.609081500464;7237.601931893796;7279.745208917662;5174.223866420067;5177.92233116496;5191.870203244949;5247.606429517878;5267.578593712417;8894.28845692356;8897.688580899896;9083.90916090857;9203.585838637144;9307.488088155216;9135.451872887383;9211.493367864832;9337.953310233677;9517.333786635147;9588.568514417007;5859.707014649165;6377.107204991397;6579.309172536815;6585.835354116185;6695.163104611924;7523.247675232433;7548.773536793686;7561.09266887977;7579.5073320234005;7587.658555364911;6515.609063456208;6732.124823772878;6868.759593414206;6875.140607424072;6954.582725036514;6643.269240383457;6694.464442844658;6788.422984046411;6831.11195544576;6896.27589103137;6069.4840938518955;6273.74905003813;6418.679066046772;6421.068945002855;6435.871282574416;4520.6835521514495;4530.411046563926;4671.462781780003;4679.040796636613;4738.07607516531;4399.1641588098155;4549.545045495424;4560.042391097906;4590.219662657423;4638.512956139692 09:19:28 INFO - PROCESS | 6732 | |4;prototype.html;304.9634043914729;319.065139142313;323.78630104131275;325.0913696219067;325.73289902280135;4850.34520339754;4885.267209918514;4955;5001.274815151801;5017.247412888073;4940.308356830038;4955.578775804061;4958.694207193655;4986.800527978877;4986.927810520738;3944.1914407546456;3969.702272329572;3973.8807835764974;3981.880543583697;4001.9599804001878;3878.720022392618;3916.751691414653;3975.3639417693175;4003.3194356959243;4010.4786377770847;3918.45141680165;3933.3116704576582;3935.4687117239237;3948.1708841143395;3955.09032922428;3931.5871833457504;3950.1309711863487;3950.5654378018394;3952.466417033721;3956.8219430125428;3287.342531493711;3414.23179784549;3422.4502745454547;3507.2985402919517;3529.188286694049;4723.740194289313;4727.66906316558;4737.952620473786;4748.335233067373;4749.786259618326;4398.450193725785;4415.11697660466;4415.315626077963;4419.801108950105;4424.734515929054;4989.952110056893;5148.3821941366905;5232.372115346183;5237.9785941741375;5240.161574148156;3676.5477636333767;3691.9815400923094;3694.501242332278;3704.3147017801525;3723.696706152852;5394.32570928634;5426.778974730654;5432.994895944224;5473.370562385297;5488.067028605146;3791.450239715226;3800.600936901641;3804.923901521954;3811.27585758705;3814.828332725034;3605.4772058051435;3849.9807500962315;3872.862980985411;3882.9223415531533;3888.669463095612;4383.715058521186;4394.274944634163;4422.646188304928;4431.689781715313;4435.756033418128;5188.8962220755375;5190.2214667800135;5233.215017747369;5235.685858848405;5242.97378513105;1341.3293353323338;1373.7801951687882;1410.4640236709984;1412.7457057729707;1413.7101894111681;926.6478738079487;938.5682586010512;941.5998200764648;958.7842735384482;967.6468089147551;4775.498572649891;4788.4253889533475;4801.5438533339275;4806.375171227718;4808.807647694123;5674;5677.574181936371;5688.23208866806;5698.715064246771;5703.230063941315;4668.929966050444;4766.189747742892;4767.4994125617;4779.187538118528;4796.472388037313;3042.680518545521;3056.5720799088144;3057.342671325676;3089.810422987166;3091.922701932425;5338.119210330329;5377.704226267593;5380.78476860929;5381.327334083239;5387.46125387466;5675.290588676416;5818.301803783573;5832.912506312408;5835.241418615553;5850.327212370632;5264.368275806851;5294.914542518774;5300.708461034603;5302;5326.573874090063;5433.809816656398;5603.655122770588;5654.604177707521;5665.631733937281;5687.118496633028;5531.640443371168;5539.086050801573;5551.584345991746;5556.833295001156;5561.221429000024;5293.391260005149;5314.707691076873;5316.760745766527;5335.093034184276;5340.75966581497;3645.1069487975615;3960.346542820402;3974.9403758943063;3984.2230765000545;4008.659263962596;5109.514596113363;5192;5324.402679196303;5359.383670877743;5361.4370491099435;5174.818881339135;5195.19474481456;5236.554892834151;5242.370915490165;5290.841274761763;5178.404483484447;5182.663126896739;5188.533032027136;5204.2974198484235;5231.482869967666;4891.902161956812;5122.359705036871;5125.79496820138;5156.097682905552;5208.322918020633;5218.6868787872845;5245.295279034252;5269.499397557379;5309.761060752198;5324.281222034976;5148.562372198256;5176.896462070662;5194.3766747990485;5205.479452054764;5221.87471897197 09:19:28 INFO - PROCESS | 6732 | |5;yui.html;596.3470000349618;628.6165439082162;665.0157766505569;687.7386593094624;696.4637229333414;11036.503357348918;11325.263857849244;11445.141614378914;11564.306141631509;11832.23090499117;12669;12712.110152289344;12731.172473789196;12760.042996775233;12786.041046921495;632.6235889645653;793.8372633610124;803.0724513187278;804.5132694719698;804.8511025460308;783.5298820707559;800.4356928365488;803.4817542684965;805.5529181304373;807.5195258820992;838.5136620759945;844.2907957315854;845.188618925832;845.7758693946109;847.0174597466939;831.6798032757432;832.9250367466926;834.5493433545909;836.7364280251701;840.4327079221501;612.5099920063931;614.3948211012149;615.062030403638;618.2210414877242;619.0188551146439;11705.063594912386;11706.063514918786;11719.94140029303;11760.64718058451;11780.528778848835;10952.123830093573;11209;11247.212695111346;11247.381394023325;11365.715857103556;8761.868571971425;8906.821863562693;8917.910820891837;9113.635454581809;9141.405808622418;3065.73941214997;3084.98457507711;3111.984440077785;3163.4938409854544;3170.508571171472;41465.378019329735;45715.542844571464;45763;46115.30827037597;46241;9013.053629368822;9071.863922041175;9089.590968406437;9129.269658427309;9180.44917304964;15855.365785368687;15884.76172857408;16012.199390030451;16050.197490125447;16204.432844850373;6274.403931626488;6367;6521.380468855451;6556.573822701509;6717.6977036033495;3402.574678165229;3477.965220347815;3508.5789705235534;3536.717062634983;3564.483149943244;2804.957925631117;2834.588984597222;2852.35821940066;2854.6003559501687;2878.4387044526534;496.4985364781597;508.4915084915085;513.2711549599578;513.9743012849417;524.3210042994332;453.39471805139846;492.3378056513983;496.6374546580944;524.190126254937;573.5297056413784;7055.964720176366;7068.151821781316;7119.71521139159;7126.429885609139;7311.963440182765;492.54439643330494;551.8868631930445;591.7603370634899;611.1077826373455;612.2499937576433;3538.5222994895876;3613.5663720353728;3710.6474884885893;3713.7771733695527;3764.3976963686227;55712.77148913998;57707;58302.125468118014;58427.41572584305;58442;47539;47548;49054;50689;50869;45550;46118.5388146121;46660.06679866451;46971.53028469672;47461.288080678816;7154.284571542804;7178.748743793943;7288.161861385796;7385.03994480714;7408.6666100026705;29727;29823.850880745456;30158.547621785252;30282.54576181315;30541.694583053886;39520.802395987834;40552.59447405606;40706.59293407028;41167.1766564673;41209;9207.631694732403;9234.95382523083;9312.72061838146;9348.53257337144;9424.246060315296;9942.453165075991;10276.897231027893;10355.068043876086;10444.843327349943;10463.58145674195;8890.33322500802;8899.644014239359;9046.592903319235;9364.484953327632;9379.437233765995;9766.870184765303;9839;9936.950315248378;9983.15143212835;10141.138003269805;9082.54587270647;9092.272618190396;9295.25637948976;9352.953235234052;9535.952320238632;7545.811354716176;7746.612669366396;7756.069271687434;8010.078840933367;8021.318187954089;7696.923030769621;7784.688612455665;7845.13703492604;7848.2151784823345;8054.436189466681 09:19:28 INFO - PROCESS | 6732 | __end_tp_report 09:19:28 INFO - PROCESS | 6732 | __start_cc_report 09:19:28 INFO - PROCESS | 6732 | _x_x_mozilla_cycle_collect,370 09:19:28 INFO - PROCESS | 6732 | __end_cc_report 09:19:28 INFO - PROCESS | 6732 | __startTimestamp1461860368392__endTimestamp 09:19:28 INFO - PROCESS | 6732 | 09:19:28 INFO - PROCESS | 6732 | ------- Summary: start ------- 09:19:28 INFO - PROCESS | 6732 | Number of tests: 6 09:19:28 INFO - PROCESS | 6732 | 09:19:28 INFO - PROCESS | 6732 | [#0] dojo.html Cycles:165 Average:4672.22 Median:4818.06 stddev:589.95 (12.2%) stddev-sans-first:569.08 09:19:28 INFO - PROCESS | 6732 | Values: 2607.0 2676.3 2679.8 2697.0 2711.8 4664.1 4737.6 4739.4 4747.9 4793.2 4671.7 4739.6 4772.3 4781.1 4837.7 4371.1 4430.8 4484.5 4491.5 4522.6 493.9 4281.0 4323.4 4350.9 4391.5 4343.6 4405.0 4415.8 4445.0 4464.7 4345.5 4373.6 4407.4 4414.0 4425.5 4317.2 4334.7 4351.7 4364.7 4375.8 4657.0 4662.3 4662.7 4667.1 4693.1 4837.4 4962.5 5066.4 5095.5 5097.4 5124.2 5142.5 5144.6 5144.7 5198.4 4722.0 4781.0 4784.0 4792.0 4831.6 5120.8 5162.9 5236.3 5250.9 5299.8 5035.9 5054.4 5056.2 5069.2 5094.1 5088.2 5095.5 5113.2 5113.9 5130.8 4860.6 5048.3 5060.0 5120.9 5130.6 4960.7 4989.8 4997.3 5017.7 5029.5 4264.6 4273.8 4277.9 4290.9 4324.6 3649.5 3664.5 3749.0 3803.3 3837.2 4278.0 4332.4 4334.3 4337.2 4370.2 5082.7 5114.4 5116.5 5122.3 5131.6 4390.5 4397.1 4397.2 4421.9 4447.7 4507.3 4541.3 4589.0 4590.8 4594.9 4667.6 4682.7 4692.8 4763.1 4818.1 5038.3 5053.8 5054.1 5079.6 5127.7 4772.7 4780.3 4780.3 4785.9 4829.2 4853.4 5045.3 5053.8 5123.0 5135.9 5076.3 5091.5 5099.4 5108.5 5153.6 4804.1 4937.0 4945.3 4951.2 4959.5 4904.8 4917.0 4938.8 4952.9 4978.7 4879.0 4885.9 4899.6 4912.7 4915.0 4854.0 4958.2 5004.0 5013.4 5056.6 4808.6 4856.1 4869.3 4897.3 4931.7 09:19:28 INFO - PROCESS | 6732 | 09:19:28 INFO - PROCESS | 6732 | [#1] ext.html Cycles:180 Average:48579.42 Median:36799.32 stddev:89541.12 (243.3%) stddev-sans-first:89719.73 09:19:28 INFO - PROCESS | 6732 | Values: 562.8 596.0 607.7 621.0 625.9 33668.5 34588.3 34596.8 34726.7 34751.5 37956.2 38569.4 38772.0 38835.2 38901.6 7633.9 7814.0 7843.2 7866.7 7868.5 7728.2 7840.5 7893.2 8011.6 8012.6 7986.1 8039.8 8078.0 8095.5 8109.7 7896.1 8013.2 8017.4 8020.3 8048.4 7820.7 7885.7 7902.0 7920.4 7946.0 38563.4 38767.0 38829.8 39080.2 39252.8 2921.6 3057.9 3109.5 3185.4 3326.3 29251.9 29469.1 29475.1 29921.0 30045.1 3154.7 3194.8 3203.0 3225.2 3267.2 251317.0 252255.0 253296.0 253479.0 254212.0 53561.5 54443.0 54740.7 54769.0 54810.5 51596.2 52662.2 52844.0 53027.0 53068.2 13315.0 13410.5 13510.5 13549.1 13638.0 10248.3 10254.8 10271.3 10275.3 10280.1 17846.8 18374.6 18534.3 18543.4 18629.0 6821.4 6984.7 7031.8 7210.6 7290.8 840.9 902.4 942.5 992.7 1006.6 27054.2 28468.3 28594.9 28631.0 29508.1 942.8 955.6 979.6 988.4 999.2 14144.2 14197.4 14284.8 14509.8 14773.7 391825.0 399564.0 400593.0 400708.0 400983.0 48752.3 49760.3 50036.2 50174.0 50250.0 1187.8 1189.9 1212.3 1216.7 1228.1 26654.2 27218.0 27526.4 27818.3 27992.4 41270.6 41774.8 41903.2 42079.4 42099.0 347783.0 348050.0 348687.0 353616.0 354946.0 25497.9 26073.0 26122.2 26177.2 26349.3 42152.0 42442.8 42562.1 43637.3 43740.6 35707.1 36315.6 36531.3 36898.3 37055.8 36634.0 36710.6 36888.0 36940.6 37205.0 35718.0 35915.3 36537.3 36542.5 36661.5 30947.4 32541.2 32951.3 32958.0 33102.7 25571.7 25830.2 26039.7 26137.0 26311.0 09:19:28 INFO - PROCESS | 6732 | 09:19:28 INFO - PROCESS | 6732 | [#2] jquery.html Cycles:180 Average:23381.85 Median:5280.34 stddev:59657.90 (1129.8%) stddev-sans-first:59800.28 09:19:28 INFO - PROCESS | 6732 | Values: 390.6 391.2 393.5 393.7 397.7 12946.7 15853.1 15997.0 16567.4 16589.9 15820.9 16098.9 16412.8 16832.4 16885.1 5165.9 5237.9 5277.6 5364.1 5367.2 5281.0 5288.6 5309.2 5334.8 5339.1 5159.9 5279.7 5285.1 5293.8 5300.9 5399.0 5403.1 5412.0 5433.5 5449.9 5112.7 5475.5 5491.7 5495.5 5516.3 11854.7 12136.8 12223.9 17345.9 20114.2 1892.0 1892.4 1980.5 1984.7 2053.0 8527.8 9032.5 9191.1 9280.7 9616.0 1756.7 1773.0 1810.0 1812.8 1880.5 70962.3 74169.0 74580.3 75177.2 75369.6 9626.6 10055.5 10201.9 10256.7 10584.9 12379.0 13134.4 14272.0 14361.9 14368.9 6854.0 6887.2 6929.2 6936.0 7045.2 6462.2 6470.7 6604.5 6627.9 6631.9 4177.3 4315.0 4345.6 4349.4 4409.3 1851.2 1867.1 1892.2 1896.0 1907.8 865.7 960.0 964.1 981.1 1005.1 16180.8 16569.0 16841.5 16866.8 17296.6 888.9 901.9 905.9 943.6 960.9 8905.2 9017.8 9355.9 9422.2 9481.2 326674.0 333347.0 335653.0 393725.0 394259.0 75078.6 78594.0 79733.6 85803.0 89585.6 986.4 997.6 1005.5 1046.4 1048.1 15379.7 15381.5 15471.1 15648.5 15848.4 36240.3 36559.9 37101.6 37906.8 38375.2 47974.0 52620.7 52825.7 53290.7 53295.7 7517.6 7661.0 7705.5 7733.2 7790.0 10534.8 11202.2 11282.7 11307.9 11502.7 9373.3 9485.8 9499.4 9725.0 9772.1 9430.5 9489.4 10033.4 10093.8 10147.9 8857.1 9470.4 9520.0 9980.7 10302.2 6999.5 7615.6 7624.6 7657.7 7783.8 9640.3 9666.6 9940.5 9975.2 10076.1 09:19:28 INFO - PROCESS | 6732 | 09:19:28 INFO - PROCESS | 6732 | [#3] mootools.html Cycles:180 Average:5136.56 Median:5346.75 stddev:2844.83 (53.2%) stddev-sans-first:2839.54 09:19:28 INFO - PROCESS | 6732 | Values: 1480.0 1496.9 1646.9 1778.3 1832.0 6974.1 7660.5 7712.6 7998.5 8003.0 7754.9 7769.6 7865.5 7894.6 7907.0 2213.6 2243.0 2245.6 2247.4 2252.8 2168.4 2209.7 2231.3 2235.5 2238.9 2183.7 2186.4 2207.6 2214.5 2248.6 2171.9 2174.5 2188.4 2191.5 2194.4 2106.8 2145.9 2152.1 2164.5 2171.6 7686.6 7687.0 7721.8 7790.3 7802.1 4294.7 4437.6 4440.6 4444.8 4447.7 2715.8 2805.7 2840.1 2864.4 2887.4 821.2 872.6 873.8 875.7 877.2 10355.7 10374.7 10389.5 10403.8 10413.9 4046.7 4137.3 4231.0 4271.4 4325.1 8873.9 8937.4 8950.0 8960.7 8970.3 4621.6 4639.7 4645.2 4664.5 4676.1 3686.4 4116.8 4198.4 4245.3 4277.2 3619.3 3664.8 3675.2 3713.1 3788.4 1217.1 1219.8 1242.0 1259.0 1260.7 780.2 822.8 905.3 908.5 913.3 5069.4 5425.9 5459.2 5470.5 5584.3 879.5 930.8 934.5 937.3 938.8 3577.6 3746.1 3772.9 3815.3 3899.0 9102.7 9508.0 9538.9 9568.0 9616.0 9446.0 9470.3 9481.2 9485.1 9495.9 6931.3 7089.5 7107.6 7237.6 7279.7 5174.2 5177.9 5191.9 5247.6 5267.6 8894.3 8897.7 9083.9 9203.6 9307.5 9135.5 9211.5 9338.0 9517.3 9588.6 5859.7 6377.1 6579.3 6585.8 6695.2 7523.2 7548.8 7561.1 7579.5 7587.7 6515.6 6732.1 6868.8 6875.1 6954.6 6643.3 6694.5 6788.4 6831.1 6896.3 6069.5 6273.7 6418.7 6421.1 6435.9 4520.7 4530.4 4671.5 4679.0 4738.1 4399.2 4549.5 4560.0 4590.2 4638.5 09:19:28 INFO - PROCESS | 6732 | 09:19:28 INFO - PROCESS | 6732 | [#4] prototype.html Cycles:180 Average:4415.69 Median:4957.14 stddev:1283.44 (25.9%) stddev-sans-first:1249.41 09:19:28 INFO - PROCESS | 6732 | Values: 305.0 319.1 323.8 325.1 325.7 4850.3 4885.3 4955.0 5001.3 5017.2 4940.3 4955.6 4958.7 4986.8 4986.9 3944.2 3969.7 3973.9 3981.9 4002.0 3878.7 3916.8 3975.4 4003.3 4010.5 3918.5 3933.3 3935.5 3948.2 3955.1 3931.6 3950.1 3950.6 3952.5 3956.8 3287.3 3414.2 3422.5 3507.3 3529.2 4723.7 4727.7 4738.0 4748.3 4749.8 4398.5 4415.1 4415.3 4419.8 4424.7 4990.0 5148.4 5232.4 5238.0 5240.2 3676.5 3692.0 3694.5 3704.3 3723.7 5394.3 5426.8 5433.0 5473.4 5488.1 3791.5 3800.6 3804.9 3811.3 3814.8 3605.5 3850.0 3872.9 3882.9 3888.7 4383.7 4394.3 4422.6 4431.7 4435.8 5188.9 5190.2 5233.2 5235.7 5243.0 1341.3 1373.8 1410.5 1412.7 1413.7 926.6 938.6 941.6 958.8 967.6 4775.5 4788.4 4801.5 4806.4 4808.8 5674.0 5677.6 5688.2 5698.7 5703.2 4668.9 4766.2 4767.5 4779.2 4796.5 3042.7 3056.6 3057.3 3089.8 3091.9 5338.1 5377.7 5380.8 5381.3 5387.5 5675.3 5818.3 5832.9 5835.2 5850.3 5264.4 5294.9 5300.7 5302.0 5326.6 5433.8 5603.7 5654.6 5665.6 5687.1 5531.6 5539.1 5551.6 5556.8 5561.2 5293.4 5314.7 5316.8 5335.1 5340.8 3645.1 3960.3 3974.9 3984.2 4008.7 5109.5 5192.0 5324.4 5359.4 5361.4 5174.8 5195.2 5236.6 5242.4 5290.8 5178.4 5182.7 5188.5 5204.3 5231.5 4891.9 5122.4 5125.8 5156.1 5208.3 5218.7 5245.3 5269.5 5309.8 5324.3 5148.6 5176.9 5194.4 5205.5 5221.9 09:19:28 INFO - PROCESS | 6732 | 09:19:28 INFO - PROCESS | 6732 | [#5] yui.html Cycles:180 Average:12614.39 Median:58434.71 stddev:15438.27 (26.4%) stddev-sans-first:15455.20 09:19:28 INFO - PROCESS | 6732 | Values: 596.3 628.6 665.0 687.7 696.5 11036.5 11325.3 11445.1 11564.3 11832.2 12669.0 12712.1 12731.2 12760.0 12786.0 632.6 793.8 803.1 804.5 804.9 783.5 800.4 803.5 805.6 807.5 838.5 844.3 845.2 845.8 847.0 831.7 832.9 834.5 836.7 840.4 612.5 614.4 615.1 618.2 619.0 11705.1 11706.1 11719.9 11760.6 11780.5 10952.1 11209.0 11247.2 11247.4 11365.7 8761.9 8906.8 8917.9 9113.6 9141.4 3065.7 3085.0 3112.0 3163.5 3170.5 41465.4 45715.5 45763.0 46115.3 46241.0 9013.1 9071.9 9089.6 9129.3 9180.4 15855.4 15884.8 16012.2 16050.2 16204.4 6274.4 6367.0 6521.4 6556.6 6717.7 3402.6 3478.0 3508.6 3536.7 3564.5 2805.0 2834.6 2852.4 2854.6 2878.4 496.5 508.5 513.3 514.0 524.3 453.4 492.3 496.6 524.2 573.5 7056.0 7068.2 7119.7 7126.4 7312.0 492.5 551.9 591.8 611.1 612.2 3538.5 3613.6 3710.6 3713.8 3764.4 55712.8 57707.0 58302.1 58427.4 58442.0 47539.0 47548.0 49054.0 50689.0 50869.0 45550.0 46118.5 46660.1 46971.5 47461.3 7154.3 7178.7 7288.2 7385.0 7408.7 29727.0 29823.9 30158.5 30282.5 30541.7 39520.8 40552.6 40706.6 41167.2 41209.0 9207.6 9235.0 9312.7 9348.5 9424.2 9942.5 10276.9 10355.1 10444.8 10463.6 8890.3 8899.6 9046.6 9364.5 9379.4 9766.9 9839.0 9937.0 9983.2 10141.1 9082.5 9092.3 9295.3 9353.0 9536.0 7545.8 7746.6 7756.1 8010.1 8021.3 7696.9 7784.7 7845.1 7848.2 8054.4 09:19:28 INFO - PROCESS | 6732 | -------- Summary: end -------- 09:19:28 INFO - PROCESS | 6732 | 09:19:29 INFO - TEST-INFO | 6732: exit 0 09:19:29 INFO - TEST-OK | dromaeo_css | took 1117158ms 09:19:29 INFO - TEST-START | kraken 09:19:29 INFO - operating with platform_type : linux_ 09:19:29 INFO - Initialising browser for kraken test... 09:19:29 INFO - TEST-INFO | started process 6966 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2b8zyh/profile http://localhost:54355/getInfo.html) 09:19:33 INFO - PROCESS | 6966 | 09:19:33 INFO - PROCESS | 6966 | (process:7033): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:19:34 INFO - PROCESS | 6966 | __metrics Screen width/height:1600/1200 09:19:34 INFO - PROCESS | 6966 | colorDepth:24 09:19:34 INFO - PROCESS | 6966 | Browser inner width/height: 1024/697 09:19:34 INFO - PROCESS | 6966 | __metrics 09:19:34 ERROR - PROCESS | 6966 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:19:35 INFO - TEST-INFO | 6966: exit 0 09:19:35 INFO - Browser initialized. 09:19:35 INFO - Running cycle 1/1 for kraken test... 09:19:35 INFO - TEST-INFO | started process 7084 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2b8zyh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:37 INFO - PROCESS | 7084 | 09:19:37 INFO - PROCESS | 7084 | (process:7143): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:19:47 INFO - PROCESS | 7084 | RSS: Main: 166309888 09:19:47 INFO - PROCESS | 7084 | 09:19:50 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54355/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:51 INFO - PROCESS | 7084 | RSS: Main: 169410560 09:19:51 INFO - PROCESS | 7084 | 09:19:54 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54355/tests/kraken/driver.html?testName=audio-dft) 09:19:54 INFO - PROCESS | 7084 | RSS: Main: 173342720 09:19:54 INFO - PROCESS | 7084 | 09:19:57 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54355/tests/kraken/driver.html?testName=audio-fft) 09:19:58 INFO - PROCESS | 7084 | RSS: Main: 164868096 09:19:58 INFO - PROCESS | 7084 | 09:20:00 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54355/tests/kraken/driver.html?testName=audio-oscillator) 09:20:00 INFO - PROCESS | 7084 | RSS: Main: 165408768 09:20:00 INFO - PROCESS | 7084 | 09:20:03 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54355/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:03 INFO - PROCESS | 7084 | RSS: Main: 165908480 09:20:03 INFO - PROCESS | 7084 | 09:20:07 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54355/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:07 INFO - PROCESS | 7084 | RSS: Main: 165871616 09:20:07 INFO - PROCESS | 7084 | 09:20:11 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54355/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:11 INFO - PROCESS | 7084 | RSS: Main: 165937152 09:20:11 INFO - PROCESS | 7084 | 09:20:14 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54355/tests/kraken/driver.html?testName=json-parse-financial) 09:20:15 INFO - PROCESS | 7084 | RSS: Main: 165945344 09:20:15 INFO - PROCESS | 7084 | 09:20:17 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54355/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:17 INFO - PROCESS | 7084 | RSS: Main: 166252544 09:20:17 INFO - PROCESS | 7084 | 09:20:19 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54355/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:20 INFO - PROCESS | 7084 | RSS: Main: 166252544 09:20:20 INFO - PROCESS | 7084 | 09:20:22 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54355/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:23 INFO - PROCESS | 7084 | RSS: Main: 166526976 09:20:23 INFO - PROCESS | 7084 | 09:20:25 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54355/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:26 INFO - PROCESS | 7084 | RSS: Main: 166318080 09:20:26 INFO - PROCESS | 7084 | 09:20:29 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54355/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:30 INFO - PROCESS | 7084 | RSS: Main: 166387712 09:20:30 INFO - PROCESS | 7084 | 09:20:31 INFO - PROCESS | 7084 | Cycle 1(1): loaded http://localhost:54355/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54355/tests/kraken/driver.html?testName=ai-astar) 09:20:32 INFO - PROCESS | 7084 | RSS: Main: 168484864 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | __start_tp_report 09:20:32 INFO - PROCESS | 7084 | _x_x_mozilla_page_load 09:20:32 INFO - PROCESS | 7084 | _x_x_mozilla_page_load_details 09:20:32 INFO - PROCESS | 7084 | |i|pagename|runs| 09:20:32 INFO - PROCESS | 7084 | |0;ai-astar;113;110;115;115;114;127;116;112;113;115 09:20:32 INFO - PROCESS | 7084 | |1;audio-beat-detection;170;133;159;187;136;161;156;134;195;134 09:20:32 INFO - PROCESS | 7084 | |2;audio-dft;163;163;164;177;164;162;162;166;166;164 09:20:32 INFO - PROCESS | 7084 | |3;audio-fft;76;75;80;162;76;83;74;75;159;74 09:20:32 INFO - PROCESS | 7084 | |4;audio-oscillator;96;95;140;94;95;94;142;94;93;144 09:20:32 INFO - PROCESS | 7084 | |5;imaging-gaussian-blur;118;118;118;118;117;118;120;118;119;118 09:20:32 INFO - PROCESS | 7084 | |6;imaging-darkroom;148;148;147;146;147;147;147;147;147;148 09:20:32 INFO - PROCESS | 7084 | |7;imaging-desaturate;95;96;95;95;95;95;95;96;96;96 09:20:32 INFO - PROCESS | 7084 | |8;json-parse-financial;66;67;66;66;65;66;65;82;63;64 09:20:32 INFO - PROCESS | 7084 | |9;json-stringify-tinderbox;65;64;69;64;65;65;65;65;65;65 09:20:32 INFO - PROCESS | 7084 | |10;stanford-crypto-aes;79;84;78;79;83;80;79;80;80;80 09:20:32 INFO - PROCESS | 7084 | |11;stanford-crypto-ccm;140;131;134;132;134;133;129;132;207;137 09:20:32 INFO - PROCESS | 7084 | |12;stanford-crypto-pbkdf2;177;174;176;178;175;179;185;178;181;181 09:20:32 INFO - PROCESS | 7084 | |13;stanford-crypto-sha256-iterative;77;73;74;75;73;74;69;72;75;80 09:20:32 INFO - PROCESS | 7084 | __end_tp_report 09:20:32 INFO - PROCESS | 7084 | __start_cc_report 09:20:32 INFO - PROCESS | 7084 | _x_x_mozilla_cycle_collect,950 09:20:32 INFO - PROCESS | 7084 | __end_cc_report 09:20:32 INFO - PROCESS | 7084 | __startTimestamp1461860432145__endTimestamp 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | ------- Summary: start ------- 09:20:32 INFO - PROCESS | 7084 | Number of tests: 14 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:4.57 (4.0%) stddev-sans-first:4.79 09:20:32 INFO - PROCESS | 7084 | Values: 113.0 110.0 115.0 115.0 114.0 127.0 116.0 112.0 113.0 115.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#1] audio-beat-detection Cycles:10 Average:156.50 Median:160.00 stddev:22.62 (14.1%) stddev-sans-first:23.46 09:20:32 INFO - PROCESS | 7084 | Values: 170.0 133.0 159.0 187.0 136.0 161.0 156.0 134.0 195.0 134.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#2] audio-dft Cycles:10 Average:165.10 Median:164.00 stddev:4.41 (2.7%) stddev-sans-first:4.61 09:20:32 INFO - PROCESS | 7084 | Values: 163.0 163.0 164.0 177.0 164.0 162.0 162.0 166.0 166.0 164.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#3] audio-fft Cycles:10 Average:93.40 Median:75.50 stddev:35.48 (47.0%) stddev-sans-first:37.07 09:20:32 INFO - PROCESS | 7084 | Values: 76.0 75.0 80.0 162.0 76.0 83.0 74.0 75.0 159.0 74.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#4] audio-oscillator Cycles:10 Average:108.70 Median:94.00 stddev:23.01 (24.5%) stddev-sans-first:23.94 09:20:32 INFO - PROCESS | 7084 | Values: 96.0 95.0 140.0 94.0 95.0 94.0 142.0 94.0 93.0 144.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#5] imaging-gaussian-blur Cycles:10 Average:118.20 Median:118.00 stddev:0.79 (0.7%) stddev-sans-first:0.83 09:20:32 INFO - PROCESS | 7084 | Values: 118.0 118.0 118.0 118.0 117.0 118.0 120.0 118.0 119.0 118.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 09:20:32 INFO - PROCESS | 7084 | Values: 148.0 148.0 147.0 146.0 147.0 147.0 147.0 147.0 147.0 148.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:20:32 INFO - PROCESS | 7084 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#8] json-parse-financial Cycles:10 Average:67.00 Median:66.00 stddev:5.40 (8.2%) stddev-sans-first:5.71 09:20:32 INFO - PROCESS | 7084 | Values: 66.0 67.0 66.0 66.0 65.0 66.0 65.0 82.0 63.0 64.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:1.40 (2.2%) stddev-sans-first:1.48 09:20:32 INFO - PROCESS | 7084 | Values: 65.0 64.0 69.0 64.0 65.0 65.0 65.0 65.0 65.0 65.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.00 stddev:1.87 (2.3%) stddev-sans-first:1.94 09:20:32 INFO - PROCESS | 7084 | Values: 79.0 84.0 78.0 79.0 83.0 80.0 79.0 80.0 80.0 80.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#11] stanford-crypto-ccm Cycles:10 Average:140.90 Median:134.00 stddev:23.43 (17.5%) stddev-sans-first:24.85 09:20:32 INFO - PROCESS | 7084 | Values: 140.0 131.0 134.0 132.0 134.0 133.0 129.0 132.0 207.0 137.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:178.50 stddev:3.27 (1.8%) stddev-sans-first:3.43 09:20:32 INFO - PROCESS | 7084 | Values: 177.0 174.0 176.0 178.0 175.0 179.0 185.0 178.0 181.0 181.0 09:20:32 INFO - PROCESS | 7084 | 09:20:32 INFO - PROCESS | 7084 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:74.50 stddev:2.94 (3.9%) stddev-sans-first:2.93 09:20:32 INFO - PROCESS | 7084 | Values: 77.0 73.0 74.0 75.0 73.0 74.0 69.0 72.0 75.0 80.0 09:20:32 INFO - PROCESS | 7084 | -------- Summary: end -------- 09:20:32 INFO - PROCESS | 7084 | 09:20:33 INFO - TEST-INFO | 7084: exit 0 09:20:33 INFO - TEST-OK | kraken | took 63972ms 09:20:33 INFO - SUITE-END | took 1181s 09:20:33 INFO - Completed test suite (00:19:41) 09:20:33 INFO - javascript benchmark 09:20:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2607.0, 2676.330917270682, 2679.8258113222637, 2696.973030269698, 2711.8237314574553, 4664.090502352038, 4737.644676649248, 4739.383880095587, 4747.857564273073, 4793.233082706767, 4671.719696818193, 4739.597134243593, 4772.284157376395, 4781.139394908915, 4837.661363704542, 4371.060222052258, 4430.760660336539, 4484.529124441936, 4491.483479399862, 4522.570355816192, 493.9449963171354, 4281.0, 4323.437953066097, 4350.912981740347, 4391.494978077514, 4343.630791382736, 4405.03089320349, 4415.8233670653135, 4444.9554354726615, 4464.687471876938, 4345.500267469219, 4373.584509471627, 4407.382966384709, 4414.0, 4425.491068527129, 4317.201317756225, 4334.674899382528, 4351.73889566627, 4364.694471387004, 4375.824967001348, 4656.976715116402, 4662.323963025342, 4662.72023678577, 4667.13657973276, 4693.131770622445, 4837.395325584302, 4962.478939711351, 5066.41736200338, 5095.464976177485, 5097.439281679012, 5124.1801311790305, 5142.51146141116, 5144.639875208737, 5144.6655967362, 5198.428172900978, 4722.031983443386, 4780.95219047812, 4784.043191361672, 4791.976040119777, 4831.637627177975, 5120.769565369567, 5162.92255616166, 5236.345456817898, 5250.921236181461, 5299.788008479694, 5035.874103147451, 5054.368203974504, 5056.241563765465, 5069.1635880079375, 5094.057599344133, 5088.185890257541, 5095.515925987025, 5113.207452844815, 5113.923291150636, 5130.820421285237, 4860.562549370503, 5048.3184770056305, 5060.0, 5120.92318615221, 5130.615203859726, 4960.727160006162, 4989.775460104304, 4997.30037794709, 5017.67385119966, 5029.547340739351, 4264.616184543406, 4273.786310684453, 4277.871663850027, 4290.948717564217, 4324.610785029299, 3649.4525821127045, 3664.486971823947, 3748.981255093762, 3803.2963901677726, 3837.194189220295, 4278.0, 4332.35014747784, 4334.349847522897, 4337.175936572041, 4370.191514569815, 5082.720450375265, 5114.411842638145, 5116.513931176532, 5122.334096567497, 5131.56381707559, 4390.53899340571, 4397.098594788061, 4397.1865204937185, 4421.889452763707, 4447.666425018137, 4507.256302710016, 4541.250693635513, 4589.013362127092, 4590.816367345269, 4594.931076033862, 4667.603253723471, 4682.719036857799, 4692.7888245029735, 4763.071201115749, 4818.060478206716, 5038.345015148007, 5053.823116190915, 5054.140796064751, 5079.64442489037, 5127.66670166438, 4772.7136371817805, 4780.259059845729, 4780.306855506002, 4785.856424307276, 4829.154897892784, 4853.369062022056, 5045.293658887832, 5053.772580233825, 5123.001014802077, 5135.92296115566, 5076.314697515788, 5091.541761241506, 5099.413567439792, 5108.489151084862, 5153.561947234377, 4804.135255654016, 4937.0, 4945.2582112683385, 4951.232558953368, 4959.454460009469, 4904.779284932115, 4916.950830491649, 4938.75306234694, 4952.900941981212, 4978.701277923336, 4879.048585525788, 4885.877853053702, 4899.632527560519, 4912.70523768575, 4914.950850491449, 4853.975730121468, 4958.181899986462, 5004.0, 5013.423456302572, 5056.570191533614, 4808.63935204868, 4856.1016212000895, 4869.342638743866, 4897.289892965428, 4931.679440836334], "value": 4636.83589068174, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.805831987964, 595.9880802383952, 607.6688204928314, 620.9782657606985, 625.9217597800275, 33668.48491817868, 34588.308233835305, 34596.82701586495, 34726.652733472656, 34751.47872781907, 37956.24087518255, 38569.421458678145, 38772.0, 38835.22329553407, 38901.610983890154, 7633.885491717642, 7813.9609301953415, 7843.176466471024, 7866.724664636738, 7868.52788832669, 7728.227177282283, 7840.4511684182135, 7893.210678932118, 8011.559364234965, 8012.599370031504, 7986.121526632066, 8039.799005024863, 8078.0, 8095.5142691438405, 8109.675612975474, 7896.131425543185, 8013.2387423194705, 8017.3586113111105, 8020.318272946777, 8048.396370272195, 7820.687172513092, 7885.684572617146, 7901.96049019757, 7920.445568810185, 7946.0464744230485, 38563.42154867679, 38767.0, 38829.805850970566, 39080.21839563221, 39252.803735981426, 2921.6347956505438, 3057.908262752121, 3109.5024796032753, 3185.4106990206656, 3326.334733053399, 29251.85374073116, 29469.115926522238, 29475.115746527637, 29921.0, 30045.098647040624, 3154.716075553188, 3194.7603929705133, 3202.96797032029, 3225.161458020908, 3267.1668724475344, 251317.0, 252254.99999999817, 253296.0, 253479.0, 254212.0, 53561.46438535565, 54443.0, 54740.726296368266, 54769.0, 54810.45189548133, 51596.22605660918, 52662.21006684903, 52844.0, 53027.0, 53068.20397694038, 13315.0, 13410.463581456632, 13510.459581616822, 13549.119307244997, 13638.0, 10248.333858299187, 10254.794904101876, 10271.332363396356, 10275.280730348804, 10280.074793268492, 17846.821531784775, 18374.63250734978, 18534.25862965493, 18543.35098271581, 18629.0, 6821.426788477839, 6984.720611175598, 7031.820135757931, 7210.60341681202, 7290.8177295566975, 840.8864803251605, 902.4044130873592, 942.4533770413144, 992.7369247149367, 1006.642641862143, 27054.1883743488, 28468.288292792433, 28594.857025715155, 28631.0, 29508.114756557337, 942.7878727286305, 955.6129767444057, 979.5738853598707, 988.3773222869689, 999.1856636841096, 14144.22206778637, 14197.432102715982, 14284.785728214085, 14509.782353264709, 14773.704525909421, 391825.0, 399564.0, 400593.0, 400708.0, 400983.0, 48752.26871596929, 49760.25359619609, 50036.24945625746, 50174.0, 50250.0, 1187.7565099154654, 1189.9107566932341, 1212.3271584270638, 1216.7019080325376, 1228.103484456352, 26654.20037398881, 27218.0, 27526.449471010066, 27818.30454238579, 27992.440151196453, 41270.58729412667, 41774.791126044176, 41903.1619367617, 42079.36880946727, 42099.0, 347783.0, 348050.0, 348687.0, 353616.0, 354946.0, 25497.872510637328, 26073.0, 26122.216333510027, 26177.214683559523, 26349.34126646849, 42152.0, 42442.78778606211, 42562.14875702531, 43637.34543981779, 43740.56259437365, 35707.10732231715, 36315.636843631226, 36531.26937461289, 36898.26203475969, 37055.8147209273, 36634.0, 36710.63289367072, 36888.0, 36940.63059369372, 37205.0, 35718.0, 35915.28169436649, 36537.26925461529, 36542.4518632226, 36661.45007824938, 30947.381052378376, 32541.186470337485, 32951.340973179926, 32958.0, 33102.668973309956, 25571.744282556938, 25830.225093247234, 26039.73960260373, 26137.0, 26311.0], "value": 17556.74051648396, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.62695126154506, 391.2117084075587, 393.54545499947534, 393.7224256898886, 397.70967193948417, 12946.676333091678, 15853.128077955707, 15997.0, 16567.420140295093, 16589.917050414762, 15820.920895395508, 16098.919505402486, 16412.83587164128, 16832.410865619706, 16885.071321077336, 5165.9225111623355, 5237.926225123841, 5277.5514081302945, 5364.141737322029, 5367.221752845835, 5281.023010743005, 5288.629795914288, 5309.177077552986, 5334.813281535147, 5339.119045357531, 5159.948400516003, 5279.656822306538, 5285.127953887604, 5293.761780719858, 5300.893982120355, 5399.0, 5403.108487099624, 5412.0, 5433.4566543345745, 5449.863753406157, 5112.667676601046, 5475.4524547545325, 5491.697956612385, 5495.450454954513, 5516.282883225155, 11854.703632409173, 12136.757264854654, 12223.877761222451, 17345.913270433568, 20114.195432182696, 1891.9621607567908, 1892.3944337811906, 1980.5345743750206, 1984.6725290327079, 2052.989735051315, 8527.786805329917, 9032.458052516804, 9191.080891910822, 9280.675176368863, 9615.951920240354, 1756.7364895265684, 1772.97340539892, 1810.0, 1812.8459080978132, 1880.5298675331167, 70962.29037709608, 74169.0, 74580.25419745788, 75177.24822751757, 75369.62315188444, 9626.614935402506, 10055.497225138713, 10201.94899025515, 10256.743581410525, 10584.947075264574, 12379.0, 13134.40895159701, 14272.0, 14361.856381436051, 14368.928155359365, 6853.965730171317, 6887.242403335629, 6929.203141638675, 6936.0, 7045.225025247219, 6462.192225971754, 6470.676466176672, 6604.471642268607, 6627.86744265112, 6631.933680663228, 4177.289860723684, 4314.9784251078545, 4345.630621397154, 4349.434573505424, 4409.338599210144, 1851.231738828398, 1867.1317837205765, 1892.233645373626, 1895.9620807584047, 1907.7519922410274, 865.7446053414131, 959.9568019439142, 964.1419136967963, 981.0973904008187, 1005.0652892809611, 16180.75728864068, 16569.0, 16841.494755157364, 16866.83133168677, 17296.567585810204, 888.9422187557917, 901.9052999435096, 905.922996545301, 943.556528431636, 960.8750862387844, 8905.243054340324, 9017.774555636162, 9355.90644093564, 9422.199113075465, 9481.24150067993, 326674.0, 333347.0, 335653.0, 393725.0, 394259.0, 75078.62460687662, 78594.0, 79733.60133199296, 85803.0, 89585.55207223922, 986.3736527305213, 997.6009596161593, 1005.4821766790109, 1046.3826342457987, 1048.051513380392, 15379.692406152039, 15381.461648842236, 15471.066116735408, 15648.4523041693, 15848.366065357717, 36240.27519449649, 36559.90320290396, 37101.628983709816, 37906.81046594749, 38375.23249534938, 47974.0, 52620.73689631528, 52825.735871320394, 53290.73354633202, 53295.73352133214, 7517.586532740752, 7660.961695191489, 7705.499142555716, 7733.226677332222, 7789.961050194939, 10534.841977370499, 11202.21584489078, 11282.661520154408, 11307.943460282646, 11502.654920352403, 9373.343865929319, 9485.762855928657, 9499.38254013487, 9725.0, 9772.071653192936, 9430.52847357643, 9489.3502183928, 10033.44816035125, 10093.798124037332, 10147.949260253652, 8857.11428857109, 9470.384425012353, 9520.0, 9980.650677226262, 10302.22733294991, 6999.545029573062, 7615.581143036965, 7624.618769061413, 7657.6554055066545, 7783.805404864924, 9640.325177237526, 9666.613335466502, 9940.502974851084, 9975.20198384113, 10076.143527799926], "value": 8121.495873558445, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1479.9704005919878, 1496.9027013244142, 1646.9258883350249, 1778.3153485907922, 1831.9863824009506, 6974.128233970754, 7660.540367577943, 7712.614369281528, 7998.480098793581, 8002.959985200073, 7754.883676744839, 7769.61151942402, 7865.528068315891, 7894.644740986655, 7907.0, 2213.612617791884, 2242.955140897181, 2245.640697488394, 2247.3932038349635, 2252.774722527742, 2168.436206586291, 2209.7458792238936, 2231.297141400462, 2235.5417139486367, 2238.888055597222, 2183.727034120735, 2186.409669389264, 2207.6467765157577, 2214.4574579228033, 2248.5840119577847, 2171.913123475059, 2174.5324755177635, 2188.365374041526, 2191.4521369657664, 2194.3855720398315, 2106.8314534837327, 2145.9034343454427, 2152.0853637204127, 2164.5346250556204, 2171.565686862618, 7686.5772382518935, 7687.0391201099865, 7721.7683469496005, 7790.298873101448, 7802.063752349754, 4294.7423154610515, 4437.5784300491405, 4440.555944405566, 4444.844430444951, 4447.68793206005, 2715.7555819976296, 2805.6773471050888, 2840.133759203442, 2864.427114577093, 2887.422515496909, 821.1911267401605, 872.5637181409295, 873.8470767615705, 875.6672464463463, 877.1579283887476, 10355.741106472324, 10374.740631484197, 10389.480525973671, 10403.791924161475, 10413.947930260452, 4046.6964977626794, 4137.2552940471005, 4231.026863821338, 4271.380649805823, 4325.113351762881, 8873.911260887438, 8937.374383793072, 8949.970753363315, 8960.68637597681, 8970.327225458117, 4621.560951709582, 4639.652026098056, 4645.210314246586, 4664.463586687507, 4676.08816280822, 3686.3733165361955, 4116.835326586963, 4198.3702444633545, 4245.257080010986, 4277.165952639268, 3619.2580520993133, 3664.8167591620313, 3675.1547144156993, 3713.090292878208, 3788.3938569828697, 1217.0689422591724, 1219.7865373559769, 1241.9813702794465, 1259.030546479206, 1260.7289432771997, 780.2080887898787, 822.761399194239, 905.3119629081945, 908.4821651658623, 913.3287034029983, 5069.44236134025, 5425.9186112208345, 5459.235707000944, 5470.5350045245805, 5584.274044374287, 879.4723166100416, 930.7719608695913, 934.5280633280286, 937.2689302344182, 938.7746940734174, 3577.6422357764013, 3746.0822098586023, 3772.86794962175, 3815.3323168445413, 3898.9805050975133, 9102.681406150752, 9507.952460237655, 9538.856917146248, 9567.952160239294, 9616.0, 9445.952770236105, 9470.289728270269, 9481.198572271247, 9485.051494850597, 9495.905040949501, 6931.341522555349, 7089.468289878279, 7107.609081500464, 7237.601931893796, 7279.745208917662, 5174.223866420067, 5177.92233116496, 5191.870203244949, 5247.606429517878, 5267.578593712417, 8894.28845692356, 8897.688580899896, 9083.90916090857, 9203.585838637144, 9307.488088155216, 9135.451872887383, 9211.493367864832, 9337.953310233677, 9517.333786635147, 9588.568514417007, 5859.707014649165, 6377.107204991397, 6579.309172536815, 6585.835354116185, 6695.163104611924, 7523.247675232433, 7548.773536793686, 7561.09266887977, 7579.5073320234005, 7587.658555364911, 6515.609063456208, 6732.124823772878, 6868.759593414206, 6875.140607424072, 6954.582725036514, 6643.269240383457, 6694.464442844658, 6788.422984046411, 6831.11195544576, 6896.27589103137, 6069.4840938518955, 6273.74905003813, 6418.679066046772, 6421.068945002855, 6435.871282574416, 4520.6835521514495, 4530.411046563926, 4671.462781780003, 4679.040796636613, 4738.07607516531, 4399.1641588098155, 4549.545045495424, 4560.042391097906, 4590.219662657423, 4638.512956139692], "value": 4162.661736549665, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.9634043914729, 319.065139142313, 323.78630104131275, 325.0913696219067, 325.73289902280135, 4850.34520339754, 4885.267209918514, 4955.0, 5001.274815151801, 5017.247412888073, 4940.308356830038, 4955.578775804061, 4958.694207193655, 4986.800527978877, 4986.927810520738, 3944.1914407546456, 3969.702272329572, 3973.8807835764974, 3981.880543583697, 4001.9599804001878, 3878.720022392618, 3916.751691414653, 3975.3639417693175, 4003.3194356959243, 4010.4786377770847, 3918.45141680165, 3933.3116704576582, 3935.4687117239237, 3948.1708841143395, 3955.09032922428, 3931.5871833457504, 3950.1309711863487, 3950.5654378018394, 3952.466417033721, 3956.8219430125428, 3287.342531493711, 3414.23179784549, 3422.4502745454547, 3507.2985402919517, 3529.188286694049, 4723.740194289313, 4727.66906316558, 4737.952620473786, 4748.335233067373, 4749.786259618326, 4398.450193725785, 4415.11697660466, 4415.315626077963, 4419.801108950105, 4424.734515929054, 4989.952110056893, 5148.3821941366905, 5232.372115346183, 5237.9785941741375, 5240.161574148156, 3676.5477636333767, 3691.9815400923094, 3694.501242332278, 3704.3147017801525, 3723.696706152852, 5394.32570928634, 5426.778974730654, 5432.994895944224, 5473.370562385297, 5488.067028605146, 3791.450239715226, 3800.600936901641, 3804.923901521954, 3811.27585758705, 3814.828332725034, 3605.4772058051435, 3849.9807500962315, 3872.862980985411, 3882.9223415531533, 3888.669463095612, 4383.715058521186, 4394.274944634163, 4422.646188304928, 4431.689781715313, 4435.756033418128, 5188.8962220755375, 5190.2214667800135, 5233.215017747369, 5235.685858848405, 5242.97378513105, 1341.3293353323338, 1373.7801951687882, 1410.4640236709984, 1412.7457057729707, 1413.7101894111681, 926.6478738079487, 938.5682586010512, 941.5998200764648, 958.7842735384482, 967.6468089147551, 4775.498572649891, 4788.4253889533475, 4801.5438533339275, 4806.375171227718, 4808.807647694123, 5674.0, 5677.574181936371, 5688.23208866806, 5698.715064246771, 5703.230063941315, 4668.929966050444, 4766.189747742892, 4767.4994125617, 4779.187538118528, 4796.472388037313, 3042.680518545521, 3056.5720799088144, 3057.342671325676, 3089.810422987166, 3091.922701932425, 5338.119210330329, 5377.704226267593, 5380.78476860929, 5381.327334083239, 5387.46125387466, 5675.290588676416, 5818.301803783573, 5832.912506312408, 5835.241418615553, 5850.327212370632, 5264.368275806851, 5294.914542518774, 5300.708461034603, 5302.0, 5326.573874090063, 5433.809816656398, 5603.655122770588, 5654.604177707521, 5665.631733937281, 5687.118496633028, 5531.640443371168, 5539.086050801573, 5551.584345991746, 5556.833295001156, 5561.221429000024, 5293.391260005149, 5314.707691076873, 5316.760745766527, 5335.093034184276, 5340.75966581497, 3645.1069487975615, 3960.346542820402, 3974.9403758943063, 3984.2230765000545, 4008.659263962596, 5109.514596113363, 5192.0, 5324.402679196303, 5359.383670877743, 5361.4370491099435, 5174.818881339135, 5195.19474481456, 5236.554892834151, 5242.370915490165, 5290.841274761763, 5178.404483484447, 5182.663126896739, 5188.533032027136, 5204.2974198484235, 5231.482869967666, 4891.902161956812, 5122.359705036871, 5125.79496820138, 5156.097682905552, 5208.322918020633, 5218.6868787872845, 5245.295279034252, 5269.499397557379, 5309.761060752198, 5324.281222034976, 5148.562372198256, 5176.896462070662, 5194.3766747990485, 5205.479452054764, 5221.87471897197], "value": 4014.7775139296737, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.3470000349618, 628.6165439082162, 665.0157766505569, 687.7386593094624, 696.4637229333414, 11036.503357348918, 11325.263857849244, 11445.141614378914, 11564.306141631509, 11832.23090499117, 12669.0, 12712.110152289344, 12731.172473789196, 12760.042996775233, 12786.041046921495, 632.6235889645653, 793.8372633610124, 803.0724513187278, 804.5132694719698, 804.8511025460308, 783.5298820707559, 800.4356928365488, 803.4817542684965, 805.5529181304373, 807.5195258820992, 838.5136620759945, 844.2907957315854, 845.188618925832, 845.7758693946109, 847.0174597466939, 831.6798032757432, 832.9250367466926, 834.5493433545909, 836.7364280251701, 840.4327079221501, 612.5099920063931, 614.3948211012149, 615.062030403638, 618.2210414877242, 619.0188551146439, 11705.063594912386, 11706.063514918786, 11719.94140029303, 11760.64718058451, 11780.528778848835, 10952.123830093573, 11209.0, 11247.212695111346, 11247.381394023325, 11365.715857103556, 8761.868571971425, 8906.821863562693, 8917.910820891837, 9113.635454581809, 9141.405808622418, 3065.73941214997, 3084.98457507711, 3111.984440077785, 3163.4938409854544, 3170.508571171472, 41465.378019329735, 45715.542844571464, 45763.0, 46115.30827037597, 46241.0, 9013.053629368822, 9071.863922041175, 9089.590968406437, 9129.269658427309, 9180.44917304964, 15855.365785368687, 15884.76172857408, 16012.199390030451, 16050.197490125447, 16204.432844850373, 6274.403931626488, 6367.0, 6521.380468855451, 6556.573822701509, 6717.6977036033495, 3402.574678165229, 3477.965220347815, 3508.5789705235534, 3536.717062634983, 3564.483149943244, 2804.957925631117, 2834.588984597222, 2852.35821940066, 2854.6003559501687, 2878.4387044526534, 496.4985364781597, 508.4915084915085, 513.2711549599578, 513.9743012849417, 524.3210042994332, 453.39471805139846, 492.3378056513983, 496.6374546580944, 524.190126254937, 573.5297056413784, 7055.964720176366, 7068.151821781316, 7119.71521139159, 7126.429885609139, 7311.963440182765, 492.54439643330494, 551.8868631930445, 591.7603370634899, 611.1077826373455, 612.2499937576433, 3538.5222994895876, 3613.5663720353728, 3710.6474884885893, 3713.7771733695527, 3764.3976963686227, 55712.77148913998, 57707.0, 58302.125468118014, 58427.41572584305, 58442.0, 47539.0, 47548.0, 49054.0, 50689.0, 50869.0, 45550.0, 46118.5388146121, 46660.06679866451, 46971.53028469672, 47461.288080678816, 7154.284571542804, 7178.748743793943, 7288.161861385796, 7385.03994480714, 7408.6666100026705, 29727.0, 29823.850880745456, 30158.547621785252, 30282.54576181315, 30541.694583053886, 39520.802395987834, 40552.59447405606, 40706.59293407028, 41167.1766564673, 41209.0, 9207.631694732403, 9234.95382523083, 9312.72061838146, 9348.53257337144, 9424.246060315296, 9942.453165075991, 10276.897231027893, 10355.068043876086, 10444.843327349943, 10463.58145674195, 8890.33322500802, 8899.644014239359, 9046.592903319235, 9364.484953327632, 9379.437233765995, 9766.870184765303, 9839.0, 9936.950315248378, 9983.15143212835, 10141.138003269805, 9082.54587270647, 9092.272618190396, 9295.25637948976, 9352.953235234052, 9535.952320238632, 7545.811354716176, 7746.612669366396, 7756.069271687434, 8010.078840933367, 8021.318187954089, 7696.923030769621, 7784.688612455665, 7845.13703492604, 7848.2151784823345, 8054.436189466681], "value": 5607.6327774851925, "unit": "score"}], "name": "dromaeo_css", "value": 6290.621655586186, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 110.0, 115.0, 115.0, 114.0, 127.0, 116.0, 112.0, 113.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 133.0, 159.0, 187.0, 136.0, 161.0, 156.0, 134.0, 195.0, 134.0], "value": 156.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 177.0, 164.0, 162.0, 162.0, 166.0, 166.0, 164.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 80.0, 162.0, 76.0, 83.0, 74.0, 75.0, 159.0, 74.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 140.0, 94.0, 95.0, 94.0, 142.0, 94.0, 93.0, 144.0], "value": 108.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 118.0, 117.0, 118.0, 120.0, 118.0, 119.0, 118.0], "value": 118.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 148.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 65.0, 66.0, 65.0, 82.0, 63.0, 64.0], "value": 67.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 69.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 78.0, 79.0, 83.0, 80.0, 79.0, 80.0, 80.0, 80.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 131.0, 134.0, 132.0, 134.0, 133.0, 129.0, 132.0, 207.0, 137.0], "value": 140.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 174.0, 176.0, 178.0, 175.0, 179.0, 185.0, 178.0, 181.0, 181.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 74.0, 75.0, 73.0, 74.0, 69.0, 72.0, 75.0, 80.0], "value": 74.2, "unit": "score"}], "name": "kraken", "value": 1605.4000000000003, "alertThreshold": 2.0}]} 09:20:33 INFO - Return code: 0 09:20:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:20:33 ERROR - # TBPL SUCCESS # 09:20:33 INFO - Running post-action listener: _resource_record_post_action 09:20:33 INFO - Running post-run listener: _resource_record_post_run 09:20:34 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 0; Write bytes: 518995968; Read time: 0; Write time: 579812 09:20:34 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234872832; Read time: 0; Write time: 293908 09:20:34 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 284123136; Read time: 0; Write time: 285904 09:20:34 INFO - Running post-run listener: _upload_blobber_files 09:20:34 INFO - Blob upload gear active. 09:20:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:34 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 09:20:34 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'] 09:20:34 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 09:20:34 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:20:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:20:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:35 INFO - (blobuploader) - INFO - Done attempting. 09:20:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:20:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:20:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:36 INFO - (blobuploader) - INFO - Done attempting. 09:20:36 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:36 INFO - Return code: 0 09:20:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3397ab788e3be2c2b1d005dff7466f2af911a419e7fb4df65e5ec900028380369ea39faa469a08bbfcf21f1e2972c6f320e66a4e6927539ee32d5440769dadc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff569ed2eeea5a53dfe147238433de2b109560578d2faa57b9a4607f25491c544f1cb242c52d06139eff4693fd47c08d25f5b05326e2f3b57b37f505fe2675c5"} 09:20:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:36 INFO - Contents: 09:20:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3397ab788e3be2c2b1d005dff7466f2af911a419e7fb4df65e5ec900028380369ea39faa469a08bbfcf21f1e2972c6f320e66a4e6927539ee32d5440769dadc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff569ed2eeea5a53dfe147238433de2b109560578d2faa57b9a4607f25491c544f1cb242c52d06139eff4693fd47c08d25f5b05326e2f3b57b37f505fe2675c5"} 09:20:36 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:36 INFO - Copying logs to upload dir... 09:20:36 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.253609 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-04-28 09:20:36.065775) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:20:36.069453) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755 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/3397ab788e3be2c2b1d005dff7466f2af911a419e7fb4df65e5ec900028380369ea39faa469a08bbfcf21f1e2972c6f320e66a4e6927539ee32d5440769dadc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff569ed2eeea5a53dfe147238433de2b109560578d2faa57b9a4607f25491c544f1cb242c52d06139eff4693fd47c08d25f5b05326e2f3b57b37f505fe2675c5"} build_url:https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011318 build_url: 'https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.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/3397ab788e3be2c2b1d005dff7466f2af911a419e7fb4df65e5ec900028380369ea39faa469a08bbfcf21f1e2972c6f320e66a4e6927539ee32d5440769dadc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff569ed2eeea5a53dfe147238433de2b109560578d2faa57b9a4607f25491c544f1cb242c52d06139eff4693fd47c08d25f5b05326e2f3b57b37f505fe2675c5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:20:36.094403) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:20:36.094779) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1461857893.871606-1141569755 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004378 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:20:36.180824) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:20:36.181244) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:20:36.181587) ========= ========= Total master_lag: 0.17 =========