builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-044 starttime: 1461867006.77 results: success (0) buildid: 20160428083223 builduid: 1797761532fd4db3a4b6ce25c4d16c9f revision: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:06.765819) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:06.766295) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:06.766592) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:06.784146) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:06.784514) ========= 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=574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-28 11:10:07-- 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% 42.9M=0s 2016-04-28 11:10:07 (42.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135296 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:06.935166) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:06.935513) ========= 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=574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017371 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:06.986437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:06.986833) ========= 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=574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-28 11:10:07,766 truncating revision to first 12 chars 2016-04-28 11:10:07,766 Setting DEBUG logging. 2016-04-28 11:10:07,766 attempt 1/10 2016-04-28 11:10:07,766 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 11:10:08,041 unpacking tar archive at: fx-team-45e67d6b5a61/testing/mozharness/ program finished with exit code 0 elapsedTime=0.557653 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:07.558402) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:07.558726) ========= script_repo_revision: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:07.559112) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:07.559375) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 11:10:07.571549) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-04-28 11:10:07.571919) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:10:08 INFO - MultiFileLogger online at 20160428 11:10:08 in /builds/slave/test 11:10:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:10:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:10:08 INFO - {'append_to_log': False, 11:10:08 INFO - 'base_work_dir': '/builds/slave/test', 11:10:08 INFO - 'blob_upload_branch': 'Fx-Team', 11:10:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:10:08 INFO - 'branch': 'Fx-Team', 11:10:08 INFO - 'buildbot_json_path': 'buildprops.json', 11:10:08 INFO - 'config_files': ('talos/linux_config.py',), 11:10:08 INFO - 'default_actions': ('clobber', 11:10:08 INFO - 'read-buildbot-config', 11:10:08 INFO - 'download-and-extract', 11:10:08 INFO - 'populate-webroot', 11:10:08 INFO - 'create-virtualenv', 11:10:08 INFO - 'install', 11:10:08 INFO - 'run-tests'), 11:10:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:10:08 INFO - 'download_minidump_stackwalk': True, 11:10:08 INFO - 'download_symbols': 'ondemand', 11:10:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:10:08 INFO - 'tooltool.py': '/tools/tooltool.py', 11:10:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:10:08 INFO - '/tools/misc-python/virtualenv.py')}, 11:10:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:10:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:10:08 INFO - 'installer_path': 'installer.exe', 11:10:08 INFO - 'log_level': 'info', 11:10:08 INFO - 'log_name': 'talos', 11:10:08 INFO - 'log_to_console': True, 11:10:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:10:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:10:08 INFO - 'opt_config_files': (), 11:10:08 INFO - 'pip_index': False, 11:10:08 INFO - 'sps_profile': False, 11:10:08 INFO - 'sps_profile_interval': 0, 11:10:08 INFO - 'suite': 'dromaeojs-e10s', 11:10:08 INFO - 'system_bits': '32', 11:10:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:10:08 INFO - 'title': 'talos-linux64-ix-044', 11:10:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:10:08 INFO - 'use_talos_json': True, 11:10:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:10:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:10:08 INFO - 'work_dir': 'build'} 11:10:08 INFO - ##### 11:10:08 INFO - ##### Running clobber step. 11:10:08 INFO - ##### 11:10:08 INFO - Running pre-action listener: _resource_record_pre_action 11:10:08 INFO - Running main action method: clobber 11:10:08 INFO - rmtree: /builds/slave/test/build 11:10:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:10:09 INFO - Running post-action listener: _resource_record_post_action 11:10:09 INFO - ##### 11:10:09 INFO - ##### Running read-buildbot-config step. 11:10:09 INFO - ##### 11:10:09 INFO - Running pre-action listener: _resource_record_pre_action 11:10:09 INFO - Running main action method: read_buildbot_config 11:10:09 INFO - Using buildbot properties: 11:10:09 INFO - { 11:10:09 INFO - "project": "", 11:10:09 INFO - "product": "firefox", 11:10:09 INFO - "script_repo_revision": "production", 11:10:09 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 11:10:09 INFO - "repository": "", 11:10:09 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 11:10:09 INFO - "buildid": "20160428083223", 11:10:09 INFO - "pgo_build": "True", 11:10:09 INFO - "basedir": "/builds/slave/test", 11:10:09 INFO - "buildnumber": 299, 11:10:09 INFO - "slavename": "talos-linux64-ix-044", 11:10:09 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:10:09 INFO - "platform": "ubuntu64_hw", 11:10:09 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 11:10:09 INFO - "branch": "fx-team", 11:10:09 INFO - "repo_path": "integration/fx-team", 11:10:09 INFO - "revision": "45e67d6b5a6137898898c23ae1fe60ddfcd59ed1", 11:10:09 INFO - "stage_platform": "linux64-pgo", 11:10:09 INFO - "builduid": "1797761532fd4db3a4b6ce25c4d16c9f", 11:10:09 INFO - "slavebuilddir": "test" 11:10:09 INFO - } 11:10:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 11:10:09 INFO - Running post-action listener: _resource_record_post_action 11:10:09 INFO - ##### 11:10:09 INFO - ##### Running download-and-extract step. 11:10:09 INFO - ##### 11:10:09 INFO - Running pre-action listener: _resource_record_pre_action 11:10:09 INFO - Running main action method: download_and_extract 11:10:09 INFO - mkdir: /builds/slave/test/build/tests 11:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:09 INFO - https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:10:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:10:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:10:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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 11:10:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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 11:10:13 INFO - Downloaded 1361 bytes. 11:10:13 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 11:10:13 INFO - Using the following test package requirements: 11:10:13 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 11:10:13 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:10:13 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:10:13 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:13 INFO - u'jsshell-linux-x86_64.zip'], 11:10:13 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:10:13 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 11:10:13 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:10:13 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:10:13 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:10:13 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 11:10:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:10:13 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:10: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')]} 11:10:13 INFO - https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:10:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:10:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:10:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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 11:10:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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 11:10:19 INFO - Downloaded 23873332 bytes. 11:10:19 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'] 11:10:19 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 11:10:20 INFO - Return code: 0 11:10:20 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 11:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:20 INFO - https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:10:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:10:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 11:10:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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 11:10:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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 11:10:22 INFO - Downloaded 23873332 bytes. 11:10:22 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'] 11:10:22 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 11:10:24 INFO - Return code: 0 11:10: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')]} 11:10:24 INFO - https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:10:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 11:10:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 11:10:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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 11:10:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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 11:10:25 INFO - Downloaded 10840145 bytes. 11:10:25 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'] 11:10:25 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 11:10:25 INFO - Return code: 0 11:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:25 INFO - https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:10:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:10:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:10:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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 11:10:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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 11:10:26 INFO - Downloaded 58400650 bytes. 11:10:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:10:26 INFO - mkdir: /builds/slave/test/properties 11:10:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:10:26 INFO - Writing to file /builds/slave/test/properties/build_url 11:10:26 INFO - Contents: 11:10:26 INFO - build_url:https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 11:10:27 INFO - Running post-action listener: _resource_record_post_action 11:10:27 INFO - Running post-action listener: set_extra_try_arguments 11:10:27 INFO - ##### 11:10:27 INFO - ##### Running populate-webroot step. 11:10:27 INFO - ##### 11:10:27 INFO - Running pre-action listener: _resource_record_pre_action 11:10:27 INFO - Running main action method: populate_webroot 11:10:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:10:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:10:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:10:27 INFO - '--tppagecycles', 11:10:27 INFO - '7'], 11:10:27 INFO - 'tests': ['tsvgm']}}, 11:10:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:10:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:10:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:10:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:10:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:10:27 INFO - 'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:10:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:10:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:10:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:10:27 INFO - 'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['damp', 'tps']}, 11:10:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:10:27 INFO - 'tests': ['damp', 'tps']}, 11:10:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['dromaeo_dom']}, 11:10:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:10:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'other-e10s': {'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['tsvgx', 11:10:27 INFO - 'tsvgr_opacity', 11:10:27 INFO - 'tart', 11:10:27 INFO - 'tscrollx', 11:10:27 INFO - 'cart']}, 11:10:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:10:27 INFO - 'tsvgr_opacity', 11:10:27 INFO - 'tart', 11:10:27 INFO - 'tscrollx', 11:10:27 INFO - 'cart']}, 11:10:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:10:27 INFO - 'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['tp5o']}, 11:10:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:10:27 INFO - 'tests': ['tp5o']}, 11:10:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:10:27 INFO - 'talos_options': ['--disable-e10s', 11:10:27 INFO - '--xperf_path', 11:10:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:10:27 INFO - 'tests': ['tp5n']}, 11:10:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:10:27 INFO - 'talos_options': ['--xperf_path', 11:10:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:10:27 INFO - 'tests': ['tp5n']}}, 11:10:27 INFO - 'talos.zip': {'path': '', 11:10:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:10:27 INFO - Running post-action listener: _resource_record_post_action 11:10:27 INFO - ##### 11:10:27 INFO - ##### Running create-virtualenv step. 11:10:27 INFO - ##### 11:10:27 INFO - Running pre-action listener: _resource_record_pre_action 11:10:27 INFO - Running main action method: create_virtualenv 11:10:27 INFO - Creating virtualenv /builds/slave/test/build/venv 11:10:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:10:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:10:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:10:27 INFO - Using real prefix '/usr' 11:10:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:10:28 INFO - Installing distribute.............................................................................................................................................................................................done. 11:10:30 INFO - Installing pip.................done. 11:10:30 INFO - Return code: 0 11:10:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f257711df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bca7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c59640>, '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 0x2c5a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c5a9d0>, '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': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:10:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:10:30 INFO - Using env: {'DISPLAY': ':0', 11:10:30 INFO - 'HOME': '/home/cltbld', 11:10:30 INFO - 'LANG': 'en_US.UTF-8', 11:10:30 INFO - 'LANGUAGE': 'en_US:en', 11:10:30 INFO - 'LOGNAME': 'cltbld', 11:10:30 INFO - 'MAIL': '/var/mail/cltbld', 11:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:30 INFO - 'MOZ_NO_REMOTE': '1', 11:10:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:30 INFO - 'NO_EM_RESTART': '1', 11:10:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:30 INFO - 'PWD': '/builds/slave/test', 11:10:30 INFO - 'SHELL': '/bin/bash', 11:10:30 INFO - 'SHLVL': '1', 11:10:30 INFO - 'TERM': 'linux', 11:10:30 INFO - 'TMOUT': '86400', 11:10:30 INFO - 'USER': 'cltbld', 11:10:30 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 11:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:30 INFO - '_': '/tools/buildbot/bin/python'} 11:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:10:30 INFO - Cleaning up... 11:10:30 INFO - Return code: 0 11:10:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f257711df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bca7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c59640>, '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 0x2c5a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c5a9d0>, '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': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:10:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 11:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 11:10:30 INFO - Using env: {'DISPLAY': ':0', 11:10:30 INFO - 'HOME': '/home/cltbld', 11:10:30 INFO - 'LANG': 'en_US.UTF-8', 11:10:30 INFO - 'LANGUAGE': 'en_US:en', 11:10:30 INFO - 'LOGNAME': 'cltbld', 11:10:30 INFO - 'MAIL': '/var/mail/cltbld', 11:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:30 INFO - 'MOZ_NO_REMOTE': '1', 11:10:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:30 INFO - 'NO_EM_RESTART': '1', 11:10:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:30 INFO - 'PWD': '/builds/slave/test', 11:10:30 INFO - 'SHELL': '/bin/bash', 11:10:30 INFO - 'SHLVL': '1', 11:10:30 INFO - 'TERM': 'linux', 11:10:30 INFO - 'TMOUT': '86400', 11:10:30 INFO - 'USER': 'cltbld', 11:10:30 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 11:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:30 INFO - '_': '/tools/buildbot/bin/python'} 11:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:30 INFO - Downloading/unpacking psutil>=0.7.1 11:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:10:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:10:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:10:32 INFO - Installing collected packages: psutil 11:10:32 INFO - Running setup.py install for psutil 11:10:32 INFO - building 'psutil._psutil_linux' extension 11:10:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:10:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:10:32 INFO - building 'psutil._psutil_posix' extension 11:10:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:10:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:10:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:10:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:10:32 INFO - Successfully installed psutil 11:10:32 INFO - Cleaning up... 11:10:32 INFO - Return code: 0 11:10:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:32 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 0x7f257711df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bca7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c59640>, '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 0x2c5a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c5a9d0>, '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': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:10:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 11:10:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 11:10:32 INFO - Using env: {'DISPLAY': ':0', 11:10:32 INFO - 'HOME': '/home/cltbld', 11:10:32 INFO - 'LANG': 'en_US.UTF-8', 11:10:32 INFO - 'LANGUAGE': 'en_US:en', 11:10:32 INFO - 'LOGNAME': 'cltbld', 11:10:32 INFO - 'MAIL': '/var/mail/cltbld', 11:10:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:32 INFO - 'MOZ_NO_REMOTE': '1', 11:10:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:32 INFO - 'NO_EM_RESTART': '1', 11:10:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:32 INFO - 'PWD': '/builds/slave/test', 11:10:32 INFO - 'SHELL': '/bin/bash', 11:10:32 INFO - 'SHLVL': '1', 11:10:32 INFO - 'TERM': 'linux', 11:10:32 INFO - 'TMOUT': '86400', 11:10:32 INFO - 'USER': 'cltbld', 11:10:32 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 11:10:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:32 INFO - '_': '/tools/buildbot/bin/python'} 11:10:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:10: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 11:10: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 11:10: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 11:10: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 11:10: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 11:10: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 11:10:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:10:35 INFO - Installing collected packages: mozsystemmonitor 11:10:35 INFO - Running setup.py install for mozsystemmonitor 11:10:35 INFO - Successfully installed mozsystemmonitor 11:10:35 INFO - Cleaning up... 11:10:35 INFO - Return code: 0 11:10:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:35 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 0x7f257711df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bca7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c59640>, '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 0x2c5a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c5a9d0>, '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': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:10:35 INFO - Using env: {'DISPLAY': ':0', 11:10:35 INFO - 'HOME': '/home/cltbld', 11:10:35 INFO - 'LANG': 'en_US.UTF-8', 11:10:35 INFO - 'LANGUAGE': 'en_US:en', 11:10:35 INFO - 'LOGNAME': 'cltbld', 11:10:35 INFO - 'MAIL': '/var/mail/cltbld', 11:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:35 INFO - 'MOZ_NO_REMOTE': '1', 11:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:35 INFO - 'NO_EM_RESTART': '1', 11:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:35 INFO - 'PWD': '/builds/slave/test', 11:10:35 INFO - 'SHELL': '/bin/bash', 11:10:35 INFO - 'SHLVL': '1', 11:10:35 INFO - 'TERM': 'linux', 11:10:35 INFO - 'TMOUT': '86400', 11:10:35 INFO - 'USER': 'cltbld', 11:10:35 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 11:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:35 INFO - '_': '/tools/buildbot/bin/python'} 11:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:35 INFO - Downloading/unpacking blobuploader==1.2.4 11:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:37 INFO - Downloading blobuploader-1.2.4.tar.gz 11:10:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:10:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:10:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:37 INFO - Downloading docopt-0.6.1.tar.gz 11:10:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:10:38 INFO - Installing collected packages: blobuploader, requests, docopt 11:10:38 INFO - Running setup.py install for blobuploader 11:10:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:10:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:10:38 INFO - Running setup.py install for requests 11:10:38 INFO - Running setup.py install for docopt 11:10:38 INFO - Successfully installed blobuploader requests docopt 11:10:38 INFO - Cleaning up... 11:10:38 INFO - Return code: 0 11:10:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:38 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 0x7f257711df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bca7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c59640>, '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 0x2c5a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c5a9d0>, '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': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:10:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:10:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:10:38 INFO - Using env: {'DISPLAY': ':0', 11:10:38 INFO - 'HOME': '/home/cltbld', 11:10:38 INFO - 'LANG': 'en_US.UTF-8', 11:10:38 INFO - 'LANGUAGE': 'en_US:en', 11:10:38 INFO - 'LOGNAME': 'cltbld', 11:10:38 INFO - 'MAIL': '/var/mail/cltbld', 11:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:38 INFO - 'MOZ_NO_REMOTE': '1', 11:10:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:38 INFO - 'NO_EM_RESTART': '1', 11:10:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:38 INFO - 'PWD': '/builds/slave/test', 11:10:38 INFO - 'SHELL': '/bin/bash', 11:10:38 INFO - 'SHLVL': '1', 11:10:38 INFO - 'TERM': 'linux', 11:10:38 INFO - 'TMOUT': '86400', 11:10:38 INFO - 'USER': 'cltbld', 11:10:38 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 11:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:38 INFO - '_': '/tools/buildbot/bin/python'} 11:10:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:10:38 INFO - Running setup.py (path:/tmp/pip-dEOIwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:10:38 INFO - Running setup.py (path:/tmp/pip-Yve75i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:10:38 INFO - Running setup.py (path:/tmp/pip-k6gWJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:10:39 INFO - Running setup.py (path:/tmp/pip-xeSajR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:10:39 INFO - Running setup.py (path:/tmp/pip-NEL2Pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:10:39 INFO - Running setup.py (path:/tmp/pip-fzuoIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:10:39 INFO - Running setup.py (path:/tmp/pip-l2qb88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:10:39 INFO - Running setup.py (path:/tmp/pip-w67fPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:10:39 INFO - Running setup.py (path:/tmp/pip-BG9XaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:10:39 INFO - Running setup.py (path:/tmp/pip-gdn9bE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:10:39 INFO - Running setup.py (path:/tmp/pip-uwZ3Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:10:39 INFO - Running setup.py (path:/tmp/pip-dckgOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:10:39 INFO - Running setup.py (path:/tmp/pip-pVD8Su-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:10:39 INFO - Running setup.py (path:/tmp/pip-YxWUnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:39 INFO - Running setup.py (path:/tmp/pip-S9Jxxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:10:39 INFO - Running setup.py (path:/tmp/pip-_JzGaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:10:39 INFO - Running setup.py (path:/tmp/pip-3MqtIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:10:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:10:40 INFO - Running setup.py install for manifestparser 11:10:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:10:40 INFO - Running setup.py install for mozcrash 11:10:40 INFO - Running setup.py install for mozdebug 11:10:40 INFO - Running setup.py install for mozdevice 11:10:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:10:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:10:40 INFO - Running setup.py install for mozfile 11:10:40 INFO - Running setup.py install for mozhttpd 11:10:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:10:40 INFO - Running setup.py install for mozinfo 11:10:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:10:40 INFO - Running setup.py install for mozInstall 11:10:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:10:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:10:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:10:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:10:40 INFO - Running setup.py install for mozleak 11:10:40 INFO - Running setup.py install for mozlog 11:10:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:10:41 INFO - Running setup.py install for moznetwork 11:10:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:10:41 INFO - Running setup.py install for mozprocess 11:10:41 INFO - Running setup.py install for mozprofile 11:10:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:10:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:10:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:10:41 INFO - Running setup.py install for mozrunner 11:10:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:10:41 INFO - Running setup.py install for mozscreenshot 11:10:41 INFO - Running setup.py install for moztest 11:10:41 INFO - Running setup.py install for mozversion 11:10:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:10:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:10:41 INFO - Cleaning up... 11:10:41 INFO - Return code: 0 11:10:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:41 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 0x7f257711df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bca7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c59640>, '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 0x2c5a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c5a9d0>, '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': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:10:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:10:41 INFO - Using env: {'DISPLAY': ':0', 11:10:41 INFO - 'HOME': '/home/cltbld', 11:10:41 INFO - 'LANG': 'en_US.UTF-8', 11:10:41 INFO - 'LANGUAGE': 'en_US:en', 11:10:41 INFO - 'LOGNAME': 'cltbld', 11:10:41 INFO - 'MAIL': '/var/mail/cltbld', 11:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:41 INFO - 'MOZ_NO_REMOTE': '1', 11:10:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:41 INFO - 'NO_EM_RESTART': '1', 11:10:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:41 INFO - 'PWD': '/builds/slave/test', 11:10:41 INFO - 'SHELL': '/bin/bash', 11:10:41 INFO - 'SHLVL': '1', 11:10:41 INFO - 'TERM': 'linux', 11:10:41 INFO - 'TMOUT': '86400', 11:10:41 INFO - 'USER': 'cltbld', 11:10:41 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 11:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:41 INFO - '_': '/tools/buildbot/bin/python'} 11:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:10:42 INFO - Running setup.py (path:/tmp/pip-eUEg0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:10:42 INFO - Running setup.py (path:/tmp/pip-OBS5Gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:10:42 INFO - Running setup.py (path:/tmp/pip-MUef_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:10:42 INFO - Running setup.py (path:/tmp/pip-1IX5_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:10:42 INFO - Running setup.py (path:/tmp/pip-1YGsk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:10:42 INFO - Running setup.py (path:/tmp/pip-c_lUlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:10:42 INFO - Running setup.py (path:/tmp/pip-UJlzRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:10:42 INFO - Running setup.py (path:/tmp/pip-ZcTpnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:10:42 INFO - Running setup.py (path:/tmp/pip-lf21ni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:10:42 INFO - Running setup.py (path:/tmp/pip-MtH05x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:10:42 INFO - Running setup.py (path:/tmp/pip-C7QlDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:10:42 INFO - Running setup.py (path:/tmp/pip-0kYTTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:10:42 INFO - Running setup.py (path:/tmp/pip-T6F87z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:10:42 INFO - Running setup.py (path:/tmp/pip-v4InR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:43 INFO - Running setup.py (path:/tmp/pip-zuXydE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:10:43 INFO - Running setup.py (path:/tmp/pip-EcxPbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:10:43 INFO - Running setup.py (path:/tmp/pip-eOmHfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:10:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:45 INFO - Downloading blessings-1.6.tar.gz 11:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:10:45 INFO - Installing collected packages: blessings 11:10:45 INFO - Running setup.py install for blessings 11:10:45 INFO - Successfully installed blessings 11:10:45 INFO - Cleaning up... 11:10:45 INFO - Return code: 0 11:10:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:10:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:10:45 INFO - Reading from file tmpfile_stdout 11:10:45 INFO - Current package versions: 11:10:45 INFO - argparse == 1.2.1 11:10:45 INFO - blessings == 1.6 11:10:45 INFO - blobuploader == 1.2.4 11:10:45 INFO - docopt == 0.6.1 11:10:45 INFO - manifestparser == 1.1 11:10:45 INFO - mozInstall == 1.12 11:10:45 INFO - mozcrash == 0.17 11:10:45 INFO - mozdebug == 0.1 11:10:45 INFO - mozdevice == 0.48 11:10:45 INFO - mozfile == 1.2 11:10:45 INFO - mozhttpd == 0.7 11:10:45 INFO - mozinfo == 0.9 11:10:45 INFO - mozleak == 0.1 11:10:45 INFO - mozlog == 3.1 11:10:45 INFO - moznetwork == 0.27 11:10:45 INFO - mozprocess == 0.22 11:10:45 INFO - mozprofile == 0.28 11:10:45 INFO - mozrunner == 6.11 11:10:45 INFO - mozscreenshot == 0.1 11:10:45 INFO - mozsystemmonitor == 0.0 11:10:45 INFO - moztest == 0.7 11:10:45 INFO - mozversion == 1.4 11:10:45 INFO - psutil == 3.1.1 11:10:45 INFO - requests == 1.2.3 11:10:45 INFO - wsgiref == 0.1.2 11:10:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:45 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 0x7f257711df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bca7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c59640>, '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 0x2c5a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c5a9d0>, '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': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:10:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:10:45 INFO - Using env: {'DISPLAY': ':0', 11:10:45 INFO - 'HOME': '/home/cltbld', 11:10:45 INFO - 'LANG': 'en_US.UTF-8', 11:10:45 INFO - 'LANGUAGE': 'en_US:en', 11:10:45 INFO - 'LOGNAME': 'cltbld', 11:10:45 INFO - 'MAIL': '/var/mail/cltbld', 11:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:45 INFO - 'MOZ_NO_REMOTE': '1', 11:10:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:45 INFO - 'NO_EM_RESTART': '1', 11:10:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:45 INFO - 'PWD': '/builds/slave/test', 11:10:45 INFO - 'SHELL': '/bin/bash', 11:10:45 INFO - 'SHLVL': '1', 11:10:45 INFO - 'TERM': 'linux', 11:10:45 INFO - 'TMOUT': '86400', 11:10:45 INFO - 'USER': 'cltbld', 11:10:45 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 11:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:45 INFO - '_': '/tools/buildbot/bin/python'} 11:10:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:10:45 INFO - Cleaning up... 11:10:45 INFO - Return code: 0 11:10:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:45 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 0x7f257711df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bca7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c59640>, '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 0x2c5a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c5a9d0>, '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': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:10:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:10:45 INFO - Using env: {'DISPLAY': ':0', 11:10:45 INFO - 'HOME': '/home/cltbld', 11:10:45 INFO - 'LANG': 'en_US.UTF-8', 11:10:45 INFO - 'LANGUAGE': 'en_US:en', 11:10:45 INFO - 'LOGNAME': 'cltbld', 11:10:45 INFO - 'MAIL': '/var/mail/cltbld', 11:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:45 INFO - 'MOZ_NO_REMOTE': '1', 11:10:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:45 INFO - 'NO_EM_RESTART': '1', 11:10:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:45 INFO - 'PWD': '/builds/slave/test', 11:10:45 INFO - 'SHELL': '/bin/bash', 11:10:45 INFO - 'SHLVL': '1', 11:10:45 INFO - 'TERM': 'linux', 11:10:45 INFO - 'TMOUT': '86400', 11:10:45 INFO - 'USER': 'cltbld', 11:10:45 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 11:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:45 INFO - '_': '/tools/buildbot/bin/python'} 11:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:46 INFO - Downloading/unpacking jsonschema 11:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:10:47 INFO - Downloading/unpacking functools32 (from jsonschema) 11:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:48 INFO - Downloading functools32-3.2.3-2.tar.gz 11:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:10:48 INFO - warning: no files found matching '*.txt' 11:10:48 INFO - no previously-included directories found matching 'build' 11:10:48 INFO - no previously-included directories found matching 'dist' 11:10:48 INFO - no previously-included directories found matching '.git*' 11:10:48 INFO - Installing collected packages: jsonschema, functools32 11:10:48 INFO - Running setup.py install for functools32 11:10:48 INFO - warning: no files found matching '*.txt' 11:10:48 INFO - no previously-included directories found matching 'build' 11:10:48 INFO - no previously-included directories found matching 'dist' 11:10:48 INFO - no previously-included directories found matching '.git*' 11:10:48 INFO - Successfully installed jsonschema functools32 11:10:48 INFO - Cleaning up... 11:10:48 INFO - Return code: 0 11:10:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:48 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 0x7f257711df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bca7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c59640>, '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 0x2c5a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c5a9d0>, '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': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:10:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 11:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 11:10:48 INFO - Using env: {'DISPLAY': ':0', 11:10:48 INFO - 'HOME': '/home/cltbld', 11:10:48 INFO - 'LANG': 'en_US.UTF-8', 11:10:48 INFO - 'LANGUAGE': 'en_US:en', 11:10:48 INFO - 'LOGNAME': 'cltbld', 11:10:48 INFO - 'MAIL': '/var/mail/cltbld', 11:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:48 INFO - 'MOZ_NO_REMOTE': '1', 11:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:10:48 INFO - 'NO_EM_RESTART': '1', 11:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:48 INFO - 'PWD': '/builds/slave/test', 11:10:48 INFO - 'SHELL': '/bin/bash', 11:10:48 INFO - 'SHLVL': '1', 11:10:48 INFO - 'TERM': 'linux', 11:10:48 INFO - 'TMOUT': '86400', 11:10:48 INFO - 'USER': 'cltbld', 11:10:48 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 11:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:48 INFO - '_': '/tools/buildbot/bin/python'} 11:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:48 INFO - Downloading/unpacking flake8 11:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:10:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:10:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:10:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:10:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:10:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:10:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:10:51 INFO - no previously-included directories found matching 'docs/_build' 11:10:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:10:51 INFO - Running setup.py install for pep8 11:10:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:10:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:10:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:10:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:10:51 INFO - no previously-included directories found matching 'docs/_build' 11:10:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:10:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:10:51 INFO - Cleaning up... 11:10:51 INFO - Return code: 0 11:10:51 INFO - Running post-action listener: _resource_record_post_action 11:10:51 INFO - Running post-action listener: _start_resource_monitoring 11:10:51 INFO - Starting resource monitoring. 11:10:51 INFO - ##### 11:10:51 INFO - ##### Running install step. 11:10:51 INFO - ##### 11:10:51 INFO - Running pre-action listener: _resource_record_pre_action 11:10:51 INFO - Running main action method: install 11:10:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:10:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:10:51 INFO - Reading from file tmpfile_stdout 11:10:51 INFO - Detecting whether we're running mozinstall >=1.0... 11:10:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:10:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:10:51 INFO - Reading from file tmpfile_stdout 11:10:51 INFO - Output received: 11:10:51 INFO - Usage: mozinstall [options] installer 11:10:51 INFO - Options: 11:10:51 INFO - -h, --help show this help message and exit 11:10:51 INFO - -d DEST, --destination=DEST 11:10:51 INFO - Directory to install application into. [default: 11:10:51 INFO - "/builds/slave/test"] 11:10:51 INFO - --app=APP Application being installed. [default: firefox] 11:10:51 INFO - mkdir: /builds/slave/test/build/application 11:10:51 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'] 11:10:51 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 11:11:08 INFO - Reading from file tmpfile_stdout 11:11:08 INFO - Output received: 11:11:08 INFO - /builds/slave/test/build/application/firefox/firefox 11:11:08 INFO - Running post-action listener: _resource_record_post_action 11:11:08 INFO - ##### 11:11:08 INFO - ##### Running run-tests step. 11:11:08 INFO - ##### 11:11:08 INFO - Running pre-action listener: _resource_record_pre_action 11:11:08 INFO - Running main action method: run_tests 11:11:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:11:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:11:08 INFO - Python 2.7.3 11:11:08 INFO - Return code: 0 11:11:08 INFO - grabbing minidump binary from tooltool 11:11: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')]} 11:11:08 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 0x2c59640>, '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 0x2c5a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c5a9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:11:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:11:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:11:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:11:08 INFO - Return code: 0 11:11:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:11:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:11:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:11:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:11:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:11:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:11:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:11:09 INFO - Return code: 0 11:11:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:11:09 INFO - Using env: {'DISPLAY': ':0', 11:11:09 INFO - 'HOME': '/home/cltbld', 11:11:09 INFO - 'LANG': 'en_US.UTF-8', 11:11:09 INFO - 'LANGUAGE': 'en_US:en', 11:11:09 INFO - 'LOGNAME': 'cltbld', 11:11:09 INFO - 'MAIL': '/var/mail/cltbld', 11:11:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:11:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:09 INFO - 'MOZ_NO_REMOTE': '1', 11:11:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:11:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:09 INFO - 'NO_EM_RESTART': '1', 11:11:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:09 INFO - 'PWD': '/builds/slave/test', 11:11:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:11:09 INFO - 'SHELL': '/bin/bash', 11:11:09 INFO - 'SHLVL': '1', 11:11:09 INFO - 'TERM': 'linux', 11:11:09 INFO - 'TMOUT': '86400', 11:11:09 INFO - 'USER': 'cltbld', 11:11:09 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648', 11:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:09 INFO - '_': '/tools/buildbot/bin/python'} 11:11:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:11:09 INFO - mozversion application_buildid: 20160428083223 11:11:09 INFO - mozversion application_changeset: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 11:11:09 INFO - mozversion application_display_name: Nightly 11:11:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:11:09 INFO - mozversion application_name: Firefox 11:11:09 INFO - mozversion application_remotingname: firefox 11:11:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:11:09 INFO - mozversion application_vendor: Mozilla 11:11:09 INFO - mozversion application_version: 49.0a1 11:11:09 INFO - mozversion platform_buildid: 20160428083223 11:11:09 INFO - mozversion platform_changeset: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 11:11:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:11:09 INFO - mozversion platform_version: 49.0a1 11:11:09 INFO - using testdate: 1461867069 11:11:09 INFO - actual date: 1461867069 11:11:09 INFO - starting webserver on 'localhost:53071' 11:11:09 INFO - SUITE-START | Running 2 tests 11:11:09 INFO - TEST-START | dromaeo_css 11:11:09 INFO - operating with platform_type : linux_ 11:11:09 INFO - Initialising browser for dromaeo_css test... 11:11:09 INFO - TEST-INFO | started process 11506 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvoaJG4/profile http://localhost:53071/getInfo.html) 11:11:13 INFO - PROCESS | 11506 | 11:11:13 INFO - PROCESS | 11506 | (process:11575): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 11:11:14 INFO - PROCESS | 11506 | __metrics Screen width/height:1600/1200 11:11:14 INFO - PROCESS | 11506 | colorDepth:24 11:11:14 INFO - PROCESS | 11506 | Browser inner width/height: 1024/697 11:11:14 INFO - PROCESS | 11506 | __metrics 11:11:14 ERROR - PROCESS | 11506 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 11:11:15 INFO - TEST-INFO | 11506: exit 0 11:11:15 INFO - Browser initialized. 11:11:15 INFO - Running cycle 1/1 for dromaeo_css test... 11:11:15 INFO - TEST-INFO | started process 11626 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvoaJG4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:11:17 INFO - PROCESS | 11626 | 11:11:17 INFO - PROCESS | 11626 | (process:11685): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 11:11:27 INFO - PROCESS | 11626 | RSS: Main: 165146624 11:11:27 INFO - PROCESS | 11626 | 11:12:17 INFO - PROCESS | 11626 | 1461867137157 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:14:24 INFO - PROCESS | 11626 | Cycle 1(1): loaded http://localhost:53071/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53071/tests/dromaeo/cssquery-ext.html) 11:14:25 INFO - PROCESS | 11626 | RSS: Main: 170979328 11:14:25 INFO - PROCESS | 11626 | 11:17:27 INFO - PROCESS | 11626 | Cycle 1(1): loaded http://localhost:53071/tests/dromaeo/cssquery-ext.html (next: http://localhost:53071/tests/dromaeo/cssquery-jquery.html) 11:17:28 INFO - PROCESS | 11626 | RSS: Main: 166379520 11:17:28 INFO - PROCESS | 11626 | 11:20:30 INFO - PROCESS | 11626 | Cycle 1(1): loaded http://localhost:53071/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53071/tests/dromaeo/cssquery-mootools.html) 11:20:30 INFO - PROCESS | 11626 | RSS: Main: 167575552 11:20:30 INFO - PROCESS | 11626 | 11:23:32 INFO - PROCESS | 11626 | Cycle 1(1): loaded http://localhost:53071/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53071/tests/dromaeo/cssquery-prototype.html) 11:23:33 INFO - PROCESS | 11626 | RSS: Main: 164904960 11:23:33 INFO - PROCESS | 11626 | 11:26:35 INFO - PROCESS | 11626 | Cycle 1(1): loaded http://localhost:53071/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53071/tests/dromaeo/cssquery-yui.html) 11:26:36 INFO - PROCESS | 11626 | RSS: Main: 166006784 11:26:36 INFO - PROCESS | 11626 | 11:29:38 INFO - PROCESS | 11626 | Cycle 1(1): loaded http://localhost:53071/tests/dromaeo/cssquery-yui.html (next: http://localhost:53071/tests/dromaeo/cssquery-dojo.html) 11:29:38 INFO - PROCESS | 11626 | RSS: Main: 168255488 11:29:38 INFO - PROCESS | 11626 | 11:29:38 INFO - PROCESS | 11626 | __start_tp_report 11:29:38 INFO - PROCESS | 11626 | _x_x_mozilla_page_load 11:29:38 INFO - PROCESS | 11626 | _x_x_mozilla_page_load_details 11:29:38 INFO - PROCESS | 11626 | |i|pagename|runs| 11:29:38 INFO - PROCESS | 11626 | |0;dojo.html;3120.251139726465;3155.0692545699017;3184.9012090828655;3222.210558413189;3233.2401885556906;5320.388155362135;5410.864728381793;5436.075867102592;5452.0458919689045;5455.318085239345;5316.548093412064;5329.760160792764;5347.652402593829;5418.106012507941;5485.177223416489;4884.877878053042;4908.754562271872;4970.353853998975;4973.204287314031;5019.222020586815;675.6440744431693;4890.388701412324;4951.628627852908;4956;4988.575971042447;4907.312976183337;4911.5088491150955;4936.5063493650705;4970.925436118443;5003.299538064655;4822.132016237076;4967.329410529568;5031.572316353115;5034.320366750479;5105.744712764384;4852.441969173555;4874.122657921573;4927.630427717935;4927.679700819435;4939.728314942677;5300.231466437345;5332.066888294572;5339.652922560022;5344.4630604755;5359.38367087786;5695.629784064064;5772.538196944234;5795.739191736382;5797.420257974169;5797.420257974211;5720.799772007959;5762.222100016528;5781.4507621775865;5782.508486778629;5826.446487583673;5328.5470734987575;5353.170258609922;5362.517373436371;5372;5372.059889519358;5791.478766910914;5841.415858414124;5876.559258055578;5890.705464726747;5929.229200203946;5630.718464076862;5643.943560564424;5647.0964645656495;5660.433956604389;5665.518430933417;5709.9714501427225;5732.226149469851;5734.197212390269;5743.2246646703;5755.59710820247;5649.12438572022;5712.714364281769;5750.137479378127;5789.334226563915;5820.796272130455;5718.656880587178;5730.398308177665;5776.046952252916;5815.941840581625;5841.182234487175;4398.626116780109;4401.40581021558;4408.360787685831;4419.558044195554;4448.4439445069365;3878.6897048236074;3885.9805700971315;3927.1752931884625;3948.58539853317;3976.165005348851;5564.1931919871395;5567.805126820542;5586.050371436865;5598.3282006159525;5602.579806514446;5747.252857128547;5849.824505264849;5853.268341457318;5862.618929769551;5910.940890591125;5416.322959630046;5493.7802487900035;5510.421405752339;5585.1901474286005;5613.803516876889;4945.727984960789;5003.674761140514;5005.424376196711;5010.223415370623;5019.598432125421;6002;6012.729427175787;6050.727717252734;6078.300995385499;6090.604110732788;5782.2483077199695;5847.619904706181;5855.765769369348;5861.677607731615;5888.263967004124;6021.638701677914;6031.185789918305;6043.909341359795;6047.818565443044;6055.485283750931;5569.387367389503;5601.887962240651;5714.79998200061;5719.5138413235345;5756.107803290497;5775.480206781242;5775.797847075332;5776.653400795965;5780.5953583248765;5813.738381772915;5599.552035837205;5608.327000759935;5626;5627.634203776742;5633.408492108433;5538.6399884007415;5561.221429000024;5604;5620.1569764534015;5701.059325211294;5514.145307477347;5560.860978475571;5565.026120428828;5633.985882541182;5642.830715078554;5753.5109515689965;5757.424257574209;5767.9134812977;5775.018246898121;5782.045962416154;5540.944590554042;5604.915926261027;5625.76233228689;5640.323161220515;5644.915326270027 11:29:38 INFO - PROCESS | 11626 | |1;ext.html;611.5107913669063;648.0344287012351;655.429776094797;663.7212370804264;671.8857794174993;37100.814495927516;38279.42580861289;38316.425253621186;38609;38684.41973370398;40494;41046.384304235464;41243.58756412443;41332;41393.58606413935;8557.614907329169;8710.608022638997;8711.259542938858;8746.956265218665;8777.341699372573;8572.271356934669;8727.519986400745;8742.781430464225;8898.777530561756;8908.688195913144;8919.24186444153;8925.821483570324;8970.327225458117;8976.820463590724;8992.145746154105;8768.123187681245;8885.378023538355;8925.107489251088;8937.106289371075;8939;8745.300375969908;8845.292376609856;8848.46909185451;8875.955620221923;8892.199702026848;40870.79564602158;41476;41531.58468415338;41532.169356613;41582.58417415847;3510.1049232445816;3514.947275790865;3536.9115772105647;3550.2366991097037;3661.0847288177956;32065.51901721476;32484.18789530257;32535.837320813243;32658;32676.509852352232;4190.622843944059;4291.644682481998;4306.160298741746;4401.801918913657;4419.734815911023;312583;312749;316624.41687791643;316809;316862;59972.700136499036;60818;60908.39091609116;61003.38996610066;61026.38973610296;60678;62143.37856621466;62160;62358.37641623616;62419;17589.20848561818;17628.559286017953;17649.117544122742;17720.291188352578;17814.821851781577;13731.24478153711;13787.586372408843;13805.654858628615;13812.86187138136;13886.652833679036;19703;19828.801711982695;20156.89921550383;20173.697394539093;20178.991050447417;7241.927580724126;7336.816579585553;7426.888596671054;7613.352865006425;7641.2740789624895;856.3834039491556;871.0331531999476;918.4122161816442;1050.8791488978713;1093.8413929980109;29358.559621605695;31312;31669.841650791597;32768.18079547983;32849;1107.983380249297;1118.5134466507095;1131.1572878205625;1172.8299396587447;1174.2426135142955;16037.759433608506;16192.75710864338;16208.270627821541;16286.348546058261;16709.081000544844;442224;445205;445648;450220;450312;55082;55764.72117639467;56068.158977614556;56171;56224.71887640536;1322.9206247625173;1334.833145856768;1335.8998075144402;1381.647679841634;1391.471240928461;29630.703692962794;29805.701942980293;30202.24494387658;30562.235944100685;31330.216744580655;56506;57276;57725.71137144287;57728;57971;424862;431370;432079;433255;433575;43574.56425435705;43820;44707.32939005983;44902.10195796;45017.32474012958;48749;48783.268250975554;48893.51106489032;49460;49873.251901220785;40629.79685101674;41260.1747965033;41503.37744933768;41593.5360120851;42130.368044478746;41019.79490102531;41223;41253.38119928144;42252.57747422609;42433;41197.58802411937;41388.379174311805;42233.788831055645;42600.36099458449;42874.14251714886;34584.82707586446;36979;37420.251594967405;37927;37970;28666.71333286724;28861.27846803847;28994.565081524215;29153.708462915947;29265.122046338645 11:29:38 INFO - PROCESS | 11626 | |2;jquery.html;394.26469634132343;394.68030894975055;396.09492310071494;402.42453291792725;404.2541510912367;13842.377093030813;18006.279748810048;18625.88244705315;18732.53168670784;18776.342828001005;17692.115394230303;17774.73337899929;18076.909615451903;19247.326343577977;19250.807491925112;5456.236126942231;5584.692841893694;5691.08942569189;5713;5771.567132465082;5536.086545719952;5596.804111856086;5617.045102332613;5642.74607642655;5657.971710141464;5164.431912489623;5381.730913454331;5578.191162281467;5584.21820945068;5620.409856965021;5218.191180367049;5378.757955891994;5383.084875571143;5394.91907621386;5431.646942948715;5362.35651721792;5415.918761218585;5440.755166017539;5455.263539422167;5457.2359869618285;13051.738965220642;13245.139065960682;17158.39945601898;23023;23045.19341823045;2191.4331253153914;2192.397090800027;2369.6327069304284;2432.525657496789;2729.19488750819;9015;9233.261339092856;9255.583498742573;9675;9677.90322096784;1875.9047373535036;1881.3603374852617;1973.1811298311184;2018.4045706516613;2160.0387827416807;69814.65092674555;73254.63372683155;73448.89826652658;77062;85540.57229713874;9784.315097943076;10048.497575121215;10076.496175191358;10233.232507561963;10610.734731631772;14312.141271523742;14344.139351638934;14486.637834054021;14486.927565362317;14508;7931.127575966639;8106.108328083906;8216.507009579444;8339.416240863082;8345.582720864053;8125.0499880028365;8200.384971127189;8206.794830129294;8341.20758527939;8350;4385.122975404932;4402.603765661106;4441.089576636782;4456.331550267421;4519.163954668397;1836.4766041678058;1861.9162137703836;1881.303917550515;1888.225827410755;1900.2873922279146;940.6660635474445;950.3870003847479;1019.9898001020043;1105.5135740274254;1108.9944550277196;17805.198766055557;17895.552611184823;18595.721064184047;19008.714869276973;19660.311889083812;1004.0712341084468;1016.2733645443543;1017.2878984710584;1033.7674023344837;1056.2395075545594;10692.679219623267;10806.51370688321;10893.237473376788;10901.01890829829;10928.890711092787;459920;471772;477852;484889;490883;61073.69463152745;70581.64709176421;77237.227627723;89961.55019224861;94588;1009.7374682582434;1033.8655974723238;1040.8646875906084;1047.4710271312938;1071.5660157636325;17139.485815425556;17330.913345433695;17374.39189628357;17430;17548.21033053541;36897.07757306089;37542.2491550162;37631.62368376391;38559.228815424096;38852.2229555413;52283;52366.738166308925;52572;52930.206046908555;56738.43261567497;8017.8396432072195;8114.391420643357;8195.918040819515;8204.917950820416;8315.875261870955;8864.379493435397;12388.938055310025;13011.544595939096;13021.934890325489;13877.306134693426;11949.402529873298;12231.204971676812;12265.26408415498;12340.259584424963;12464.688382790502;11474.541018359172;11808.586699465477;11866.28802271832;11897.821532676844;12105.576304829288;10877.585913831468;11606;12207.511699532275;12444;12613.558525451564;8054.597270136587;8126.959365203136;8212.548309843016;8235.21765432283;8407.747767566983;8356.289715374018;10456.32033917793;11822;11863;11946.283223006647 11:29:38 INFO - PROCESS | 11626 | |3;mootools.html;1519.4681861348527;1545.6599548099418;1670.799504059513;1796.083997161449;1802.3781795280624;7556;7853.5680537570415;8126.065502467218;8152.1847815218525;8186.91813081869;8227.095019547845;8245.093039765621;8266.669333226664;8272.338212942965;8313.792155196108;2274.3404412720292;2317.9420514487106;2318.3508617587017;2325.244295603927;2340.028888011473;2257.548490301946;2287.794098531132;2325.918592849242;2326.5928462519078;2329.6621989811465;2284.931452056441;2298.747137814839;2301.137073597401;2315.907363705458;2344.8593084414906;2231.5313784105356;2245.2702871566726;2255.4812393149587;2265.4336415895946;2269.0810221860174;2259.706238189025;2270.421042634134;2275.89758460868;2281.1673739085113;2282.1213832674375;8464.068952415291;8532.189442003;8542.060373358925;8570.442921210164;8584.484930904166;4862.416510018785;5051.494850514956;5084.46613105626;5088.287639730441;5088.872778180538;3181.0933883842995;3211.6467188609236;3377.662233776627;3387.9830600847085;3404.4723067924265;1008.1581879130935;1020.4999550220413;1047.4029803012286;1049.5854137615675;1074.097757883374;11706.180567360288;11780.823287650692;11816.704582385422;11877.346745928884;11884.940575297154;4331.631811296075;4376.452943382077;4503.684742068024;4506.481754598263;4517.616002639812;9974.850377244347;10069.647562335284;10076.69769906904;10097.141742951784;10148.69553913369;5022.522860328336;5037.899242015213;5040.672356296829;5061.063703214917;5086.4659210783075;4012.6789856811383;4556.544345565417;4580.0610874771255;4669.276262179367;4669.906601867943;3736.6823819975607;3761.567419746709;3843.8078096095082;3855.5566109897723;3868.5357757069332;1209.1535924852462;1209.8808602043075;1214.4352875912743;1222.7004383925996;1227.864934857165;985.9457729824928;995.9651412200683;1006.4967516241879;1010.5099026971913;1020.3163880200284;5760.567957403212;5881.882362352814;5893.734781934738;6000.609960352563;6085.5435842312;427.1584977594135;428.21850123524695;429.9290617048152;1050.99474502627;1057.9682609521726;4246.808893599796;4286.249906266391;4312.353147027909;4312.417823593774;4316.546762589883;10189.133923616428;10412;10556.788864222826;10588.894111058946;10630;10359.948200259101;10450.843237351446;10469.214808889365;10495.737606559896;10515.316504427188;7366.815829604303;7682;7682.270184332591;7698.499597526143;7908.604569771488;5817.854553636193;6005.669688167192;6088.6042407243385;6125.111858780363;6184.536159788124;9552.761180970532;9742.707718768164;9772.413655180711;9846.409215447096;9972.451515166735;9965.053319934595;10069;10071.597136114473;10278.126359259546;10322.277440579088;7035.824104397431;7438.814029649302;7560.395168386406;7634.656440460304;7817.921820781719;8271;8313.459625124347;8316.83366332682;8353.707620233263;8366.581670916552;7251.601161936144;7306.41548676094;7499.962500187682;7509.286617771303;7568.924310756822;7324.707011719472;7396.852062958603;7398.926010739824;7510.962445187739;7515.210902855121;6680;6781.762638307636;6826.795196144124;6828.5902845829405;6833.624150671761;4956.826511072095;5097.898042039064;5160.045391602565;5177.844664659916;5182.637215394886;5099.464556221692;5135.948640513697;5143.5113664201845;5181.171012637959;5237.790488380575 11:29:38 INFO - PROCESS | 11626 | |4;prototype.html;302.5220152159587;315.9636641786194;319.9728023118035;320.09413360190655;322.732132330166;5328.733563321828;5378.89242215156;5389.461053894609;5541.556675465963;5556.861078473035;5450.781968721256;5454.763546945753;5469.316335458067;5479.890402191953;5504.036793561131;4171.332586786115;4193.301712806318;4199.286121359361;4200.747955122687;4213.915721685565;4147.979260103695;4224.366345048252;4232.470941132358;4249.6387807036435;4257.191133684605;4071.857484988026;4126.154138401621;4161.916761664765;4198.37024446334;4211.7051806373565;4117.397277382275;4118.91762164755;4127.793610319487;4139.172165566899;4153;3693.759905606127;3700.5559332879957;3705.0181701849033;3720.162963333228;3724.7392682512236;5119.38567371914;5201.517567493246;5230.195582524061;5244.160934250502;5301.761420736076;4760.33421284082;4788.138135135674;4791.9520804791855;4795.786822828713;4799.448063472675;5455.536279416255;5662.2639056922335;5705.286839145107;5718.885622287531;5721.971390143064;4016.819243134066;4030.2745505808944;4030.3954406838916;4033.1127152026506;4041.9797901010306;5941.70291485424;6006.129111278884;6046.6069705469445;6054;6066.696665166724;4099.549049604541;4114.362273847559;4118.773467459258;4127.731697439657;4139.648129908931;3885.514310711161;4098.672106231554;4114.670826333886;4137.54487006429;4143.772092534879;4761.428628564595;4770.76146192689;4778.068276686013;4779;4788.647946437438;5548.77804887808;5755.453231942959;5776.277965254343;5803.854903627359;5830.2129212555765;1440.7550716378241;1492.8059352284133;1493.9850601494063;1494.9103053816805;1498.1086253679043;978.1196922769565;981.0630847540582;991.8413053911339;1002.2683441088046;1008.7478130467383;5928.703564821742;5931.406859314034;5959.480332515181;5967.880642387215;6001.379627500551;6206.503479721611;6296.968515157487;6311.400416960381;6333.638267772451;6362.872742545123;5369.9928040297555;5644.250282412465;5665.413684168439;5701.771929122789;5991.101334799728;3408.454647256427;3421.2473255883665;3424.383610950053;3432.5194472773833;3454.378211921828;6470.6441145737435;6606.8017959461295;6630.86738265232;6691.631960242136;6718.865622687519;6466.191726034246;6513.2509761377105;6529.412352888263;6532.020196970492;6572;6502.317256687979;6609.966950165219;6616.239132499728;6617.66911654425;6619.669016549154;6360.809175724736;6382.3617638237665;6386.393292637192;6388.1376014237485;6397.328280530477;6265.49876009909;6294.276158241861;6320.345454909041;6336.58812177207;6355.586886852339;5886.6173698709445;6194.318624951342;6199.380061993765;6240.968795155995;6252.687365631791;4036.3541833307704;4054.6350828425584;4072.6538244248386;4090.263752524574;4110.794460276914;5321.680699158062;5967.41863406189;5997.850053748692;6013.278406591237;6034.939650603438;5820.359760426434;5835.533157347317;5837.737301821343;5851.239338885917;5864.824055278348;5810.448007439287;5817.214676018683;5837.211976383303;5839.386864379179;5843.824685259449;5622.718864056863;5748.281464816898;5755.496359083644;5764.711764411679;5780.323706125177;5824.039033559585;5843.328017278068;5852.795152169824;5877.500412464989;5910.438508341701;5524.723763811874;5681.403452637579;5703.287089113861;5705.686187259741;5724.79963201286 11:29:38 INFO - PROCESS | 11626 | |5;yui.html;765.3112199020877;835.1189495082688;857.4726543175949;858.9914100858989;875.1685898396524;13047.934760326198;13366;13373.933130334359;13403.1958082515;13456.1926284423;12461.127721059529;12943.741125177514;12944;12952.35238238089;12952.546660866872;701.554512884317;817.4400535633081;830.9376796740254;845.4504572028169;852.7271273192581;839.2866063845744;844.3667249562828;847.1697736218509;848.499385362636;852.7100785732849;887.6937456577502;891.1845661220009;891.7681402835277;891.7993451473399;892.629558733125;880.9603567839431;885.8759773631697;887.4630848336792;888.3026823943173;888.7156110044788;641.397086738469;644.7195469970578;646.5797231799321;648.9123968264271;649.0815496073051;12790;12792.48830046797;12817.935910320482;12843.100982931199;12874.935625321814;13903.652408689763;13929.512467063705;14186.785514429697;14510.129392236395;14600;11425.31448113116;11472.77054458906;11603.129765267555;11775.823362649568;11810.822837657443;3886.261610294035;3918.960810391888;3953.169834334793;3982.183652351261;4080.3063479208604;52791.736041319935;53398.466015339734;54151.45848541504;54533.909321813735;54597;10479.84280235797;10648.281621250839;10794.190435717353;10843.945780271206;10880.510377033053;18719.625607487775;18756.90621546911;18833.717494237597;19055.714164287547;19058.14238359277;8189.26296633294;8229.177082291842;8310.667573297122;8326.542040187727;8436.493810371398;4564.42944631921;4731.148393289172;4753.215719406259;4754.595859351994;4890.217565189624;3369.814660193713;3428.0572842468023;3428.6571342866014;3429.091290807938;3444.844981975817;600.9188759517409;614.1954040207343;617.8177437655902;654.4110300729286;654.5418207254845;637.789529455288;649.4122818848949;777.9183185765527;794.7417089445953;816.7958010497375;9219.539023048821;9929;9945.950270248748;10049.547770350351;10068.194544436428;645.6255371884296;685.8525417035362;694.7186389512154;791.5962858941981;807.7294106474279;4477.507474177838;4498.5501449854755;4769.73766442849;4793.400824896888;4835.975820120877;69409.3059069413;71842;72401.63799180971;73151.26848731445;73312;59642.40357596368;60969.39030609726;61385;61386.38613613896;63673.36326636767;48335.999999999294;57363.42636573665;57512.42487575071;58565.121523177186;58937.115943260884;9414.811703766023;9454.810903782023;9566.234701223746;9788.816180844678;10027.649032284127;33473.83263083669;34183.82908085443;34662;35071.47392789161;35462;44889.77555112204;46256.768716156206;46642.300365495226;46794.29808552942;46935.29597056115;12365.938170309091;12591.811122832982;12628.179168354027;12675.683107922376;12897.806532901826;12989.740205196033;13071.869281307065;13085.869141308465;13420.798688019882;13489.2580908051;12843;12897.161684490478;12938.805917911051;13233.80149297742;13292.401841916944;13184.2089474628;13271.071025028155;13423.328833558087;13881.722365552834;13883.652908677364;11184.664460066211;12761.248833932035;13357.732845342844;13498.32508374597;13979.720405592034;10694.304870183412;10758.300710453796;10922.344659320466;11163.497642606264;11243.887561124284;10561.735956601147;10927.125829933428;10998.835017474905;11139.275947063416;11185.496652650487 11:29:38 INFO - PROCESS | 11626 | __end_tp_report 11:29:38 INFO - PROCESS | 11626 | __start_cc_report 11:29:38 INFO - PROCESS | 11626 | _x_x_mozilla_cycle_collect,342 11:29:38 INFO - PROCESS | 11626 | __end_cc_report 11:29:38 INFO - PROCESS | 11626 | __startTimestamp1461868178874__endTimestamp 11:29:38 INFO - PROCESS | 11626 | 11:29:38 INFO - PROCESS | 11626 | ------- Summary: start ------- 11:29:38 INFO - PROCESS | 11626 | Number of tests: 6 11:29:38 INFO - PROCESS | 11626 | 11:29:38 INFO - PROCESS | 11626 | [#0] dojo.html Cycles:165 Average:5355.73 Median:5604.00 stddev:707.29 (12.6%) stddev-sans-first:687.37 11:29:38 INFO - PROCESS | 11626 | Values: 3120.3 3155.1 3184.9 3222.2 3233.2 5320.4 5410.9 5436.1 5452.0 5455.3 5316.5 5329.8 5347.7 5418.1 5485.2 4884.9 4908.8 4970.4 4973.2 5019.2 675.6 4890.4 4951.6 4956.0 4988.6 4907.3 4911.5 4936.5 4970.9 5003.3 4822.1 4967.3 5031.6 5034.3 5105.7 4852.4 4874.1 4927.6 4927.7 4939.7 5300.2 5332.1 5339.7 5344.5 5359.4 5695.6 5772.5 5795.7 5797.4 5797.4 5720.8 5762.2 5781.5 5782.5 5826.4 5328.5 5353.2 5362.5 5372.0 5372.1 5791.5 5841.4 5876.6 5890.7 5929.2 5630.7 5643.9 5647.1 5660.4 5665.5 5710.0 5732.2 5734.2 5743.2 5755.6 5649.1 5712.7 5750.1 5789.3 5820.8 5718.7 5730.4 5776.0 5815.9 5841.2 4398.6 4401.4 4408.4 4419.6 4448.4 3878.7 3886.0 3927.2 3948.6 3976.2 5564.2 5567.8 5586.1 5598.3 5602.6 5747.3 5849.8 5853.3 5862.6 5910.9 5416.3 5493.8 5510.4 5585.2 5613.8 4945.7 5003.7 5005.4 5010.2 5019.6 6002.0 6012.7 6050.7 6078.3 6090.6 5782.2 5847.6 5855.8 5861.7 5888.3 6021.6 6031.2 6043.9 6047.8 6055.5 5569.4 5601.9 5714.8 5719.5 5756.1 5775.5 5775.8 5776.7 5780.6 5813.7 5599.6 5608.3 5626.0 5627.6 5633.4 5538.6 5561.2 5604.0 5620.2 5701.1 5514.1 5560.9 5565.0 5634.0 5642.8 5753.5 5757.4 5767.9 5775.0 5782.0 5540.9 5604.9 5625.8 5640.3 5644.9 11:29:38 INFO - PROCESS | 11626 | 11:29:38 INFO - PROCESS | 11626 | [#1] ext.html Cycles:180 Average:57221.32 Median:22892.08 stddev:105974.13 (462.9%) stddev-sans-first:106186.17 11:29:38 INFO - PROCESS | 11626 | Values: 611.5 648.0 655.4 663.7 671.9 37100.8 38279.4 38316.4 38609.0 38684.4 40494.0 41046.4 41243.6 41332.0 41393.6 8557.6 8710.6 8711.3 8747.0 8777.3 8572.3 8727.5 8742.8 8898.8 8908.7 8919.2 8925.8 8970.3 8976.8 8992.1 8768.1 8885.4 8925.1 8937.1 8939.0 8745.3 8845.3 8848.5 8876.0 8892.2 40870.8 41476.0 41531.6 41532.2 41582.6 3510.1 3514.9 3536.9 3550.2 3661.1 32065.5 32484.2 32535.8 32658.0 32676.5 4190.6 4291.6 4306.2 4401.8 4419.7 312583.0 312749.0 316624.4 316809.0 316862.0 59972.7 60818.0 60908.4 61003.4 61026.4 60678.0 62143.4 62160.0 62358.4 62419.0 17589.2 17628.6 17649.1 17720.3 17814.8 13731.2 13787.6 13805.7 13812.9 13886.7 19703.0 19828.8 20156.9 20173.7 20179.0 7241.9 7336.8 7426.9 7613.4 7641.3 856.4 871.0 918.4 1050.9 1093.8 29358.6 31312.0 31669.8 32768.2 32849.0 1108.0 1118.5 1131.2 1172.8 1174.2 16037.8 16192.8 16208.3 16286.3 16709.1 442224.0 445205.0 445648.0 450220.0 450312.0 55082.0 55764.7 56068.2 56171.0 56224.7 1322.9 1334.8 1335.9 1381.6 1391.5 29630.7 29805.7 30202.2 30562.2 31330.2 56506.0 57276.0 57725.7 57728.0 57971.0 424862.0 431370.0 432079.0 433255.0 433575.0 43574.6 43820.0 44707.3 44902.1 45017.3 48749.0 48783.3 48893.5 49460.0 49873.3 40629.8 41260.2 41503.4 41593.5 42130.4 41019.8 41223.0 41253.4 42252.6 42433.0 41197.6 41388.4 42233.8 42600.4 42874.1 34584.8 36979.0 37420.3 37927.0 37970.0 28666.7 28861.3 28994.6 29153.7 29265.1 11:29:38 INFO - PROCESS | 11626 | 11:29:38 INFO - PROCESS | 11626 | [#2] jquery.html Cycles:180 Average:27531.93 Median:37219.66 stddev:78417.85 (210.7%) stddev-sans-first:78611.36 11:29:38 INFO - PROCESS | 11626 | Values: 394.3 394.7 396.1 402.4 404.3 13842.4 18006.3 18625.9 18732.5 18776.3 17692.1 17774.7 18076.9 19247.3 19250.8 5456.2 5584.7 5691.1 5713.0 5771.6 5536.1 5596.8 5617.0 5642.7 5658.0 5164.4 5381.7 5578.2 5584.2 5620.4 5218.2 5378.8 5383.1 5394.9 5431.6 5362.4 5415.9 5440.8 5455.3 5457.2 13051.7 13245.1 17158.4 23023.0 23045.2 2191.4 2192.4 2369.6 2432.5 2729.2 9015.0 9233.3 9255.6 9675.0 9677.9 1875.9 1881.4 1973.2 2018.4 2160.0 69814.7 73254.6 73448.9 77062.0 85540.6 9784.3 10048.5 10076.5 10233.2 10610.7 14312.1 14344.1 14486.6 14486.9 14508.0 7931.1 8106.1 8216.5 8339.4 8345.6 8125.0 8200.4 8206.8 8341.2 8350.0 4385.1 4402.6 4441.1 4456.3 4519.2 1836.5 1861.9 1881.3 1888.2 1900.3 940.7 950.4 1020.0 1105.5 1109.0 17805.2 17895.6 18595.7 19008.7 19660.3 1004.1 1016.3 1017.3 1033.8 1056.2 10692.7 10806.5 10893.2 10901.0 10928.9 459920.0 471772.0 477852.0 484889.0 490883.0 61073.7 70581.6 77237.2 89961.6 94588.0 1009.7 1033.9 1040.9 1047.5 1071.6 17139.5 17330.9 17374.4 17430.0 17548.2 36897.1 37542.2 37631.6 38559.2 38852.2 52283.0 52366.7 52572.0 52930.2 56738.4 8017.8 8114.4 8195.9 8204.9 8315.9 8864.4 12388.9 13011.5 13021.9 13877.3 11949.4 12231.2 12265.3 12340.3 12464.7 11474.5 11808.6 11866.3 11897.8 12105.6 10877.6 11606.0 12207.5 12444.0 12613.6 8054.6 8127.0 8212.5 8235.2 8407.7 8356.3 10456.3 11822.0 11863.0 11946.3 11:29:38 INFO - PROCESS | 11626 | 11:29:38 INFO - PROCESS | 11626 | [#3] mootools.html Cycles:180 Average:5622.52 Median:4512.05 stddev:3166.23 (70.2%) stddev-sans-first:3160.10 11:29:38 INFO - PROCESS | 11626 | Values: 1519.5 1545.7 1670.8 1796.1 1802.4 7556.0 7853.6 8126.1 8152.2 8186.9 8227.1 8245.1 8266.7 8272.3 8313.8 2274.3 2317.9 2318.4 2325.2 2340.0 2257.5 2287.8 2325.9 2326.6 2329.7 2284.9 2298.7 2301.1 2315.9 2344.9 2231.5 2245.3 2255.5 2265.4 2269.1 2259.7 2270.4 2275.9 2281.2 2282.1 8464.1 8532.2 8542.1 8570.4 8584.5 4862.4 5051.5 5084.5 5088.3 5088.9 3181.1 3211.6 3377.7 3388.0 3404.5 1008.2 1020.5 1047.4 1049.6 1074.1 11706.2 11780.8 11816.7 11877.3 11884.9 4331.6 4376.5 4503.7 4506.5 4517.6 9974.9 10069.6 10076.7 10097.1 10148.7 5022.5 5037.9 5040.7 5061.1 5086.5 4012.7 4556.5 4580.1 4669.3 4669.9 3736.7 3761.6 3843.8 3855.6 3868.5 1209.2 1209.9 1214.4 1222.7 1227.9 985.9 996.0 1006.5 1010.5 1020.3 5760.6 5881.9 5893.7 6000.6 6085.5 427.2 428.2 429.9 1051.0 1058.0 4246.8 4286.2 4312.4 4312.4 4316.5 10189.1 10412.0 10556.8 10588.9 10630.0 10359.9 10450.8 10469.2 10495.7 10515.3 7366.8 7682.0 7682.3 7698.5 7908.6 5817.9 6005.7 6088.6 6125.1 6184.5 9552.8 9742.7 9772.4 9846.4 9972.5 9965.1 10069.0 10071.6 10278.1 10322.3 7035.8 7438.8 7560.4 7634.7 7817.9 8271.0 8313.5 8316.8 8353.7 8366.6 7251.6 7306.4 7500.0 7509.3 7568.9 7324.7 7396.9 7398.9 7511.0 7515.2 6680.0 6781.8 6826.8 6828.6 6833.6 4956.8 5097.9 5160.0 5177.8 5182.6 5099.5 5135.9 5143.5 5181.2 5237.8 11:29:38 INFO - PROCESS | 11626 | 11:29:38 INFO - PROCESS | 11626 | [#4] prototype.html Cycles:180 Average:4927.03 Median:5552.82 stddev:1519.51 (27.4%) stddev-sans-first:1483.60 11:29:38 INFO - PROCESS | 11626 | Values: 302.5 316.0 320.0 320.1 322.7 5328.7 5378.9 5389.5 5541.6 5556.9 5450.8 5454.8 5469.3 5479.9 5504.0 4171.3 4193.3 4199.3 4200.7 4213.9 4148.0 4224.4 4232.5 4249.6 4257.2 4071.9 4126.2 4161.9 4198.4 4211.7 4117.4 4118.9 4127.8 4139.2 4153.0 3693.8 3700.6 3705.0 3720.2 3724.7 5119.4 5201.5 5230.2 5244.2 5301.8 4760.3 4788.1 4792.0 4795.8 4799.4 5455.5 5662.3 5705.3 5718.9 5722.0 4016.8 4030.3 4030.4 4033.1 4042.0 5941.7 6006.1 6046.6 6054.0 6066.7 4099.5 4114.4 4118.8 4127.7 4139.6 3885.5 4098.7 4114.7 4137.5 4143.8 4761.4 4770.8 4778.1 4779.0 4788.6 5548.8 5755.5 5776.3 5803.9 5830.2 1440.8 1492.8 1494.0 1494.9 1498.1 978.1 981.1 991.8 1002.3 1008.7 5928.7 5931.4 5959.5 5967.9 6001.4 6206.5 6297.0 6311.4 6333.6 6362.9 5370.0 5644.3 5665.4 5701.8 5991.1 3408.5 3421.2 3424.4 3432.5 3454.4 6470.6 6606.8 6630.9 6691.6 6718.9 6466.2 6513.3 6529.4 6532.0 6572.0 6502.3 6610.0 6616.2 6617.7 6619.7 6360.8 6382.4 6386.4 6388.1 6397.3 6265.5 6294.3 6320.3 6336.6 6355.6 5886.6 6194.3 6199.4 6241.0 6252.7 4036.4 4054.6 4072.7 4090.3 4110.8 5321.7 5967.4 5997.9 6013.3 6034.9 5820.4 5835.5 5837.7 5851.2 5864.8 5810.4 5817.2 5837.2 5839.4 5843.8 5622.7 5748.3 5755.5 5764.7 5780.3 5824.0 5843.3 5852.8 5877.5 5910.4 5524.7 5681.4 5703.3 5705.7 5724.8 11:29:38 INFO - PROCESS | 11626 | 11:29:38 INFO - PROCESS | 11626 | [#5] yui.html Cycles:180 Average:15491.46 Median:4531.49 stddev:18674.85 (412.1%) stddev-sans-first:18694.49 11:29:38 INFO - PROCESS | 11626 | Values: 765.3 835.1 857.5 859.0 875.2 13047.9 13366.0 13373.9 13403.2 13456.2 12461.1 12943.7 12944.0 12952.4 12952.5 701.6 817.4 830.9 845.5 852.7 839.3 844.4 847.2 848.5 852.7 887.7 891.2 891.8 891.8 892.6 881.0 885.9 887.5 888.3 888.7 641.4 644.7 646.6 648.9 649.1 12790.0 12792.5 12817.9 12843.1 12874.9 13903.7 13929.5 14186.8 14510.1 14600.0 11425.3 11472.8 11603.1 11775.8 11810.8 3886.3 3919.0 3953.2 3982.2 4080.3 52791.7 53398.5 54151.5 54533.9 54597.0 10479.8 10648.3 10794.2 10843.9 10880.5 18719.6 18756.9 18833.7 19055.7 19058.1 8189.3 8229.2 8310.7 8326.5 8436.5 4564.4 4731.1 4753.2 4754.6 4890.2 3369.8 3428.1 3428.7 3429.1 3444.8 600.9 614.2 617.8 654.4 654.5 637.8 649.4 777.9 794.7 816.8 9219.5 9929.0 9946.0 10049.5 10068.2 645.6 685.9 694.7 791.6 807.7 4477.5 4498.6 4769.7 4793.4 4836.0 69409.3 71842.0 72401.6 73151.3 73312.0 59642.4 60969.4 61385.0 61386.4 63673.4 48336.0 57363.4 57512.4 58565.1 58937.1 9414.8 9454.8 9566.2 9788.8 10027.6 33473.8 34183.8 34662.0 35071.5 35462.0 44889.8 46256.8 46642.3 46794.3 46935.3 12365.9 12591.8 12628.2 12675.7 12897.8 12989.7 13071.9 13085.9 13420.8 13489.3 12843.0 12897.2 12938.8 13233.8 13292.4 13184.2 13271.1 13423.3 13881.7 13883.7 11184.7 12761.2 13357.7 13498.3 13979.7 10694.3 10758.3 10922.3 11163.5 11243.9 10561.7 10927.1 10998.8 11139.3 11185.5 11:29:38 INFO - PROCESS | 11626 | -------- Summary: end -------- 11:29:38 INFO - PROCESS | 11626 | 11:29:39 INFO - TEST-INFO | 11626: exit 0 11:29:39 INFO - TEST-OK | dromaeo_css | took 1110041ms 11:29:39 INFO - TEST-START | kraken 11:29:39 INFO - operating with platform_type : linux_ 11:29:39 INFO - Initialising browser for kraken test... 11:29:39 INFO - TEST-INFO | started process 11856 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS8Uice/profile http://localhost:53071/getInfo.html) 11:29:43 INFO - PROCESS | 11856 | 11:29:43 INFO - PROCESS | 11856 | (process:11925): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 11:29:44 INFO - PROCESS | 11856 | __metrics Screen width/height:1600/1200 11:29:44 INFO - PROCESS | 11856 | colorDepth:24 11:29:44 INFO - PROCESS | 11856 | Browser inner width/height: 1024/697 11:29:44 INFO - PROCESS | 11856 | __metrics 11:29:44 ERROR - PROCESS | 11856 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 11:29:45 INFO - TEST-INFO | 11856: exit 0 11:29:45 INFO - Browser initialized. 11:29:45 INFO - Running cycle 1/1 for kraken test... 11:29:45 INFO - TEST-INFO | started process 11976 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS8Uice/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:47 INFO - PROCESS | 11976 | 11:29:47 INFO - PROCESS | 11976 | (process:12038): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 11:29:57 INFO - PROCESS | 11976 | RSS: Main: 164704256 11:29:57 INFO - PROCESS | 11976 | 11:30:00 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53071/tests/kraken/driver.html?testName=audio-beat-detection) 11:30:00 INFO - PROCESS | 11976 | RSS: Main: 169615360 11:30:00 INFO - PROCESS | 11976 | 11:30:03 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53071/tests/kraken/driver.html?testName=audio-dft) 11:30:04 INFO - PROCESS | 11976 | RSS: Main: 172908544 11:30:04 INFO - PROCESS | 11976 | 11:30:07 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53071/tests/kraken/driver.html?testName=audio-fft) 11:30:07 INFO - PROCESS | 11976 | RSS: Main: 164872192 11:30:07 INFO - PROCESS | 11976 | 11:30:10 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53071/tests/kraken/driver.html?testName=audio-oscillator) 11:30:10 INFO - PROCESS | 11976 | RSS: Main: 164098048 11:30:10 INFO - PROCESS | 11976 | 11:30:12 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53071/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:30:13 INFO - PROCESS | 11976 | RSS: Main: 164462592 11:30:13 INFO - PROCESS | 11976 | 11:30:16 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53071/tests/kraken/driver.html?testName=imaging-darkroom) 11:30:17 INFO - PROCESS | 11976 | RSS: Main: 165003264 11:30:17 INFO - PROCESS | 11976 | 11:30:20 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53071/tests/kraken/driver.html?testName=imaging-desaturate) 11:30:21 INFO - PROCESS | 11976 | RSS: Main: 165240832 11:30:21 INFO - PROCESS | 11976 | 11:30:24 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53071/tests/kraken/driver.html?testName=json-parse-financial) 11:30:24 INFO - PROCESS | 11976 | RSS: Main: 166076416 11:30:24 INFO - PROCESS | 11976 | 11:30:26 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53071/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:30:26 INFO - PROCESS | 11976 | RSS: Main: 165814272 11:30:26 INFO - PROCESS | 11976 | 11:30:28 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53071/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:30:29 INFO - PROCESS | 11976 | RSS: Main: 165466112 11:30:29 INFO - PROCESS | 11976 | 11:30:31 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53071/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:30:32 INFO - PROCESS | 11976 | RSS: Main: 165978112 11:30:32 INFO - PROCESS | 11976 | 11:30:34 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:30:35 INFO - PROCESS | 11976 | RSS: Main: 166457344 11:30:35 INFO - PROCESS | 11976 | 11:30:38 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:30:38 INFO - PROCESS | 11976 | RSS: Main: 166674432 11:30:38 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | Cycle 1(1): loaded http://localhost:53071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53071/tests/kraken/driver.html?testName=ai-astar) 11:30:40 INFO - PROCESS | 11976 | RSS: Main: 167538688 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | __start_tp_report 11:30:40 INFO - PROCESS | 11976 | _x_x_mozilla_page_load 11:30:40 INFO - PROCESS | 11976 | _x_x_mozilla_page_load_details 11:30:40 INFO - PROCESS | 11976 | |i|pagename|runs| 11:30:40 INFO - PROCESS | 11976 | |0;ai-astar;114;107;113;112;112;124;111;112;114;114 11:30:40 INFO - PROCESS | 11976 | |1;audio-beat-detection;157;131;153;192;133;165;156;130;187;130 11:30:40 INFO - PROCESS | 11976 | |2;audio-dft;162;163;163;163;163;165;170;162;166;163 11:30:40 INFO - PROCESS | 11976 | |3;audio-fft;77;77;75;154;78;73;74;76;160;73 11:30:40 INFO - PROCESS | 11976 | |4;audio-oscillator;93;93;145;93;94;94;151;94;94;141 11:30:40 INFO - PROCESS | 11976 | |5;imaging-gaussian-blur;118;118;118;119;117;117;118;119;119;118 11:30:40 INFO - PROCESS | 11976 | |6;imaging-darkroom;148;148;146;147;146;147;147;147;147;148 11:30:40 INFO - PROCESS | 11976 | |7;imaging-desaturate;97;96;95;96;96;95;95;96;95;95 11:30:40 INFO - PROCESS | 11976 | |8;json-parse-financial;58;59;63;58;57;58;63;73;55;58 11:30:40 INFO - PROCESS | 11976 | |9;json-stringify-tinderbox;61;62;67;62;63;62;62;62;62;64 11:30:40 INFO - PROCESS | 11976 | |10;stanford-crypto-aes;77;89;77;79;79;77;78;78;80;79 11:30:40 INFO - PROCESS | 11976 | |11;stanford-crypto-ccm;130;126;125;124;128;133;132;123;160;126 11:30:40 INFO - PROCESS | 11976 | |12;stanford-crypto-pbkdf2;178;164;165;165;167;163;165;173;166;164 11:30:40 INFO - PROCESS | 11976 | |13;stanford-crypto-sha256-iterative;70;67;68;71;72;73;67;75;69;68 11:30:40 INFO - PROCESS | 11976 | __end_tp_report 11:30:40 INFO - PROCESS | 11976 | __start_cc_report 11:30:40 INFO - PROCESS | 11976 | _x_x_mozilla_cycle_collect,843 11:30:40 INFO - PROCESS | 11976 | __end_cc_report 11:30:40 INFO - PROCESS | 11976 | __startTimestamp1461868240624__endTimestamp 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | ------- Summary: start ------- 11:30:40 INFO - PROCESS | 11976 | Number of tests: 14 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#0] ai-astar Cycles:10 Average:113.30 Median:113.50 stddev:4.30 (3.8%) stddev-sans-first:4.55 11:30:40 INFO - PROCESS | 11976 | Values: 114.0 107.0 113.0 112.0 112.0 124.0 111.0 112.0 114.0 114.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#1] audio-beat-detection Cycles:10 Average:153.40 Median:156.50 stddev:23.06 (14.7%) stddev-sans-first:24.42 11:30:40 INFO - PROCESS | 11976 | Values: 157.0 131.0 153.0 192.0 133.0 165.0 156.0 130.0 187.0 130.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#2] audio-dft Cycles:10 Average:164.00 Median:163.00 stddev:2.45 (1.5%) stddev-sans-first:2.49 11:30:40 INFO - PROCESS | 11976 | Values: 162.0 163.0 163.0 163.0 163.0 165.0 170.0 162.0 166.0 163.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#3] audio-fft Cycles:10 Average:91.70 Median:75.50 stddev:34.49 (45.7%) stddev-sans-first:36.17 11:30:40 INFO - PROCESS | 11976 | Values: 77.0 77.0 75.0 154.0 78.0 73.0 74.0 76.0 160.0 73.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#4] audio-oscillator Cycles:10 Average:109.20 Median:93.50 stddev:25.28 (27.0%) stddev-sans-first:26.12 11:30:40 INFO - PROCESS | 11976 | Values: 93.0 93.0 145.0 93.0 94.0 94.0 151.0 94.0 94.0 141.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#5] imaging-gaussian-blur Cycles:10 Average:118.10 Median:118.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 11:30:40 INFO - PROCESS | 11976 | Values: 118.0 118.0 118.0 119.0 117.0 117.0 118.0 119.0 119.0 118.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 11:30:40 INFO - PROCESS | 11976 | Values: 148.0 148.0 146.0 147.0 146.0 147.0 147.0 147.0 147.0 148.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.53 11:30:40 INFO - PROCESS | 11976 | Values: 97.0 96.0 95.0 96.0 96.0 95.0 95.0 96.0 95.0 95.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:58.50 stddev:5.14 (8.8%) stddev-sans-first:5.39 11:30:40 INFO - PROCESS | 11976 | Values: 58.0 59.0 63.0 58.0 57.0 58.0 63.0 73.0 55.0 58.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#9] json-stringify-tinderbox Cycles:10 Average:62.70 Median:62.00 stddev:1.70 (2.7%) stddev-sans-first:1.69 11:30:40 INFO - PROCESS | 11976 | Values: 61.0 62.0 67.0 62.0 63.0 62.0 62.0 62.0 62.0 64.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:79.00 stddev:3.56 (4.5%) stddev-sans-first:3.68 11:30:40 INFO - PROCESS | 11976 | Values: 77.0 89.0 77.0 79.0 79.0 77.0 78.0 78.0 80.0 79.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#11] stanford-crypto-ccm Cycles:10 Average:130.70 Median:129.00 stddev:10.82 (8.4%) stddev-sans-first:11.48 11:30:40 INFO - PROCESS | 11976 | Values: 130.0 126.0 125.0 124.0 128.0 133.0 132.0 123.0 160.0 126.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:165.50 stddev:4.76 (2.9%) stddev-sans-first:2.95 11:30:40 INFO - PROCESS | 11976 | Values: 178.0 164.0 165.0 165.0 167.0 163.0 165.0 173.0 166.0 164.0 11:30:40 INFO - PROCESS | 11976 | 11:30:40 INFO - PROCESS | 11976 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:70.50 stddev:2.71 (3.8%) stddev-sans-first:2.87 11:30:40 INFO - PROCESS | 11976 | Values: 70.0 67.0 68.0 71.0 72.0 73.0 67.0 75.0 69.0 68.0 11:30:40 INFO - PROCESS | 11976 | -------- Summary: end -------- 11:30:40 INFO - PROCESS | 11976 | 11:30:41 INFO - TEST-INFO | 11976: exit 0 11:30:41 INFO - TEST-OK | kraken | took 61954ms 11:30:41 INFO - SUITE-END | took 1171s 11:30:41 INFO - Completed test suite (00:19:32) 11:30:41 INFO - javascript benchmark 11:30:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3120.251139726465, 3155.0692545699017, 3184.9012090828655, 3222.210558413189, 3233.2401885556906, 5320.388155362135, 5410.864728381793, 5436.075867102592, 5452.0458919689045, 5455.318085239345, 5316.548093412064, 5329.760160792764, 5347.652402593829, 5418.106012507941, 5485.177223416489, 4884.877878053042, 4908.754562271872, 4970.353853998975, 4973.204287314031, 5019.222020586815, 675.6440744431693, 4890.388701412324, 4951.628627852908, 4956.0, 4988.575971042447, 4907.312976183337, 4911.5088491150955, 4936.5063493650705, 4970.925436118443, 5003.299538064655, 4822.132016237076, 4967.329410529568, 5031.572316353115, 5034.320366750479, 5105.744712764384, 4852.441969173555, 4874.122657921573, 4927.630427717935, 4927.679700819435, 4939.728314942677, 5300.231466437345, 5332.066888294572, 5339.652922560022, 5344.4630604755, 5359.38367087786, 5695.629784064064, 5772.538196944234, 5795.739191736382, 5797.420257974169, 5797.420257974211, 5720.799772007959, 5762.222100016528, 5781.4507621775865, 5782.508486778629, 5826.446487583673, 5328.5470734987575, 5353.170258609922, 5362.517373436371, 5372.0, 5372.059889519358, 5791.478766910914, 5841.415858414124, 5876.559258055578, 5890.705464726747, 5929.229200203946, 5630.718464076862, 5643.943560564424, 5647.0964645656495, 5660.433956604389, 5665.518430933417, 5709.9714501427225, 5732.226149469851, 5734.197212390269, 5743.2246646703, 5755.59710820247, 5649.12438572022, 5712.714364281769, 5750.137479378127, 5789.334226563915, 5820.796272130455, 5718.656880587178, 5730.398308177665, 5776.046952252916, 5815.941840581625, 5841.182234487175, 4398.626116780109, 4401.40581021558, 4408.360787685831, 4419.558044195554, 4448.4439445069365, 3878.6897048236074, 3885.9805700971315, 3927.1752931884625, 3948.58539853317, 3976.165005348851, 5564.1931919871395, 5567.805126820542, 5586.050371436865, 5598.3282006159525, 5602.579806514446, 5747.252857128547, 5849.824505264849, 5853.268341457318, 5862.618929769551, 5910.940890591125, 5416.322959630046, 5493.7802487900035, 5510.421405752339, 5585.1901474286005, 5613.803516876889, 4945.727984960789, 5003.674761140514, 5005.424376196711, 5010.223415370623, 5019.598432125421, 6002.0, 6012.729427175787, 6050.727717252734, 6078.300995385499, 6090.604110732788, 5782.2483077199695, 5847.619904706181, 5855.765769369348, 5861.677607731615, 5888.263967004124, 6021.638701677914, 6031.185789918305, 6043.909341359795, 6047.818565443044, 6055.485283750931, 5569.387367389503, 5601.887962240651, 5714.79998200061, 5719.5138413235345, 5756.107803290497, 5775.480206781242, 5775.797847075332, 5776.653400795965, 5780.5953583248765, 5813.738381772915, 5599.552035837205, 5608.327000759935, 5626.0, 5627.634203776742, 5633.408492108433, 5538.6399884007415, 5561.221429000024, 5604.0, 5620.1569764534015, 5701.059325211294, 5514.145307477347, 5560.860978475571, 5565.026120428828, 5633.985882541182, 5642.830715078554, 5753.5109515689965, 5757.424257574209, 5767.9134812977, 5775.018246898121, 5782.045962416154, 5540.944590554042, 5604.915926261027, 5625.76233228689, 5640.323161220515, 5644.915326270027], "value": 5310.600077389549, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.5107913669063, 648.0344287012351, 655.429776094797, 663.7212370804264, 671.8857794174993, 37100.814495927516, 38279.42580861289, 38316.425253621186, 38609.0, 38684.41973370398, 40494.0, 41046.384304235464, 41243.58756412443, 41332.0, 41393.58606413935, 8557.614907329169, 8710.608022638997, 8711.259542938858, 8746.956265218665, 8777.341699372573, 8572.271356934669, 8727.519986400745, 8742.781430464225, 8898.777530561756, 8908.688195913144, 8919.24186444153, 8925.821483570324, 8970.327225458117, 8976.820463590724, 8992.145746154105, 8768.123187681245, 8885.378023538355, 8925.107489251088, 8937.106289371075, 8939.0, 8745.300375969908, 8845.292376609856, 8848.46909185451, 8875.955620221923, 8892.199702026848, 40870.79564602158, 41476.0, 41531.58468415338, 41532.169356613, 41582.58417415847, 3510.1049232445816, 3514.947275790865, 3536.9115772105647, 3550.2366991097037, 3661.0847288177956, 32065.51901721476, 32484.18789530257, 32535.837320813243, 32658.0, 32676.509852352232, 4190.622843944059, 4291.644682481998, 4306.160298741746, 4401.801918913657, 4419.734815911023, 312583.0, 312749.0, 316624.41687791643, 316809.0, 316862.0, 59972.700136499036, 60818.0, 60908.39091609116, 61003.38996610066, 61026.38973610296, 60678.0, 62143.37856621466, 62160.0, 62358.37641623616, 62419.0, 17589.20848561818, 17628.559286017953, 17649.117544122742, 17720.291188352578, 17814.821851781577, 13731.24478153711, 13787.586372408843, 13805.654858628615, 13812.86187138136, 13886.652833679036, 19703.0, 19828.801711982695, 20156.89921550383, 20173.697394539093, 20178.991050447417, 7241.927580724126, 7336.816579585553, 7426.888596671054, 7613.352865006425, 7641.2740789624895, 856.3834039491556, 871.0331531999476, 918.4122161816442, 1050.8791488978713, 1093.8413929980109, 29358.559621605695, 31312.0, 31669.841650791597, 32768.18079547983, 32849.0, 1107.983380249297, 1118.5134466507095, 1131.1572878205625, 1172.8299396587447, 1174.2426135142955, 16037.759433608506, 16192.75710864338, 16208.270627821541, 16286.348546058261, 16709.081000544844, 442224.0, 445205.0, 445648.0, 450220.0, 450312.0, 55082.0, 55764.72117639467, 56068.158977614556, 56171.0, 56224.71887640536, 1322.9206247625173, 1334.833145856768, 1335.8998075144402, 1381.647679841634, 1391.471240928461, 29630.703692962794, 29805.701942980293, 30202.24494387658, 30562.235944100685, 31330.216744580655, 56506.0, 57276.0, 57725.71137144287, 57728.0, 57971.0, 424862.0, 431370.0, 432079.0, 433255.0, 433575.0, 43574.56425435705, 43820.0, 44707.32939005983, 44902.10195796, 45017.32474012958, 48749.0, 48783.268250975554, 48893.51106489032, 49460.0, 49873.251901220785, 40629.79685101674, 41260.1747965033, 41503.37744933768, 41593.5360120851, 42130.368044478746, 41019.79490102531, 41223.0, 41253.38119928144, 42252.57747422609, 42433.0, 41197.58802411937, 41388.379174311805, 42233.788831055645, 42600.36099458449, 42874.14251714886, 34584.82707586446, 36979.0, 37420.251594967405, 37927.0, 37970.0, 28666.71333286724, 28861.27846803847, 28994.565081524215, 29153.708462915947, 29265.122046338645], "value": 20306.345867123295, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.26469634132343, 394.68030894975055, 396.09492310071494, 402.42453291792725, 404.2541510912367, 13842.377093030813, 18006.279748810048, 18625.88244705315, 18732.53168670784, 18776.342828001005, 17692.115394230303, 17774.73337899929, 18076.909615451903, 19247.326343577977, 19250.807491925112, 5456.236126942231, 5584.692841893694, 5691.08942569189, 5713.0, 5771.567132465082, 5536.086545719952, 5596.804111856086, 5617.045102332613, 5642.74607642655, 5657.971710141464, 5164.431912489623, 5381.730913454331, 5578.191162281467, 5584.21820945068, 5620.409856965021, 5218.191180367049, 5378.757955891994, 5383.084875571143, 5394.91907621386, 5431.646942948715, 5362.35651721792, 5415.918761218585, 5440.755166017539, 5455.263539422167, 5457.2359869618285, 13051.738965220642, 13245.139065960682, 17158.39945601898, 23023.0, 23045.19341823045, 2191.4331253153914, 2192.397090800027, 2369.6327069304284, 2432.525657496789, 2729.19488750819, 9015.0, 9233.261339092856, 9255.583498742573, 9675.0, 9677.90322096784, 1875.9047373535036, 1881.3603374852617, 1973.1811298311184, 2018.4045706516613, 2160.0387827416807, 69814.65092674555, 73254.63372683155, 73448.89826652658, 77062.0, 85540.57229713874, 9784.315097943076, 10048.497575121215, 10076.496175191358, 10233.232507561963, 10610.734731631772, 14312.141271523742, 14344.139351638934, 14486.637834054021, 14486.927565362317, 14508.0, 7931.127575966639, 8106.108328083906, 8216.507009579444, 8339.416240863082, 8345.582720864053, 8125.0499880028365, 8200.384971127189, 8206.794830129294, 8341.20758527939, 8350.0, 4385.122975404932, 4402.603765661106, 4441.089576636782, 4456.331550267421, 4519.163954668397, 1836.4766041678058, 1861.9162137703836, 1881.303917550515, 1888.225827410755, 1900.2873922279146, 940.6660635474445, 950.3870003847479, 1019.9898001020043, 1105.5135740274254, 1108.9944550277196, 17805.198766055557, 17895.552611184823, 18595.721064184047, 19008.714869276973, 19660.311889083812, 1004.0712341084468, 1016.2733645443543, 1017.2878984710584, 1033.7674023344837, 1056.2395075545594, 10692.679219623267, 10806.51370688321, 10893.237473376788, 10901.01890829829, 10928.890711092787, 459920.0, 471772.0, 477852.0, 484889.0, 490883.0, 61073.69463152745, 70581.64709176421, 77237.227627723, 89961.55019224861, 94588.0, 1009.7374682582434, 1033.8655974723238, 1040.8646875906084, 1047.4710271312938, 1071.5660157636325, 17139.485815425556, 17330.913345433695, 17374.39189628357, 17430.0, 17548.21033053541, 36897.07757306089, 37542.2491550162, 37631.62368376391, 38559.228815424096, 38852.2229555413, 52283.0, 52366.738166308925, 52572.0, 52930.206046908555, 56738.43261567497, 8017.8396432072195, 8114.391420643357, 8195.918040819515, 8204.917950820416, 8315.875261870955, 8864.379493435397, 12388.938055310025, 13011.544595939096, 13021.934890325489, 13877.306134693426, 11949.402529873298, 12231.204971676812, 12265.26408415498, 12340.259584424963, 12464.688382790502, 11474.541018359172, 11808.586699465477, 11866.28802271832, 11897.821532676844, 12105.576304829288, 10877.585913831468, 11606.0, 12207.511699532275, 12444.0, 12613.558525451564, 8054.597270136587, 8126.959365203136, 8212.548309843016, 8235.21765432283, 8407.747767566983, 8356.289715374018, 10456.32033917793, 11822.0, 11863.0, 11946.283223006647], "value": 8890.962871006757, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.4681861348527, 1545.6599548099418, 1670.799504059513, 1796.083997161449, 1802.3781795280624, 7556.0, 7853.5680537570415, 8126.065502467218, 8152.1847815218525, 8186.91813081869, 8227.095019547845, 8245.093039765621, 8266.669333226664, 8272.338212942965, 8313.792155196108, 2274.3404412720292, 2317.9420514487106, 2318.3508617587017, 2325.244295603927, 2340.028888011473, 2257.548490301946, 2287.794098531132, 2325.918592849242, 2326.5928462519078, 2329.6621989811465, 2284.931452056441, 2298.747137814839, 2301.137073597401, 2315.907363705458, 2344.8593084414906, 2231.5313784105356, 2245.2702871566726, 2255.4812393149587, 2265.4336415895946, 2269.0810221860174, 2259.706238189025, 2270.421042634134, 2275.89758460868, 2281.1673739085113, 2282.1213832674375, 8464.068952415291, 8532.189442003, 8542.060373358925, 8570.442921210164, 8584.484930904166, 4862.416510018785, 5051.494850514956, 5084.46613105626, 5088.287639730441, 5088.872778180538, 3181.0933883842995, 3211.6467188609236, 3377.662233776627, 3387.9830600847085, 3404.4723067924265, 1008.1581879130935, 1020.4999550220413, 1047.4029803012286, 1049.5854137615675, 1074.097757883374, 11706.180567360288, 11780.823287650692, 11816.704582385422, 11877.346745928884, 11884.940575297154, 4331.631811296075, 4376.452943382077, 4503.684742068024, 4506.481754598263, 4517.616002639812, 9974.850377244347, 10069.647562335284, 10076.69769906904, 10097.141742951784, 10148.69553913369, 5022.522860328336, 5037.899242015213, 5040.672356296829, 5061.063703214917, 5086.4659210783075, 4012.6789856811383, 4556.544345565417, 4580.0610874771255, 4669.276262179367, 4669.906601867943, 3736.6823819975607, 3761.567419746709, 3843.8078096095082, 3855.5566109897723, 3868.5357757069332, 1209.1535924852462, 1209.8808602043075, 1214.4352875912743, 1222.7004383925996, 1227.864934857165, 985.9457729824928, 995.9651412200683, 1006.4967516241879, 1010.5099026971913, 1020.3163880200284, 5760.567957403212, 5881.882362352814, 5893.734781934738, 6000.609960352563, 6085.5435842312, 427.1584977594135, 428.21850123524695, 429.9290617048152, 1050.99474502627, 1057.9682609521726, 4246.808893599796, 4286.249906266391, 4312.353147027909, 4312.417823593774, 4316.546762589883, 10189.133923616428, 10412.0, 10556.788864222826, 10588.894111058946, 10630.0, 10359.948200259101, 10450.843237351446, 10469.214808889365, 10495.737606559896, 10515.316504427188, 7366.815829604303, 7682.0, 7682.270184332591, 7698.499597526143, 7908.604569771488, 5817.854553636193, 6005.669688167192, 6088.6042407243385, 6125.111858780363, 6184.536159788124, 9552.761180970532, 9742.707718768164, 9772.413655180711, 9846.409215447096, 9972.451515166735, 9965.053319934595, 10069.0, 10071.597136114473, 10278.126359259546, 10322.277440579088, 7035.824104397431, 7438.814029649302, 7560.395168386406, 7634.656440460304, 7817.921820781719, 8271.0, 8313.459625124347, 8316.83366332682, 8353.707620233263, 8366.581670916552, 7251.601161936144, 7306.41548676094, 7499.962500187682, 7509.286617771303, 7568.924310756822, 7324.707011719472, 7396.852062958603, 7398.926010739824, 7510.962445187739, 7515.210902855121, 6680.0, 6781.762638307636, 6826.795196144124, 6828.5902845829405, 6833.624150671761, 4956.826511072095, 5097.898042039064, 5160.045391602565, 5177.844664659916, 5182.637215394886, 5099.464556221692, 5135.948640513697, 5143.5113664201845, 5181.171012637959, 5237.790488380575], "value": 4492.904152762389, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.5220152159587, 315.9636641786194, 319.9728023118035, 320.09413360190655, 322.732132330166, 5328.733563321828, 5378.89242215156, 5389.461053894609, 5541.556675465963, 5556.861078473035, 5450.781968721256, 5454.763546945753, 5469.316335458067, 5479.890402191953, 5504.036793561131, 4171.332586786115, 4193.301712806318, 4199.286121359361, 4200.747955122687, 4213.915721685565, 4147.979260103695, 4224.366345048252, 4232.470941132358, 4249.6387807036435, 4257.191133684605, 4071.857484988026, 4126.154138401621, 4161.916761664765, 4198.37024446334, 4211.7051806373565, 4117.397277382275, 4118.91762164755, 4127.793610319487, 4139.172165566899, 4153.0, 3693.759905606127, 3700.5559332879957, 3705.0181701849033, 3720.162963333228, 3724.7392682512236, 5119.38567371914, 5201.517567493246, 5230.195582524061, 5244.160934250502, 5301.761420736076, 4760.33421284082, 4788.138135135674, 4791.9520804791855, 4795.786822828713, 4799.448063472675, 5455.536279416255, 5662.2639056922335, 5705.286839145107, 5718.885622287531, 5721.971390143064, 4016.819243134066, 4030.2745505808944, 4030.3954406838916, 4033.1127152026506, 4041.9797901010306, 5941.70291485424, 6006.129111278884, 6046.6069705469445, 6054.0, 6066.696665166724, 4099.549049604541, 4114.362273847559, 4118.773467459258, 4127.731697439657, 4139.648129908931, 3885.514310711161, 4098.672106231554, 4114.670826333886, 4137.54487006429, 4143.772092534879, 4761.428628564595, 4770.76146192689, 4778.068276686013, 4779.0, 4788.647946437438, 5548.77804887808, 5755.453231942959, 5776.277965254343, 5803.854903627359, 5830.2129212555765, 1440.7550716378241, 1492.8059352284133, 1493.9850601494063, 1494.9103053816805, 1498.1086253679043, 978.1196922769565, 981.0630847540582, 991.8413053911339, 1002.2683441088046, 1008.7478130467383, 5928.703564821742, 5931.406859314034, 5959.480332515181, 5967.880642387215, 6001.379627500551, 6206.503479721611, 6296.968515157487, 6311.400416960381, 6333.638267772451, 6362.872742545123, 5369.9928040297555, 5644.250282412465, 5665.413684168439, 5701.771929122789, 5991.101334799728, 3408.454647256427, 3421.2473255883665, 3424.383610950053, 3432.5194472773833, 3454.378211921828, 6470.6441145737435, 6606.8017959461295, 6630.86738265232, 6691.631960242136, 6718.865622687519, 6466.191726034246, 6513.2509761377105, 6529.412352888263, 6532.020196970492, 6572.0, 6502.317256687979, 6609.966950165219, 6616.239132499728, 6617.66911654425, 6619.669016549154, 6360.809175724736, 6382.3617638237665, 6386.393292637192, 6388.1376014237485, 6397.328280530477, 6265.49876009909, 6294.276158241861, 6320.345454909041, 6336.58812177207, 6355.586886852339, 5886.6173698709445, 6194.318624951342, 6199.380061993765, 6240.968795155995, 6252.687365631791, 4036.3541833307704, 4054.6350828425584, 4072.6538244248386, 4090.263752524574, 4110.794460276914, 5321.680699158062, 5967.41863406189, 5997.850053748692, 6013.278406591237, 6034.939650603438, 5820.359760426434, 5835.533157347317, 5837.737301821343, 5851.239338885917, 5864.824055278348, 5810.448007439287, 5817.214676018683, 5837.211976383303, 5839.386864379179, 5843.824685259449, 5622.718864056863, 5748.281464816898, 5755.496359083644, 5764.711764411679, 5780.323706125177, 5824.039033559585, 5843.328017278068, 5852.795152169824, 5877.500412464989, 5910.438508341701, 5524.723763811874, 5681.403452637579, 5703.287089113861, 5705.686187259741, 5724.79963201286], "value": 4437.514238783692, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.3112199020877, 835.1189495082688, 857.4726543175949, 858.9914100858989, 875.1685898396524, 13047.934760326198, 13366.0, 13373.933130334359, 13403.1958082515, 13456.1926284423, 12461.127721059529, 12943.741125177514, 12944.0, 12952.35238238089, 12952.546660866872, 701.554512884317, 817.4400535633081, 830.9376796740254, 845.4504572028169, 852.7271273192581, 839.2866063845744, 844.3667249562828, 847.1697736218509, 848.499385362636, 852.7100785732849, 887.6937456577502, 891.1845661220009, 891.7681402835277, 891.7993451473399, 892.629558733125, 880.9603567839431, 885.8759773631697, 887.4630848336792, 888.3026823943173, 888.7156110044788, 641.397086738469, 644.7195469970578, 646.5797231799321, 648.9123968264271, 649.0815496073051, 12790.0, 12792.48830046797, 12817.935910320482, 12843.100982931199, 12874.935625321814, 13903.652408689763, 13929.512467063705, 14186.785514429697, 14510.129392236395, 14600.0, 11425.31448113116, 11472.77054458906, 11603.129765267555, 11775.823362649568, 11810.822837657443, 3886.261610294035, 3918.960810391888, 3953.169834334793, 3982.183652351261, 4080.3063479208604, 52791.736041319935, 53398.466015339734, 54151.45848541504, 54533.909321813735, 54597.0, 10479.84280235797, 10648.281621250839, 10794.190435717353, 10843.945780271206, 10880.510377033053, 18719.625607487775, 18756.90621546911, 18833.717494237597, 19055.714164287547, 19058.14238359277, 8189.26296633294, 8229.177082291842, 8310.667573297122, 8326.542040187727, 8436.493810371398, 4564.42944631921, 4731.148393289172, 4753.215719406259, 4754.595859351994, 4890.217565189624, 3369.814660193713, 3428.0572842468023, 3428.6571342866014, 3429.091290807938, 3444.844981975817, 600.9188759517409, 614.1954040207343, 617.8177437655902, 654.4110300729286, 654.5418207254845, 637.789529455288, 649.4122818848949, 777.9183185765527, 794.7417089445953, 816.7958010497375, 9219.539023048821, 9929.0, 9945.950270248748, 10049.547770350351, 10068.194544436428, 645.6255371884296, 685.8525417035362, 694.7186389512154, 791.5962858941981, 807.7294106474279, 4477.507474177838, 4498.5501449854755, 4769.73766442849, 4793.400824896888, 4835.975820120877, 69409.3059069413, 71842.0, 72401.63799180971, 73151.26848731445, 73312.0, 59642.40357596368, 60969.39030609726, 61385.0, 61386.38613613896, 63673.36326636767, 48335.999999999294, 57363.42636573665, 57512.42487575071, 58565.121523177186, 58937.115943260884, 9414.811703766023, 9454.810903782023, 9566.234701223746, 9788.816180844678, 10027.649032284127, 33473.83263083669, 34183.82908085443, 34662.0, 35071.47392789161, 35462.0, 44889.77555112204, 46256.768716156206, 46642.300365495226, 46794.29808552942, 46935.29597056115, 12365.938170309091, 12591.811122832982, 12628.179168354027, 12675.683107922376, 12897.806532901826, 12989.740205196033, 13071.869281307065, 13085.869141308465, 13420.798688019882, 13489.2580908051, 12843.0, 12897.161684490478, 12938.805917911051, 13233.80149297742, 13292.401841916944, 13184.2089474628, 13271.071025028155, 13423.328833558087, 13881.722365552834, 13883.652908677364, 11184.664460066211, 12761.248833932035, 13357.732845342844, 13498.32508374597, 13979.720405592034, 10694.304870183412, 10758.300710453796, 10922.344659320466, 11163.497642606264, 11243.887561124284, 10561.735956601147, 10927.125829933428, 10998.835017474905, 11139.275947063416, 11185.496652650487], "value": 6913.434819820766, "unit": "score"}], "name": "dromaeo_css", "value": 7137.095939338624, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 107.0, 113.0, 112.0, 112.0, 124.0, 111.0, 112.0, 114.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 131.0, 153.0, 192.0, 133.0, 165.0, 156.0, 130.0, 187.0, 130.0], "value": 153.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 163.0, 163.0, 165.0, 170.0, 162.0, 166.0, 163.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 75.0, 154.0, 78.0, 73.0, 74.0, 76.0, 160.0, 73.0], "value": 91.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 145.0, 93.0, 94.0, 94.0, 151.0, 94.0, 94.0, 141.0], "value": 109.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 119.0, 117.0, 117.0, 118.0, 119.0, 119.0, 118.0], "value": 118.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 146.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 148.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 63.0, 58.0, 57.0, 58.0, 63.0, 73.0, 55.0, 58.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 67.0, 62.0, 63.0, 62.0, 62.0, 62.0, 62.0, 64.0], "value": 62.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 89.0, 77.0, 79.0, 79.0, 77.0, 78.0, 78.0, 80.0, 79.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 126.0, 125.0, 124.0, 128.0, 133.0, 132.0, 123.0, 160.0, 126.0], "value": 130.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 164.0, 165.0, 165.0, 167.0, 163.0, 165.0, 173.0, 166.0, 164.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 71.0, 72.0, 73.0, 67.0, 75.0, 69.0, 68.0], "value": 70.0, "unit": "score"}], "name": "kraken", "value": 1562.3000000000002, "alertThreshold": 2.0}]} 11:30:41 INFO - Return code: 0 11:30:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:30:41 ERROR - # TBPL SUCCESS # 11:30:41 INFO - Running post-action listener: _resource_record_post_action 11:30:41 INFO - Running post-run listener: _resource_record_post_run 11:30:42 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 534253568; Read time: 0; Write time: 492884 11:30:42 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 244436992; Read time: 0; Write time: 281668 11:30:43 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 289648640; Read time: 0; Write time: 211192 11:30:43 INFO - Running post-run listener: _upload_blobber_files 11:30:43 INFO - Blob upload gear active. 11:30:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:30:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:30:43 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:30:43 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:30:43 INFO - (blobuploader) - INFO - Open directory for files ... 11:30:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:30:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:30:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:44 INFO - (blobuploader) - INFO - Done attempting. 11:30:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:30:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:30:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:44 INFO - (blobuploader) - INFO - Done attempting. 11:30:44 INFO - (blobuploader) - INFO - Iteration through files over. 11:30:45 INFO - Return code: 0 11:30:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:30:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:30:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d10a853b2eccdd6a984ed19affeb1ff0cf84e3891806d28fe36ab507d9303fcccf8396bdabee55260ab2d82c1544c577af88537a433ea1b432c29f62595918c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dafe0b4f6925442b9659059dc01e46862978648db12b0ea9799c38331dad455ac3d9cc26bad62731760255d3dd1d11899f8e2eb5ffe112a4303f06c2c942ff82"} 11:30:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:30:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:30:45 INFO - Contents: 11:30:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d10a853b2eccdd6a984ed19affeb1ff0cf84e3891806d28fe36ab507d9303fcccf8396bdabee55260ab2d82c1544c577af88537a433ea1b432c29f62595918c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dafe0b4f6925442b9659059dc01e46862978648db12b0ea9799c38331dad455ac3d9cc26bad62731760255d3dd1d11899f8e2eb5ffe112a4303f06c2c942ff82"} 11:30:45 INFO - Running post-run listener: copy_logs_to_upload_dir 11:30:45 INFO - Copying logs to upload dir... 11:30:45 INFO - mkdir: /builds/slave/test/build/upload/logs 11:30:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.723526 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-04-28 11:30:44.301327) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 11:30:44.304994) ========= 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=574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648 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/sha512/7d10a853b2eccdd6a984ed19affeb1ff0cf84e3891806d28fe36ab507d9303fcccf8396bdabee55260ab2d82c1544c577af88537a433ea1b432c29f62595918c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dafe0b4f6925442b9659059dc01e46862978648db12b0ea9799c38331dad455ac3d9cc26bad62731760255d3dd1d11899f8e2eb5ffe112a4303f06c2c942ff82"} build_url:https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/ATy0pSn0TyWmQJz41KCZgQ/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/sha512/7d10a853b2eccdd6a984ed19affeb1ff0cf84e3891806d28fe36ab507d9303fcccf8396bdabee55260ab2d82c1544c577af88537a433ea1b432c29f62595918c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dafe0b4f6925442b9659059dc01e46862978648db12b0ea9799c38331dad455ac3d9cc26bad62731760255d3dd1d11899f8e2eb5ffe112a4303f06c2c942ff82"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 11:30:44.329090) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 11:30:44.329373) ========= 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=574dc5aafe09851ec43d388c000001b6-1461866197.655651-7339648 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004491 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 11:30:44.412297) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 11:30:44.412581) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 11:30:44.412861) ========= ========= Total master_lag: 0.16 =========