builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-038 starttime: 1461584885.77 results: success (0) buildid: 20160422035709 builduid: 3af33a9a7de54ba3986a82f34ab86caa revision: 80598be5d80e695727ff74e712faa623b02f3c70 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:05.767476) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:05.772124) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:05.772452) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:05.831913) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:05.832205) ========= 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=8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-25 04:48:06-- 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% 46.2M=0s 2016-04-25 04:48:06 (46.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088880 ========= master_lag: 0.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:06.200656) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:06.200993) ========= 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=8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020908 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:06.340589) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 04:48:06.344012) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 80598be5d80e695727ff74e712faa623b02f3c70 --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 80598be5d80e695727ff74e712faa623b02f3c70 --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=8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-25 04:48:06,656 truncating revision to first 12 chars 2016-04-25 04:48:06,656 Setting DEBUG logging. 2016-04-25 04:48:06,656 attempt 1/10 2016-04-25 04:48:06,656 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/80598be5d80e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 04:48:07,494 unpacking tar archive at: fx-team-80598be5d80e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.089570 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 04:48:07.452262) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:07.452557) ========= script_repo_revision: 80598be5d80e695727ff74e712faa623b02f3c70 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:07.452946) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:07.453211) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 04:48:07.467629) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-04-25 04:48:07.467935) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:48:07 INFO - MultiFileLogger online at 20160425 04:48:07 in /builds/slave/test 04:48:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 04:48:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:48:07 INFO - {'append_to_log': False, 04:48:07 INFO - 'base_work_dir': '/builds/slave/test', 04:48:07 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 04:48:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:48:07 INFO - 'branch': 'Fx-Team-Non-PGO', 04:48:07 INFO - 'buildbot_json_path': 'buildprops.json', 04:48:07 INFO - 'config_files': ('talos/linux_config.py',), 04:48:07 INFO - 'default_actions': ('clobber', 04:48:07 INFO - 'read-buildbot-config', 04:48:07 INFO - 'download-and-extract', 04:48:07 INFO - 'populate-webroot', 04:48:07 INFO - 'create-virtualenv', 04:48:07 INFO - 'install', 04:48:07 INFO - 'run-tests'), 04:48:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:48:07 INFO - 'download_minidump_stackwalk': True, 04:48:07 INFO - 'download_symbols': 'ondemand', 04:48:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:48:07 INFO - 'tooltool.py': '/tools/tooltool.py', 04:48:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:48:07 INFO - '/tools/misc-python/virtualenv.py')}, 04:48:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:48:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:48:07 INFO - 'installer_path': 'installer.exe', 04:48:07 INFO - 'log_level': 'info', 04:48:07 INFO - 'log_name': 'talos', 04:48:07 INFO - 'log_to_console': True, 04:48:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:48:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:48:07 INFO - 'opt_config_files': (), 04:48:07 INFO - 'pip_index': False, 04:48:07 INFO - 'sps_profile': False, 04:48:07 INFO - 'sps_profile_interval': 0, 04:48:07 INFO - 'suite': 'dromaeojs-e10s', 04:48:07 INFO - 'system_bits': '32', 04:48:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:48:07 INFO - 'title': 'talos-linux64-ix-038', 04:48:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:48:07 INFO - 'use_talos_json': True, 04:48:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:48:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:48:07 INFO - 'work_dir': 'build'} 04:48:07 INFO - ##### 04:48:07 INFO - ##### Running clobber step. 04:48:07 INFO - ##### 04:48:07 INFO - Running pre-action listener: _resource_record_pre_action 04:48:07 INFO - Running main action method: clobber 04:48:07 INFO - rmtree: /builds/slave/test/build 04:48:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:48:08 INFO - Running post-action listener: _resource_record_post_action 04:48:08 INFO - ##### 04:48:08 INFO - ##### Running read-buildbot-config step. 04:48:08 INFO - ##### 04:48:08 INFO - Running pre-action listener: _resource_record_pre_action 04:48:08 INFO - Running main action method: read_buildbot_config 04:48:08 INFO - Using buildbot properties: 04:48:08 INFO - { 04:48:08 INFO - "project": "", 04:48:08 INFO - "product": "firefox", 04:48:08 INFO - "script_repo_revision": "production", 04:48:08 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 04:48:08 INFO - "repository": "", 04:48:08 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 04:48:08 INFO - "buildid": "20160422035709", 04:48:08 INFO - "pgo_build": "False", 04:48:08 INFO - "basedir": "/builds/slave/test", 04:48:08 INFO - "buildnumber": 1205, 04:48:08 INFO - "slavename": "talos-linux64-ix-038", 04:48:08 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:48:08 INFO - "platform": "ubuntu64_hw", 04:48:08 INFO - "scheduler": "tests-fx-team-linux64-talos", 04:48:08 INFO - "branch": "fx-team", 04:48:08 INFO - "repo_path": "integration/fx-team", 04:48:08 INFO - "revision": "80598be5d80e695727ff74e712faa623b02f3c70", 04:48:08 INFO - "stage_platform": "linux64", 04:48:08 INFO - "builduid": "3af33a9a7de54ba3986a82f34ab86caa", 04:48:08 INFO - "slavebuilddir": "test" 04:48:08 INFO - } 04:48:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 04:48:08 INFO - Running post-action listener: _resource_record_post_action 04:48:08 INFO - ##### 04:48:08 INFO - ##### Running download-and-extract step. 04:48:08 INFO - ##### 04:48:08 INFO - Running pre-action listener: _resource_record_pre_action 04:48:08 INFO - Running main action method: download_and_extract 04:48:08 INFO - mkdir: /builds/slave/test/build/tests 04:48: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')]} 04:48:08 INFO - https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:48:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 04:48:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 04:48:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 04:48:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:48:15 INFO - Downloaded 1361 bytes. 04:48:15 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 04:48:15 INFO - Using the following test package requirements: 04:48:15 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 04:48:15 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:48:15 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:48:15 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:48:15 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:48:15 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:48:15 INFO - u'jsshell-linux-x86_64.zip'], 04:48:15 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:48:15 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:48:15 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 04:48:15 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:48:15 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:48:15 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:48:15 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:48:15 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:48:15 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:48:15 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:48:15 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:48:15 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:15 INFO - https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:48:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:48:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:48:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:48:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:48:20 INFO - Downloaded 22281373 bytes. 04:48:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:48:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:48:21 INFO - Return code: 0 04:48:21 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:21 INFO - https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:48:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:48:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:48:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:48:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:48:27 INFO - Downloaded 22281373 bytes. 04:48:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:48:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:48:28 INFO - Return code: 0 04:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:28 INFO - https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:48:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 04:48:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 04:48:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 04:48:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:48:30 INFO - Downloaded 10845952 bytes. 04:48:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:48:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:48:30 INFO - Return code: 0 04:48: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')]} 04:48:30 INFO - https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:48:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 04:48:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 04:48:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 04:48:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:48:31 INFO - Downloaded 54650321 bytes. 04:48:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 04:48:31 INFO - mkdir: /builds/slave/test/properties 04:48:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:48:31 INFO - Writing to file /builds/slave/test/properties/build_url 04:48:31 INFO - Contents: 04:48:31 INFO - build_url:https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 04:48:32 INFO - Running post-action listener: _resource_record_post_action 04:48:32 INFO - Running post-action listener: set_extra_try_arguments 04:48:32 INFO - ##### 04:48:32 INFO - ##### Running populate-webroot step. 04:48:32 INFO - ##### 04:48:32 INFO - Running pre-action listener: _resource_record_pre_action 04:48:32 INFO - Running main action method: populate_webroot 04:48:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:48:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:48:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:48:32 INFO - '--tppagecycles', 04:48:32 INFO - '7'], 04:48:32 INFO - 'tests': ['tsvgm']}}, 04:48:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:48:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:48:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:48:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:48:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:48:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:48:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:32 INFO - 'talos_options': ['--disable-e10s'], 04:48:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:48:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:48:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:32 INFO - 'talos_options': ['--disable-e10s'], 04:48:32 INFO - 'tests': ['damp', 'tps']}, 04:48:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:32 INFO - 'tests': ['damp', 'tps']}, 04:48:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:48:32 INFO - 'tests': ['dromaeo_dom']}, 04:48:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:48:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:48:32 INFO - 'tests': ['a11yr', 04:48:32 INFO - 'ts_paint', 04:48:32 INFO - 'tpaint', 04:48:32 INFO - 'sessionrestore', 04:48:32 INFO - 'sessionrestore_no_auto_restore', 04:48:32 INFO - 'tabpaint']}, 04:48:32 INFO - 'other-e10s': {'tests': ['a11yr', 04:48:32 INFO - 'ts_paint', 04:48:32 INFO - 'tpaint', 04:48:32 INFO - 'sessionrestore', 04:48:32 INFO - 'sessionrestore_no_auto_restore', 04:48:32 INFO - 'tabpaint']}, 04:48:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:48:32 INFO - 'ts_paint', 04:48:32 INFO - 'tpaint', 04:48:32 INFO - 'sessionrestore', 04:48:32 INFO - 'sessionrestore_no_auto_restore', 04:48:32 INFO - 'tabpaint']}, 04:48:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:48:32 INFO - 'ts_paint', 04:48:32 INFO - 'tpaint', 04:48:32 INFO - 'sessionrestore', 04:48:32 INFO - 'sessionrestore_no_auto_restore', 04:48:32 INFO - 'tabpaint']}, 04:48:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:48:32 INFO - 'tests': ['a11yr', 04:48:32 INFO - 'ts_paint', 04:48:32 INFO - 'tpaint', 04:48:32 INFO - 'sessionrestore', 04:48:32 INFO - 'sessionrestore_no_auto_restore', 04:48:32 INFO - 'tabpaint']}, 04:48:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:48:32 INFO - 'tests': ['a11yr', 04:48:32 INFO - 'ts_paint', 04:48:32 INFO - 'tpaint', 04:48:32 INFO - 'sessionrestore', 04:48:32 INFO - 'sessionrestore_no_auto_restore', 04:48:32 INFO - 'tabpaint']}, 04:48:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:48:32 INFO - 'tests': ['tsvgx', 04:48:32 INFO - 'tsvgr_opacity', 04:48:32 INFO - 'tart', 04:48:32 INFO - 'tscrollx', 04:48:32 INFO - 'cart']}, 04:48:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:48:32 INFO - 'tsvgr_opacity', 04:48:32 INFO - 'tart', 04:48:32 INFO - 'tscrollx', 04:48:32 INFO - 'cart']}, 04:48:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:32 INFO - 'talos_options': ['--disable-e10s'], 04:48:32 INFO - 'tests': ['tp5o']}, 04:48:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:32 INFO - 'tests': ['tp5o']}, 04:48:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:32 INFO - 'talos_options': ['--disable-e10s', 04:48:32 INFO - '--xperf_path', 04:48:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:48:32 INFO - 'tests': ['tp5n']}, 04:48:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:32 INFO - 'talos_options': ['--xperf_path', 04:48:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:48:32 INFO - 'tests': ['tp5n']}}, 04:48:32 INFO - 'talos.zip': {'path': '', 04:48:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:48:32 INFO - Running post-action listener: _resource_record_post_action 04:48:32 INFO - ##### 04:48:32 INFO - ##### Running create-virtualenv step. 04:48:32 INFO - ##### 04:48:32 INFO - Running pre-action listener: _resource_record_pre_action 04:48:32 INFO - Running main action method: create_virtualenv 04:48:32 INFO - Creating virtualenv /builds/slave/test/build/venv 04:48:32 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 04:48:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:48:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:48:32 INFO - Using real prefix '/usr' 04:48:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:48:33 INFO - Installing distribute.............................................................................................................................................................................................done. 04:48:35 INFO - Installing pip.................done. 04:48:35 INFO - Return code: 0 04:48:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0d256cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2766410>, '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 0x2767310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27677a0>, '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': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', '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 04:48: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', 'pip>=1.5'] in /builds/slave/test/build 04:48: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 pip>=1.5 04:48:35 INFO - Using env: {'DISPLAY': ':0', 04:48:35 INFO - 'HOME': '/home/cltbld', 04:48:35 INFO - 'LANG': 'en_US.UTF-8', 04:48:35 INFO - 'LANGUAGE': 'en_US:en', 04:48:35 INFO - 'LOGNAME': 'cltbld', 04:48:35 INFO - 'MAIL': '/var/mail/cltbld', 04:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:35 INFO - 'MOZ_NO_REMOTE': '1', 04:48:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:35 INFO - 'NO_EM_RESTART': '1', 04:48:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:35 INFO - 'PWD': '/builds/slave/test', 04:48:35 INFO - 'SHELL': '/bin/bash', 04:48:35 INFO - 'SHLVL': '1', 04:48:35 INFO - 'TERM': 'linux', 04:48:35 INFO - 'TMOUT': '86400', 04:48:35 INFO - 'USER': 'cltbld', 04:48:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', 04:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:35 INFO - '_': '/tools/buildbot/bin/python'} 04:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:35 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 04:48:35 INFO - Cleaning up... 04:48:35 INFO - Return code: 0 04:48:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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', '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 0x7f0d256cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2766410>, '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 0x2767310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27677a0>, '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': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', '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 04:48: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', 'psutil>=0.7.1'] in /builds/slave/test/build 04:48: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 psutil>=0.7.1 04:48:35 INFO - Using env: {'DISPLAY': ':0', 04:48:35 INFO - 'HOME': '/home/cltbld', 04:48:35 INFO - 'LANG': 'en_US.UTF-8', 04:48:35 INFO - 'LANGUAGE': 'en_US:en', 04:48:35 INFO - 'LOGNAME': 'cltbld', 04:48:35 INFO - 'MAIL': '/var/mail/cltbld', 04:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:35 INFO - 'MOZ_NO_REMOTE': '1', 04:48:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:35 INFO - 'NO_EM_RESTART': '1', 04:48:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:35 INFO - 'PWD': '/builds/slave/test', 04:48:35 INFO - 'SHELL': '/bin/bash', 04:48:35 INFO - 'SHLVL': '1', 04:48:35 INFO - 'TERM': 'linux', 04:48:35 INFO - 'TMOUT': '86400', 04:48:35 INFO - 'USER': 'cltbld', 04:48:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', 04:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:35 INFO - '_': '/tools/buildbot/bin/python'} 04:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:35 INFO - Downloading/unpacking psutil>=0.7.1 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:48:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:37 INFO - Installing collected packages: psutil 04:48:37 INFO - Running setup.py install for psutil 04:48:37 INFO - building 'psutil._psutil_linux' extension 04:48:37 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 04:48:37 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 04:48:38 INFO - building 'psutil._psutil_posix' extension 04:48:38 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 04:48:38 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 04:48:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:38 INFO - Successfully installed psutil 04:48:38 INFO - Cleaning up... 04:48:38 INFO - Return code: 0 04:48:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:38 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 0x7f0d256cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2766410>, '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 0x2767310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27677a0>, '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': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', '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 04:48:38 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 04:48:38 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 04:48:38 INFO - Using env: {'DISPLAY': ':0', 04:48:38 INFO - 'HOME': '/home/cltbld', 04:48:38 INFO - 'LANG': 'en_US.UTF-8', 04:48:38 INFO - 'LANGUAGE': 'en_US:en', 04:48:38 INFO - 'LOGNAME': 'cltbld', 04:48:38 INFO - 'MAIL': '/var/mail/cltbld', 04:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:38 INFO - 'MOZ_NO_REMOTE': '1', 04:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:38 INFO - 'NO_EM_RESTART': '1', 04:48:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:38 INFO - 'PWD': '/builds/slave/test', 04:48:38 INFO - 'SHELL': '/bin/bash', 04:48:38 INFO - 'SHLVL': '1', 04:48:38 INFO - 'TERM': 'linux', 04:48:38 INFO - 'TMOUT': '86400', 04:48:38 INFO - 'USER': 'cltbld', 04:48:38 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', 04:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:38 INFO - '_': '/tools/buildbot/bin/python'} 04:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:48:38 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 04:48:38 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 04:48:38 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 04:48:38 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 04:48:38 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 04:48:38 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 04:48:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:48:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:48:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:48:40 INFO - Installing collected packages: mozsystemmonitor 04:48:40 INFO - Running setup.py install for mozsystemmonitor 04:48:40 INFO - Successfully installed mozsystemmonitor 04:48:40 INFO - Cleaning up... 04:48:40 INFO - Return code: 0 04:48:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:40 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 0x7f0d256cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2766410>, '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 0x2767310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27677a0>, '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': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', '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 04:48:40 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 04:48:40 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 04:48:40 INFO - Using env: {'DISPLAY': ':0', 04:48:40 INFO - 'HOME': '/home/cltbld', 04:48:40 INFO - 'LANG': 'en_US.UTF-8', 04:48:40 INFO - 'LANGUAGE': 'en_US:en', 04:48:40 INFO - 'LOGNAME': 'cltbld', 04:48:40 INFO - 'MAIL': '/var/mail/cltbld', 04:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:40 INFO - 'MOZ_NO_REMOTE': '1', 04:48:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:40 INFO - 'NO_EM_RESTART': '1', 04:48:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:40 INFO - 'PWD': '/builds/slave/test', 04:48:40 INFO - 'SHELL': '/bin/bash', 04:48:40 INFO - 'SHLVL': '1', 04:48:40 INFO - 'TERM': 'linux', 04:48:40 INFO - 'TMOUT': '86400', 04:48:40 INFO - 'USER': 'cltbld', 04:48:40 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', 04:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:40 INFO - '_': '/tools/buildbot/bin/python'} 04:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:40 INFO - Downloading/unpacking blobuploader==1.2.4 04:48:40 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 04:48:40 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 04:48:40 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 04:48:40 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 04:48:40 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 04:48:40 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 04:48:42 INFO - Downloading blobuploader-1.2.4.tar.gz 04:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:48:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:48:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:43 INFO - Downloading docopt-0.6.1.tar.gz 04:48:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:48:43 INFO - Installing collected packages: blobuploader, requests, docopt 04:48:43 INFO - Running setup.py install for blobuploader 04:48:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:48:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:48:43 INFO - Running setup.py install for requests 04:48:43 INFO - Running setup.py install for docopt 04:48:44 INFO - Successfully installed blobuploader requests docopt 04:48:44 INFO - Cleaning up... 04:48:44 INFO - Return code: 0 04:48:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:44 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 0x7f0d256cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2766410>, '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 0x2767310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27677a0>, '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': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', '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 04:48:44 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 04:48:44 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 04:48:44 INFO - Using env: {'DISPLAY': ':0', 04:48:44 INFO - 'HOME': '/home/cltbld', 04:48:44 INFO - 'LANG': 'en_US.UTF-8', 04:48:44 INFO - 'LANGUAGE': 'en_US:en', 04:48:44 INFO - 'LOGNAME': 'cltbld', 04:48:44 INFO - 'MAIL': '/var/mail/cltbld', 04:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:44 INFO - 'MOZ_NO_REMOTE': '1', 04:48:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:44 INFO - 'NO_EM_RESTART': '1', 04:48:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:44 INFO - 'PWD': '/builds/slave/test', 04:48:44 INFO - 'SHELL': '/bin/bash', 04:48:44 INFO - 'SHLVL': '1', 04:48:44 INFO - 'TERM': 'linux', 04:48:44 INFO - 'TMOUT': '86400', 04:48:44 INFO - 'USER': 'cltbld', 04:48:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', 04:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:44 INFO - '_': '/tools/buildbot/bin/python'} 04:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:44 INFO - Running setup.py (path:/tmp/pip-lwLdOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:44 INFO - Running setup.py (path:/tmp/pip-BhFc5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:44 INFO - Running setup.py (path:/tmp/pip-KVVzBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:44 INFO - Running setup.py (path:/tmp/pip-leXu8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:44 INFO - Running setup.py (path:/tmp/pip-_Ml3bD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:44 INFO - Running setup.py (path:/tmp/pip-yHTAUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:44 INFO - Running setup.py (path:/tmp/pip-h2GrBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:44 INFO - Running setup.py (path:/tmp/pip-z9WPR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:44 INFO - Running setup.py (path:/tmp/pip-8xumDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:44 INFO - Running setup.py (path:/tmp/pip-KzWqTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:45 INFO - Running setup.py (path:/tmp/pip-pbhnOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:45 INFO - Running setup.py (path:/tmp/pip-ObUK_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:45 INFO - Running setup.py (path:/tmp/pip-3DZ0B1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:45 INFO - Running setup.py (path:/tmp/pip-WYoWiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:45 INFO - Running setup.py (path:/tmp/pip-amkHtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:45 INFO - Running setup.py (path:/tmp/pip-ZCgj_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:45 INFO - Running setup.py (path:/tmp/pip-ob6tf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:48:45 INFO - Running setup.py install for manifestparser 04:48:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:48:45 INFO - Running setup.py install for mozcrash 04:48:45 INFO - Running setup.py install for mozdebug 04:48:45 INFO - Running setup.py install for mozdevice 04:48:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:48:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:48:45 INFO - Running setup.py install for mozfile 04:48:46 INFO - Running setup.py install for mozhttpd 04:48:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Running setup.py install for mozinfo 04:48:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Running setup.py install for mozInstall 04:48:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Running setup.py install for mozleak 04:48:46 INFO - Running setup.py install for mozlog 04:48:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Running setup.py install for moznetwork 04:48:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Running setup.py install for mozprocess 04:48:46 INFO - Running setup.py install for mozprofile 04:48:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:48:46 INFO - Running setup.py install for mozrunner 04:48:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:48:47 INFO - Running setup.py install for mozscreenshot 04:48:47 INFO - Running setup.py install for moztest 04:48:47 INFO - Running setup.py install for mozversion 04:48:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:48:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:48:47 INFO - Cleaning up... 04:48:47 INFO - Return code: 0 04:48:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:47 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 0x7f0d256cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2766410>, '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 0x2767310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27677a0>, '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': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', '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 04:48:47 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 04:48:47 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 04:48:47 INFO - Using env: {'DISPLAY': ':0', 04:48:47 INFO - 'HOME': '/home/cltbld', 04:48:47 INFO - 'LANG': 'en_US.UTF-8', 04:48:47 INFO - 'LANGUAGE': 'en_US:en', 04:48:47 INFO - 'LOGNAME': 'cltbld', 04:48:47 INFO - 'MAIL': '/var/mail/cltbld', 04:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:47 INFO - 'MOZ_NO_REMOTE': '1', 04:48:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:47 INFO - 'NO_EM_RESTART': '1', 04:48:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:47 INFO - 'PWD': '/builds/slave/test', 04:48:47 INFO - 'SHELL': '/bin/bash', 04:48:47 INFO - 'SHLVL': '1', 04:48:47 INFO - 'TERM': 'linux', 04:48:47 INFO - 'TMOUT': '86400', 04:48:47 INFO - 'USER': 'cltbld', 04:48:47 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', 04:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:47 INFO - '_': '/tools/buildbot/bin/python'} 04:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:47 INFO - Running setup.py (path:/tmp/pip-3iP9jH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:47 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)) 04:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:47 INFO - Running setup.py (path:/tmp/pip-PKnZux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:47 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)) 04:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:47 INFO - Running setup.py (path:/tmp/pip-pzLahm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:47 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)) 04:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:47 INFO - Running setup.py (path:/tmp/pip-ZDSgD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:47 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)) 04:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:47 INFO - Running setup.py (path:/tmp/pip-xeLeq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:47 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)) 04:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:47 INFO - Running setup.py (path:/tmp/pip-OHV1v0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:47 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)) 04:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:47 INFO - Running setup.py (path:/tmp/pip-1Cv7Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:48 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)) 04:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:48 INFO - Running setup.py (path:/tmp/pip-VjdGLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:48 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)) 04:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:48 INFO - Running setup.py (path:/tmp/pip-mU43yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:48 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)) 04:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:48 INFO - Running setup.py (path:/tmp/pip-CuB5ud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:48 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)) 04:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:48 INFO - Running setup.py (path:/tmp/pip-oIE3R6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:48 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)) 04:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:48 INFO - Running setup.py (path:/tmp/pip-6lhILi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:48 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)) 04:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:48 INFO - Running setup.py (path:/tmp/pip-ADXDbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:48 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)) 04:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:48 INFO - Running setup.py (path:/tmp/pip-z1L2pM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:48 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)) 04:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:48 INFO - Running setup.py (path:/tmp/pip-09g7Kj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:48 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)) 04:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:48 INFO - Running setup.py (path:/tmp/pip-Jw688t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:48 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)) 04:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:48 INFO - Running setup.py (path:/tmp/pip-CvoFjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:48 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)) 04:48:48 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)) 04:48:48 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)) 04:48:48 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)) 04:48:48 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)) 04:48:48 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)) 04:48:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:50 INFO - Downloading blessings-1.6.tar.gz 04:48:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:48:50 INFO - Installing collected packages: blessings 04:48:50 INFO - Running setup.py install for blessings 04:48:50 INFO - Successfully installed blessings 04:48:50 INFO - Cleaning up... 04:48:50 INFO - Return code: 0 04:48:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:48:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:51 INFO - Reading from file tmpfile_stdout 04:48:51 INFO - Current package versions: 04:48:51 INFO - argparse == 1.2.1 04:48:51 INFO - blessings == 1.6 04:48:51 INFO - blobuploader == 1.2.4 04:48:51 INFO - docopt == 0.6.1 04:48:51 INFO - manifestparser == 1.1 04:48:51 INFO - mozInstall == 1.12 04:48:51 INFO - mozcrash == 0.17 04:48:51 INFO - mozdebug == 0.1 04:48:51 INFO - mozdevice == 0.48 04:48:51 INFO - mozfile == 1.2 04:48:51 INFO - mozhttpd == 0.7 04:48:51 INFO - mozinfo == 0.9 04:48:51 INFO - mozleak == 0.1 04:48:51 INFO - mozlog == 3.1 04:48:51 INFO - moznetwork == 0.27 04:48:51 INFO - mozprocess == 0.22 04:48:51 INFO - mozprofile == 0.28 04:48:51 INFO - mozrunner == 6.11 04:48:51 INFO - mozscreenshot == 0.1 04:48:51 INFO - mozsystemmonitor == 0.0 04:48:51 INFO - moztest == 0.7 04:48:51 INFO - mozversion == 1.4 04:48:51 INFO - psutil == 3.1.1 04:48:51 INFO - requests == 1.2.3 04:48:51 INFO - wsgiref == 0.1.2 04:48:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:51 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 0x7f0d256cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2766410>, '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 0x2767310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27677a0>, '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': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', '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 04:48:51 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 04:48:51 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 04:48:51 INFO - Using env: {'DISPLAY': ':0', 04:48:51 INFO - 'HOME': '/home/cltbld', 04:48:51 INFO - 'LANG': 'en_US.UTF-8', 04:48:51 INFO - 'LANGUAGE': 'en_US:en', 04:48:51 INFO - 'LOGNAME': 'cltbld', 04:48:51 INFO - 'MAIL': '/var/mail/cltbld', 04:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:51 INFO - 'MOZ_NO_REMOTE': '1', 04:48:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:51 INFO - 'NO_EM_RESTART': '1', 04:48:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:51 INFO - 'PWD': '/builds/slave/test', 04:48:51 INFO - 'SHELL': '/bin/bash', 04:48:51 INFO - 'SHLVL': '1', 04:48:51 INFO - 'TERM': 'linux', 04:48:51 INFO - 'TMOUT': '86400', 04:48:51 INFO - 'USER': 'cltbld', 04:48:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', 04:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:51 INFO - '_': '/tools/buildbot/bin/python'} 04:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:51 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)) 04:48:51 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)) 04:48:51 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)) 04:48:51 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)) 04:48:51 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)) 04:48:51 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)) 04:48:51 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)) 04:48:51 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)) 04:48:51 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)) 04:48:51 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)) 04:48:51 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)) 04:48:51 INFO - Cleaning up... 04:48:51 INFO - Return code: 0 04:48:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:51 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 0x7f0d256cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2766410>, '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 0x2767310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27677a0>, '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': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', '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 04:48:51 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 04:48:51 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 04:48:51 INFO - Using env: {'DISPLAY': ':0', 04:48:51 INFO - 'HOME': '/home/cltbld', 04:48:51 INFO - 'LANG': 'en_US.UTF-8', 04:48:51 INFO - 'LANGUAGE': 'en_US:en', 04:48:51 INFO - 'LOGNAME': 'cltbld', 04:48:51 INFO - 'MAIL': '/var/mail/cltbld', 04:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:51 INFO - 'MOZ_NO_REMOTE': '1', 04:48:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:51 INFO - 'NO_EM_RESTART': '1', 04:48:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:51 INFO - 'PWD': '/builds/slave/test', 04:48:51 INFO - 'SHELL': '/bin/bash', 04:48:51 INFO - 'SHLVL': '1', 04:48:51 INFO - 'TERM': 'linux', 04:48:51 INFO - 'TMOUT': '86400', 04:48:51 INFO - 'USER': 'cltbld', 04:48:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', 04:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:51 INFO - '_': '/tools/buildbot/bin/python'} 04:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:51 INFO - Downloading/unpacking jsonschema 04:48:51 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 04:48:51 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 04:48:51 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 04:48:51 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 04:48:51 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 04:48:51 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 04:48:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:48:53 INFO - Downloading/unpacking functools32 (from jsonschema) 04:48:53 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 04:48:53 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 04:48:53 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 04:48:53 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 04:48:53 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 04:48:53 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 04:48:53 INFO - Downloading functools32-3.2.3-2.tar.gz 04:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:48:53 INFO - warning: no files found matching '*.txt' 04:48:53 INFO - no previously-included directories found matching 'build' 04:48:53 INFO - no previously-included directories found matching 'dist' 04:48:53 INFO - no previously-included directories found matching '.git*' 04:48:53 INFO - Installing collected packages: jsonschema, functools32 04:48:53 INFO - Running setup.py install for functools32 04:48:53 INFO - warning: no files found matching '*.txt' 04:48:53 INFO - no previously-included directories found matching 'build' 04:48:53 INFO - no previously-included directories found matching 'dist' 04:48:53 INFO - no previously-included directories found matching '.git*' 04:48:53 INFO - Successfully installed jsonschema functools32 04:48:53 INFO - Cleaning up... 04:48:53 INFO - Return code: 0 04:48:53 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:48:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:53 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 0x7f0d256cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2766410>, '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 0x2767310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27677a0>, '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': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', '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 04:48:53 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 04:48:53 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 04:48:53 INFO - Using env: {'DISPLAY': ':0', 04:48:53 INFO - 'HOME': '/home/cltbld', 04:48:53 INFO - 'LANG': 'en_US.UTF-8', 04:48:53 INFO - 'LANGUAGE': 'en_US:en', 04:48:53 INFO - 'LOGNAME': 'cltbld', 04:48:53 INFO - 'MAIL': '/var/mail/cltbld', 04:48:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:53 INFO - 'MOZ_NO_REMOTE': '1', 04:48:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:53 INFO - 'NO_EM_RESTART': '1', 04:48:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:53 INFO - 'PWD': '/builds/slave/test', 04:48:53 INFO - 'SHELL': '/bin/bash', 04:48:53 INFO - 'SHLVL': '1', 04:48:53 INFO - 'TERM': 'linux', 04:48:53 INFO - 'TMOUT': '86400', 04:48:53 INFO - 'USER': 'cltbld', 04:48:53 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', 04:48:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:53 INFO - '_': '/tools/buildbot/bin/python'} 04:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:54 INFO - Downloading/unpacking flake8 04:48:54 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 04:48:54 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 04:48:54 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 04:48:54 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 04:48:54 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 04:48:54 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 04:48:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:48:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:48:55 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 04:48:55 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 04:48:55 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 04:48:55 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 04:48:55 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 04:48:55 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 04:48:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:48:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:48:56 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 04:48:56 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 04:48:56 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 04:48:56 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 04:48:56 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 04:48:56 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 04:48:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:48:56 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 04:48:56 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 04:48:56 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 04:48:56 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 04:48:56 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 04:48:56 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 04:48:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:48:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:48:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:48:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:48:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:48:56 INFO - no previously-included directories found matching 'docs/_build' 04:48:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:48:56 INFO - Running setup.py install for pep8 04:48:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:48:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:48:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:48:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:48:56 INFO - no previously-included directories found matching 'docs/_build' 04:48:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:48:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:48:56 INFO - Cleaning up... 04:48:56 INFO - Return code: 0 04:48:56 INFO - Running post-action listener: _resource_record_post_action 04:48:56 INFO - Running post-action listener: _start_resource_monitoring 04:48:56 INFO - Starting resource monitoring. 04:48:56 INFO - ##### 04:48:56 INFO - ##### Running install step. 04:48:56 INFO - ##### 04:48:56 INFO - Running pre-action listener: _resource_record_pre_action 04:48:56 INFO - Running main action method: install 04:48:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:57 INFO - Reading from file tmpfile_stdout 04:48:57 INFO - Detecting whether we're running mozinstall >=1.0... 04:48:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:48:57 INFO - Reading from file tmpfile_stdout 04:48:57 INFO - Output received: 04:48:57 INFO - Usage: mozinstall [options] installer 04:48:57 INFO - Options: 04:48:57 INFO - -h, --help show this help message and exit 04:48:57 INFO - -d DEST, --destination=DEST 04:48:57 INFO - Directory to install application into. [default: 04:48:57 INFO - "/builds/slave/test"] 04:48:57 INFO - --app=APP Application being installed. [default: firefox] 04:48:57 INFO - mkdir: /builds/slave/test/build/application 04:48:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:49:13 INFO - Reading from file tmpfile_stdout 04:49:13 INFO - Output received: 04:49:13 INFO - /builds/slave/test/build/application/firefox/firefox 04:49:13 INFO - Running post-action listener: _resource_record_post_action 04:49:13 INFO - ##### 04:49:13 INFO - ##### Running run-tests step. 04:49:13 INFO - ##### 04:49:13 INFO - Running pre-action listener: _resource_record_pre_action 04:49:13 INFO - Running main action method: run_tests 04:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:49:13 INFO - Python 2.7.3 04:49:13 INFO - Return code: 0 04:49:13 INFO - grabbing minidump binary from tooltool 04:49: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')]} 04:49:13 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 0x2766410>, '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 0x2767310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27677a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:49:13 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 04:49:13 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 04:49:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:49:13 INFO - Return code: 0 04:49:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:49:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:49:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:49:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:49:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:49:14 INFO - Return code: 0 04:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 04:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 04:49:14 INFO - Using env: {'DISPLAY': ':0', 04:49:14 INFO - 'HOME': '/home/cltbld', 04:49:14 INFO - 'LANG': 'en_US.UTF-8', 04:49:14 INFO - 'LANGUAGE': 'en_US:en', 04:49:14 INFO - 'LOGNAME': 'cltbld', 04:49:14 INFO - 'MAIL': '/var/mail/cltbld', 04:49:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:49:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:14 INFO - 'MOZ_NO_REMOTE': '1', 04:49:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:49:14 INFO - 'NO_EM_RESTART': '1', 04:49:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:14 INFO - 'PWD': '/builds/slave/test', 04:49:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:49:14 INFO - 'SHELL': '/bin/bash', 04:49:14 INFO - 'SHLVL': '1', 04:49:14 INFO - 'TERM': 'linux', 04:49:14 INFO - 'TMOUT': '86400', 04:49:14 INFO - 'USER': 'cltbld', 04:49:14 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177', 04:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:14 INFO - '_': '/tools/buildbot/bin/python'} 04:49:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 04:49:14 INFO - mozversion application_buildid: 20160422035709 04:49:14 INFO - mozversion application_changeset: 80598be5d80e695727ff74e712faa623b02f3c70 04:49:14 INFO - mozversion application_display_name: Nightly 04:49:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:49:14 INFO - mozversion application_name: Firefox 04:49:14 INFO - mozversion application_remotingname: firefox 04:49:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:49:14 INFO - mozversion application_vendor: Mozilla 04:49:14 INFO - mozversion application_version: 48.0a1 04:49:14 INFO - mozversion platform_buildid: 20160422035709 04:49:14 INFO - mozversion platform_changeset: 80598be5d80e695727ff74e712faa623b02f3c70 04:49:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:49:14 INFO - mozversion platform_version: 48.0a1 04:49:14 INFO - using testdate: 1461584954 04:49:14 INFO - actual date: 1461584954 04:49:14 INFO - starting webserver on 'localhost:51642' 04:49:14 INFO - SUITE-START | Running 2 tests 04:49:14 INFO - TEST-START | dromaeo_css 04:49:14 INFO - operating with platform_type : linux_ 04:49:14 INFO - Initialising browser for dromaeo_css test... 04:49:14 INFO - TEST-INFO | started process 11646 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp36hm6X/profile http://localhost:51642/getInfo.html) 04:49:18 INFO - PROCESS | 11646 | 04:49:18 INFO - PROCESS | 11646 | (process:11715): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:49:20 INFO - PROCESS | 11646 | __metrics Screen width/height:1600/1200 04:49:20 INFO - PROCESS | 11646 | colorDepth:24 04:49:20 INFO - PROCESS | 11646 | Browser inner width/height: 1024/697 04:49:20 INFO - PROCESS | 11646 | __metrics 04:49:20 ERROR - PROCESS | 11646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 04:49:21 INFO - TEST-INFO | 11646: exit 0 04:49:21 INFO - Browser initialized. 04:49:21 INFO - Running cycle 1/1 for dromaeo_css test... 04:49:21 INFO - TEST-INFO | started process 11766 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp36hm6X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:23 INFO - PROCESS | 11766 | 04:49:23 INFO - PROCESS | 11766 | (process:11824): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:49:33 INFO - PROCESS | 11766 | RSS: Main: 165048320 04:49:33 INFO - PROCESS | 11766 | 04:50:23 INFO - PROCESS | 11766 | 1461585023136 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] 04:52:34 INFO - PROCESS | 11766 | Cycle 1(1): loaded http://localhost:51642/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51642/tests/dromaeo/cssquery-ext.html) 04:52:35 INFO - PROCESS | 11766 | RSS: Main: 169431040 04:52:35 INFO - PROCESS | 11766 | 04:55:38 INFO - PROCESS | 11766 | Cycle 1(1): loaded http://localhost:51642/tests/dromaeo/cssquery-ext.html (next: http://localhost:51642/tests/dromaeo/cssquery-jquery.html) 04:55:38 INFO - PROCESS | 11766 | RSS: Main: 166211584 04:55:38 INFO - PROCESS | 11766 | 04:58:40 INFO - PROCESS | 11766 | Cycle 1(1): loaded http://localhost:51642/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51642/tests/dromaeo/cssquery-mootools.html) 04:58:41 INFO - PROCESS | 11766 | RSS: Main: 167563264 04:58:41 INFO - PROCESS | 11766 | 05:01:43 INFO - PROCESS | 11766 | Cycle 1(1): loaded http://localhost:51642/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51642/tests/dromaeo/cssquery-prototype.html) 05:01:43 INFO - PROCESS | 11766 | RSS: Main: 164827136 05:01:43 INFO - PROCESS | 11766 | 05:04:46 INFO - PROCESS | 11766 | Cycle 1(1): loaded http://localhost:51642/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51642/tests/dromaeo/cssquery-yui.html) 05:04:47 INFO - PROCESS | 11766 | RSS: Main: 166240256 05:04:47 INFO - PROCESS | 11766 | 05:07:49 INFO - PROCESS | 11766 | Cycle 1(1): loaded http://localhost:51642/tests/dromaeo/cssquery-yui.html (next: http://localhost:51642/tests/dromaeo/cssquery-dojo.html) 05:07:49 INFO - PROCESS | 11766 | RSS: Main: 168415232 05:07:49 INFO - PROCESS | 11766 | 05:07:49 INFO - PROCESS | 11766 | __start_tp_report 05:07:49 INFO - PROCESS | 11766 | _x_x_mozilla_page_load 05:07:49 INFO - PROCESS | 11766 | _x_x_mozilla_page_load_details 05:07:49 INFO - PROCESS | 11766 | |i|pagename|runs| 05:07:49 INFO - PROCESS | 11766 | |0;dojo.html;2640.1947406041154;2688.099486671965;2708.7020427752946;2710.4443589064244;2720.8639568021595;4810.110129626018;4831.68594041387;4835.58897493713;4855.126077306083;4894.902101957959;4689.320048592952;4727.763611819404;4780.450248221456;4865.026994601077;4866.1240976624285;4412.933805992905;4438.7045499702035;4491.056878055612;4496.595306422421;4541.523140070294;638.2699188184212;4391.802368893407;4477;4505.819767209324;4548.590626843584;4477.731336119827;4488.349189367524;4501.887452813706;4509.233430316821;4543.386642803212;4262.531121576624;4545.116601819064;4549.772511374451;4558.817647294104;4578.153041687298;4343.956560434387;4380.518143004267;4383.736975781463;4410.029793445437;4473.4408198975125;4663.440387153563;4678.6491013174145;4688.203005489075;4739.099571081483;4764.547368000034;5102.413222479388;5124.154514505102;5161.638685292031;5167.534921857014;5251.159814429673;5176.844694659167;5187.8184263550975;5205.401378841444;5224.555912747421;5227.764750586232;4837.95162048382;4838.29844672524;4846.660733748604;4851.733154676456;4853.733044682505;5314.707691077027;5321.09541377959;5341.599380046512;5376;5392.784288628411;5093.516115968977;5099.974500127476;5109.770060347293;5113.9744301279;5152.046871328741;5056.974715126401;5181.326427564392;5195.766190521435;5195.766190521435;5204.843854684365;5148.099082660519;5169.534741873174;5185.2999845021195;5186.974065129651;5231.581473482112;4915.803367865316;5100.617453690988;5128.769205385766;5140.794368225229;5167.844964651066;4248.702590818613;4310;4312.654987600984;4351.108022855307;4369.191699535596;3779.055236190952;3824.6366595173413;3833.750806197588;3858.3247931611854;3863.8454461821775;4434.889127771767;4457.153140903218;4471.597556219956;4471.8658440246845;4512.165249428866;5176.145935920531;5191.377034755854;5197.662151960186;5224.9738751306;5305.230741542532;4461.464624245112;4461.8399216204025;4463.218936686067;4480.887977800516;4505.414296141481;4560.270356742905;4577.359169716243;4583.083383323348;4583.793729282191;4633.559811817872;4738.502457242009;4860.416749989953;4900.313956046228;4982.352294201731;4984.526469985346;5163.870903227449;5192.74036298176;5196.350456192976;5204.843854684365;5210.53105220532;4868.561829435368;4869.123557759495;4871.878203044952;4876.097921884463;4880.975595122001;5022.146235139962;5136.512031357015;5158.509941555546;5158.97420512895;5200.60995425337;5207.114790485551;5231.581473482036;5281.366236051698;5295.841124766263;5350.785968561214;4921.458639549718;5111.514406131411;5119.539241468286;5122.154844450624;5130.589552835839;4894.363732714724;5020.071286811952;5047.217681259411;5083.466236045162;5109.3613298337705;5027.321311622884;5036.370453693288;5048.469910659474;5049.444561098349;5113.821016264413;5006.624503162205;5082.517160869711;5127.615428842926;5128.846134615818;5182.352205974254;4965.875853103701;4980.32765576657;4989.151844186569;5025.899482010266;5029 05:07:49 INFO - PROCESS | 11766 | |1;ext.html;571.1404336473606;582.3070546050201;590.235644839932;606.966616836074;641.566942313938;33151.171220719465;34022.31955360891;34022.65977340226;34041.14897127573;34096.65903340966;34231.65768342316;34710.305793884174;34732.652673473254;34783.82608086956;34925.82537087317;7550.282723141307;7728.459007869451;7738.883916741253;7740.535567865918;7740.729074482394;7467;7651.732189373373;7713.73001944932;7717.305442510173;7721.72973945912;7715.267049630276;7723.53658780475;7754.612269386536;7781;7827.099883513412;7661.233876612322;7681.692732290701;7693;7743.806404839867;7743.8838417423785;7682.692692292301;7750.263724946121;7783.143854176036;7785.844283114363;7820.921790782133;28454;35669.28661426783;36002.09994750126;36085.27829443396;36156.02283748786;2718.7009428962797;2807.550791873311;3002;3211.341674956628;3287.3260981498975;26325;27293.454130917467;28536.28659283514;28614.856925715238;28968.27579310513;3401.846916888746;3565.6612621800887;3582.9641703582893;3606.296772129436;3632.891013269606;197464;199763;200067;200189;201332.99333503385;52518.737406313485;53178.202326965125;53220.46779532233;53265.2010219847;53306.46693533015;54317.72841135769;60319.69840150771;62639.373606264264;62643.6867815658;62757.372426276066;13046.41291141901;13075.476980920846;13100.279484628438;13159;13167.736645267041;10401.27191096616;10467.685969420929;10497.055265026183;10530.57877684884;10547.472626368652;18481.445556633324;18659.720104198448;18803.435896923114;18898.433047008613;19665.901670491556;7350.926490735131;7578.227426583083;7582.393408527305;7698.651911167036;7752.147263800977;884.5577211394303;903.1420150856582;904.3669431398048;974.6783561424713;982.6511588386163;27137;27783;27865.395378588055;27874.721252787618;28315.43369132606;996.6661168508485;1006.3810756384771;1052.3843551522446;1071.0307172009188;1111.5331560744507;14393.280335983369;14932.402703891723;15035.473758418402;15155.166465844484;15172.165530895685;349368;355599;355748;356230;357156;47545.286820697715;48312.516874831505;48346.5165348342;48610;48658.27012594814;1186.8805242377773;1207.6497815633543;1227.5274019502376;1240.2186622427994;1240.7332423528985;26120;27823.582646260726;27930.301742456602;28111.578326324714;28179.859100704365;42200.36699449449;42421.363679544214;43040.35439468473;43085.56914430815;43122;318253;319001;319152;319795;320241;24770.380740481633;25358;25381.492370152864;25381.492370152864;38283.425748613234;36739;37398.252034959696;37494.81252593829;37664;37792.2441551162;31924.8403757989;32634.34731305313;34268.31463370768;34881;35125.824370877985;33919;33997.49003764995;34157.31685366329;34648;34745.652543475255;34515.4822677655;34910.476342855385;36946.44580331243;38205.61794382021;38260.234795304495;28945.56581651235;30337.848310758305;30731.846340769047;30910;31316.217094571908;23735.52528949377;24389;24438.51122977566;24673;24690.753092469564 05:07:49 INFO - PROCESS | 11766 | |2;jquery.html;376.59704116595236;382.25651108593786;385.65483891916716;389.14582491431304;392.6015094678903;15636.92181539092;15971.680566388664;16850.32598696054;16912.577185570368;16989.490315290546;13963.720725585508;15365.462208822693;16346.427875024376;16346.509604711848;16412.17939103046;4608.193566125923;4719.787609557578;4776.641751868607;4778.593819525345;4779.450363208241;3471.305738852227;5133.255677926699;5386.703731294777;5412.648177868446;5420.214068959998;5386.626410265377;5392.622516423852;5393.865153371177;5398.91901621476;5411.540019098381;4787.042591481701;5076.720780357079;5079.542841144278;5118.564921981618;5120.257562653432;4917.606591472673;4943.3573635427165;4945.629077819142;5426.538744206747;5428.4028756836715;18876.81123188778;19484.80515194844;19912.70130948037;20112.698309525367;20123.195072197243;1903.4765439504108;1945.8929758863258;1960.6764883794158;2032.8780273183656;2162.48100455891;9119.224865886408;9228.123328283802;9303.069693030711;9498.667546635834;9582.233421326277;1692.0439951427527;1761.6124452620531;1761.7357396390516;1823.2342416185102;1833.3766519383341;69951.65024174846;69991;70727.64636176838;71570.28429715689;71700.6414967927;9935.950320248352;10001.54993025301;10086.949565252127;10165.695029149138;10371.274010819321;14103.506377276746;14188.148711077369;14249.216293103978;14265.857341426661;14281.357338919774;6848.623325717133;6913.481488888354;6957.721691132399;7007.178133685278;7086.397656199178;6461.8061458156335;6465.967670161619;6506.154199953976;6573.80278591643;6627;4350.434443522322;4377.518472967971;4417.0724147929295;4422.469303683514;4520.028193938319;1714.9657006859793;1774.4119013483119;1810.8732388732926;1834.3763120538813;1847.1410793980863;835.2941764209233;850.5152063323847;868.3530769576706;910.4082346474844;924.8520236762085;15203.239838008056;15230.314635841414;15543.611409714622;16413.261403236884;16643.91678041602;860.4794099569796;883.7702197428715;920.7790130368797;946.1012038563392;950.9429434233969;9085.318601104944;9385.202257808027;9401.811963760687;9419.011003844635;9619.27855410833;390092.2014399924;399890;417602;423757;423819;46533.76733116313;53283.73358133184;78960;80512.59743701361;82311.5884420574;926.9490178040272;949.3829011142674;951.5147274889717;975.327024353194;982.9852552211722;13579.932100339434;13840.584782456543;14020.78968815489;14298.070625409315;14351.569452916428;33938.66061339454;34516.309673806885;34890.162082171446;35451;35693.28613427665;45779.31331029971;45917.31124033209;48101.278480823516;48167;48326.75836620794;7711;7722.652480638272;7838.529688218725;7879.684812607432;7884.960575197088;9993;10910.399928004035;10933.234673572773;11295.265807722471;11647.30116193031;9581.952090239503;9866.671997121473;10281.383117012703;10472.214583906085;10586;10123.848142277724;10235.94882025615;10287.485625718535;10808.243422960632;10965.396903170402;9887.604495820087;9981.650642227487;10102.141317988053;10119.645812396531;10528.99974502421;7522.210167932507;7708.152103268748;7774.30031297186;7935.920640793518;8107.554084525172;10003.2997690161;10246;10263;10328.896711032794;10477.952204779462 05:07:49 INFO - PROCESS | 11766 | |3;mootools.html;1421.8435972043071;1442.8917831162662;1556.968860622787;1672.9711227595033;1713.7600735896976;7095.7161713531395;7505.849883002336;7713.92286077139;7737.651805668751;7750.57371844549;7847.176046515105;7857.764267071982;7860.096088949773;7957;7973.481723687971;2291.415688999303;2321.9883900580476;2326.336993956723;2332.288651961151;2332.54515369503;2278.1798552521077;2278.9886050569726;2311.849729767568;2315.1896836107394;2321.721393432782;2237.3623517297574;2263.513344630904;2268.376196545955;2316;2322.9883850580723;2241.081156725743;2265.5355652091366;2266.7733226677365;2269.8751568663633;2275.8520696154865;2244.483768733184;2250.4036430345973;2259.186692790594;2261.9660705089436;2266.7959883610556;7984.3612510999565;8070.838583228361;8135.918640813576;8153.714619988331;8164.836703265961;4730.668853180278;4915.410150781893;4951.554360107608;4951.950480495185;4953.851384458472;2935.7082883531393;2938.41231753648;3029.3789773096464;3038.848057597111;3051.7711171662213;982.6855406269997;989.2580564576567;998.3859926145441;1002.5960075194706;1034.6275340877278;11084.833727494093;11108.333499990027;11113.555457781678;11150.721231969184;11169.888301117047;4273.529911709709;4374.081442896963;4492.887677808016;4551.249043907785;4624.3294722265755;9387.812243755086;9431.009743976922;9436.858447123299;9462.763430914281;9483.525823708787;4739.241721324572;4769.642276829251;4776.570108690165;4795.424549054136;4799.232122860326;3827.8660246891227;4332.415123958225;4344.261475549164;4457.8439754608435;4466.59800617946;3706.666400023957;3773.8490460381277;3858.7105967052585;3876.0503676599415;3909.3158697227873;1258.4211262819022;1262.2174251963659;1274.7068174319772;1280.667026573079;1291.7222797098668;728.4682182007059;746.7349091072591;778.4006315137312;782.1591788827034;811.3306522119275;5258.2638430619745;5365.087935050972;5447.727613619303;5464.754086066137;5467.261919640877;406.5081251685435;406.89217357400315;414.57713132604573;427.68351419949016;433.92406328892315;3536.3634545781492;3607.845489443353;3639.6542328478754;3657.9634203657624;3675.026118078711;9793.755156121155;10001.899981000242;10155.187584993182;10186.796264074677;10189.133923616428;10025.348352357074;10035.598576057022;10060.39637621745;10106.343087699275;10127.291089623655;7226.421886249087;7564.621768911533;7622.771316860503;7703.576303303372;7711.691532338756;5318.255444237732;5349.384820745664;5353.946460535501;5478.397376288684;5484.122540393678;8693.174148455886;8887;9014.32392570573;9026.548672566476;9046.592903319235;8787.604557794786;9085.182333590008;9172.541372931193;9242.214411775074;9262.120098590623;6356.396142366468;6526.608403495806;6696.1629796275465;6787.796366109024;6951.165860096618;7633.923660763321;7661.770146895602;7677.8464430709955;7716.807079823049;7717;6675.766348177791;6883.655817209019;6924.030635711105;6978.406835419046;6998.825029374307;6697.3302669734585;6729.5625784323865;6736.696848641921;6749.257581665915;6831.419329357061;6362.77730279438;6395.93604063972;6430.067640192239;6436.871262574816;6466.0947467355545;4610.2854057621125;4632.907341853212;4637.791299391603;4654.511276316073;4736.550027747358;4514.142312960527;4622.653301002371;4694.483606803181;4741.810327586858;4761.333413322207 05:07:49 INFO - PROCESS | 11766 | |4;prototype.html;314.67274035003595;317.8156669131902;319.6739325887593;320.34969012903787;320.60885719422305;5238.973805130974;5298.390685071218;5360.892782144355;5378.1663842104435;5405.729713514318;5303.65526240794;5318.643745844814;5332.680039197641;5356.812511562096;5358.946410535894;3883.8446462141483;4004.719669623113;4103.733257338278;4115.773632450214;4121.9381709274385;4098.303288440958;4127.484064491939;4142.357934520139;4147.585241475858;4152.584741525853;4117.876463706094;4118.423420721101;4128.958710412902;4129.710920235585;4143.8964025899295;4043.2924238258483;4102.733322334068;4125.525564560054;4136.172765446892;4136.800327904904;3596.856125754967;3613.1147868772055;3616.439451884962;3637.563492380905;3650.9817450912574;4978.551930326288;5003.399592048941;5014.197728363481;5030.748462576893;5038.899222015576;4626.329182268586;4796.304535842283;4800.423949126092;4802.399700037496;4811.903761924742;5559.638623489501;5584.738181036687;5603.299587551556;5611.494965453088;5619.157126431027;3976.5228172619472;3986.362182050858;3991.7604943703473;3993.900152496182;4019.8191081401114;5624.21261023457;5879.617824841372;5897.115432685088;5899.970500147515;5953.315368732608;3997.1006523532556;4010.959890401117;4012;4022.356422972369;4029.657479114308;4036.858709945138;4041.3533834586324;4056.006278461796;4087.6729861611;4088.8364465421646;4593.106343003886;4673.228917228687;4677.391939047902;4697.718136911796;4702.6708130431225;5648.604597678123;5673.347565030074;5676.290463692038;5678.602497825113;5682.346530149003;1386.3484162443635;1391.87473127417;1407.5636552668705;1407.6340151560669;1413.8303403591635;854.3848429131016;922.7646950027835;932.5989824375448;949.7245798718433;954.2985905359556;4846.7818948147415;4861.927071093936;4911.16510193268;4916.4110199554525;4928.753562321941;5739.942600573941;5779.3642699303045;5782.88434231313;5787.536997040227;5833.358330583717;4752.14461396945;4823.589994850477;4856.902861942742;4868.030501394147;4873.245631572631;3075.3234288456506;3092.4742793725122;3093.6442309134736;3099.7985130966417;3114.159177022191;5353.9732301338245;5376.78492860289;5381.48875856793;5398.8920221595345;5400.83797486076;5773.8556536086935;5815.185873977646;5925.288965324189;5954.166416701579;5969.134475501028;5330.200469929464;5330.280412144389;5363.168708850057;5378.247045413724;5390.380106287748;5660.858478537904;5738.741756620879;5746.741396637245;5766.913496297643;5852.4147585241135;5659.037963546125;5663.518600918968;5681;5687.20379146928;5705.115707065412;5350.678959262456;5352.839414817562;5364.597655175956;5384.461553844584;5414.133738601805;3897.902552436212;3972.5431575368048;3984.242993831163;3993.940090898697;3995.580464051233;5705.885882282414;5712.828615141552;5728.742206600797;5731.5701322400155;5755.5683323750045;5546.667199968015;5551.500364967011;5559.24950131727;5566.554675625859;5640.210370548209;5370.946290537201;5539.833804985857;5558.833235002957;5559.138333558305;5573.108302671715;5297.443768404262;5497.8075767347955;5502.367227768697;5510.035743744748;5526.143447765603;5571.498565129158;5743.827685169452;5747.4252574742195;5751.74117164737;5755.309362876482;5547.8890422192135;5550.613236308264;5553.111502159797;5559.833205003856;5560.582956278215 05:07:49 INFO - PROCESS | 11766 | |5;yui.html;588.0955209694665;645.7804346522181;654.8493846415328;656.9835754106148;661.0711949710658;11489.425528723561;11686.649400517997;11692;11693.181477296592;11878.7624247515;11460.656180314583;11884.881151188485;12003.819942700866;12023;12997.155184912974;768.4352001279055;772.98840517392;775.0428221146907;776.4099284543731;777.6228529163351;756.9697212111508;763.4083585221442;764.4266799900075;768.5734417398342;769.6959700918134;797.8085259537698;802.4022103532847;802.6869520886829;803.1927912448011;809.1261437647327;807.4590024683476;809.2150613904503;809.5547448903138;810.4610434061342;813.6826637611277;599.2090440618385;601.5247954116243;601.8104297146429;602.6866029664594;603.9818805435845;11927.463264153133;12017.699557511045;12051.758964820741;12062.758744825142;12089.6373108806;11889.643310700607;11907.85751858432;12303.93848030763;12348.320842353667;12591.874081259162;9216.801815763974;9839.409635421825;11144.554217831277;11207.887921120848;11483.368414737186;3574.3816060808144;3594.1733401317583;3646.9038734010455;3653.2693461307845;3676.1361080146144;38427;41963.58036419627;43649.78175109104;45480;46402.535974640494;8406.327493800482;8482.703105391281;8866.379353445325;8869.02440731519;8894.866577001221;16365.427210047592;16387;16387.262573184234;16429.096399697894;16479.670406591802;6879.5184337096925;7021.087258656342;7053.89419158713;7063.89404158938;7237.167725711505;3719.888403347904;3806.1626442182674;3844.519435070616;3850.383938569815;3972.2055588881763;2794.455081259135;2817.1689351641317;2851.6435445569305;2862.298736809453;2865.412590418959;510.07930685112984;519.0294149939637;522.334024119251;527.309224915362;535.5260594374007;440.33509400805013;464.7443905851768;542.9548119869236;547.7896686868467;549.8240563019796;7165.92834071663;7231.5299505531975;7258.891116633255;7462.440316976248;7540.509866858637;470.9105269998689;566.980155694557;569.3765326966965;591.9319278283052;600.8828278485653;3332.250243695149;3379.898603041913;3449.3963556378035;3521.154922818505;3605.0446631642635;48363.27455088177;48377.75811120992;49168;49336.75331623319;49636.503634963185;43770.56229437728;43855;43875.12249754987;43910;44568.55431445644;34659.653403466145;38862.02844928843;40868;41204.587954120674;41348.1730365385;7005.684744186422;7181.569105853666;7325.560466372035;7394.852102957803;7586.431017673807;25543.87228063848;25927.22218333453;28229.294267643472;29554.113376598736;29575.408491829614;33376;34823.825880870434;35299.29401412009;35424.46863297104;36474;9605.327627066039;9949.104580587782;9956;10005.949970250102;10210.132138768287;10077.596896124072;10082.596696132072;10318;10392.792144156923;10480;9892.208623310262;10113.19094472455;10165.288429809842;10181.74545636365;10326.845097323696;9436.433813971184;9686.854697179408;10482.68551943443;10658.200634952254;10835.891641083488;10216.335938163995;10346;10404.58381664725;10525.736856578647;10643.8935610646;8636.611352489028;8796.91203087961;8890.422122562013;9194.00667804552;9281.303902207226;8456.40805143634;8695.347848911231;8834.690785822466;9034.503102329434;9090.13643703847 05:07:49 INFO - PROCESS | 11766 | __end_tp_report 05:07:49 INFO - PROCESS | 11766 | __start_cc_report 05:07:49 INFO - PROCESS | 11766 | _x_x_mozilla_cycle_collect,362 05:07:49 INFO - PROCESS | 11766 | __end_cc_report 05:07:49 INFO - PROCESS | 11766 | __startTimestamp1461586069554__endTimestamp 05:07:49 INFO - PROCESS | 11766 | 05:07:49 INFO - PROCESS | 11766 | ------- Summary: start ------- 05:07:49 INFO - PROCESS | 11766 | Number of tests: 6 05:07:49 INFO - PROCESS | 11766 | 05:07:49 INFO - PROCESS | 11766 | [#0] dojo.html Cycles:165 Average:4766.02 Median:4900.31 stddev:604.53 (12.3%) stddev-sans-first:582.93 05:07:49 INFO - PROCESS | 11766 | Values: 2640.2 2688.1 2708.7 2710.4 2720.9 4810.1 4831.7 4835.6 4855.1 4894.9 4689.3 4727.8 4780.5 4865.0 4866.1 4412.9 4438.7 4491.1 4496.6 4541.5 638.3 4391.8 4477.0 4505.8 4548.6 4477.7 4488.3 4501.9 4509.2 4543.4 4262.5 4545.1 4549.8 4558.8 4578.2 4344.0 4380.5 4383.7 4410.0 4473.4 4663.4 4678.6 4688.2 4739.1 4764.5 5102.4 5124.2 5161.6 5167.5 5251.2 5176.8 5187.8 5205.4 5224.6 5227.8 4838.0 4838.3 4846.7 4851.7 4853.7 5314.7 5321.1 5341.6 5376.0 5392.8 5093.5 5100.0 5109.8 5114.0 5152.0 5057.0 5181.3 5195.8 5195.8 5204.8 5148.1 5169.5 5185.3 5187.0 5231.6 4915.8 5100.6 5128.8 5140.8 5167.8 4248.7 4310.0 4312.7 4351.1 4369.2 3779.1 3824.6 3833.8 3858.3 3863.8 4434.9 4457.2 4471.6 4471.9 4512.2 5176.1 5191.4 5197.7 5225.0 5305.2 4461.5 4461.8 4463.2 4480.9 4505.4 4560.3 4577.4 4583.1 4583.8 4633.6 4738.5 4860.4 4900.3 4982.4 4984.5 5163.9 5192.7 5196.4 5204.8 5210.5 4868.6 4869.1 4871.9 4876.1 4881.0 5022.1 5136.5 5158.5 5159.0 5200.6 5207.1 5231.6 5281.4 5295.8 5350.8 4921.5 5111.5 5119.5 5122.2 5130.6 4894.4 5020.1 5047.2 5083.5 5109.4 5027.3 5036.4 5048.5 5049.4 5113.8 5006.6 5082.5 5127.6 5128.8 5182.4 4965.9 4980.3 4989.2 5025.9 5029.0 05:07:49 INFO - PROCESS | 11766 | 05:07:49 INFO - PROCESS | 11766 | [#1] ext.html Cycles:180 Average:44545.96 Median:34739.15 stddev:78653.15 (226.4%) stddev-sans-first:78804.50 05:07:49 INFO - PROCESS | 11766 | Values: 571.1 582.3 590.2 607.0 641.6 33151.2 34022.3 34022.7 34041.1 34096.7 34231.7 34710.3 34732.7 34783.8 34925.8 7550.3 7728.5 7738.9 7740.5 7740.7 7467.0 7651.7 7713.7 7717.3 7721.7 7715.3 7723.5 7754.6 7781.0 7827.1 7661.2 7681.7 7693.0 7743.8 7743.9 7682.7 7750.3 7783.1 7785.8 7820.9 28454.0 35669.3 36002.1 36085.3 36156.0 2718.7 2807.6 3002.0 3211.3 3287.3 26325.0 27293.5 28536.3 28614.9 28968.3 3401.8 3565.7 3583.0 3606.3 3632.9 197464.0 199763.0 200067.0 200189.0 201333.0 52518.7 53178.2 53220.5 53265.2 53306.5 54317.7 60319.7 62639.4 62643.7 62757.4 13046.4 13075.5 13100.3 13159.0 13167.7 10401.3 10467.7 10497.1 10530.6 10547.5 18481.4 18659.7 18803.4 18898.4 19665.9 7350.9 7578.2 7582.4 7698.7 7752.1 884.6 903.1 904.4 974.7 982.7 27137.0 27783.0 27865.4 27874.7 28315.4 996.7 1006.4 1052.4 1071.0 1111.5 14393.3 14932.4 15035.5 15155.2 15172.2 349368.0 355599.0 355748.0 356230.0 357156.0 47545.3 48312.5 48346.5 48610.0 48658.3 1186.9 1207.6 1227.5 1240.2 1240.7 26120.0 27823.6 27930.3 28111.6 28179.9 42200.4 42421.4 43040.4 43085.6 43122.0 318253.0 319001.0 319152.0 319795.0 320241.0 24770.4 25358.0 25381.5 25381.5 38283.4 36739.0 37398.3 37494.8 37664.0 37792.2 31924.8 32634.3 34268.3 34881.0 35125.8 33919.0 33997.5 34157.3 34648.0 34745.7 34515.5 34910.5 36946.4 38205.6 38260.2 28945.6 30337.8 30731.8 30910.0 31316.2 23735.5 24389.0 24438.5 24673.0 24690.8 05:07:49 INFO - PROCESS | 11766 | 05:07:49 INFO - PROCESS | 11766 | [#2] jquery.html Cycles:180 Average:24347.73 Median:45848.31 stddev:67663.65 (147.6%) stddev-sans-first:67829.53 05:07:49 INFO - PROCESS | 11766 | Values: 376.6 382.3 385.7 389.1 392.6 15636.9 15971.7 16850.3 16912.6 16989.5 13963.7 15365.5 16346.4 16346.5 16412.2 4608.2 4719.8 4776.6 4778.6 4779.5 3471.3 5133.3 5386.7 5412.6 5420.2 5386.6 5392.6 5393.9 5398.9 5411.5 4787.0 5076.7 5079.5 5118.6 5120.3 4917.6 4943.4 4945.6 5426.5 5428.4 18876.8 19484.8 19912.7 20112.7 20123.2 1903.5 1945.9 1960.7 2032.9 2162.5 9119.2 9228.1 9303.1 9498.7 9582.2 1692.0 1761.6 1761.7 1823.2 1833.4 69951.7 69991.0 70727.6 71570.3 71700.6 9936.0 10001.5 10086.9 10165.7 10371.3 14103.5 14188.1 14249.2 14265.9 14281.4 6848.6 6913.5 6957.7 7007.2 7086.4 6461.8 6466.0 6506.2 6573.8 6627.0 4350.4 4377.5 4417.1 4422.5 4520.0 1715.0 1774.4 1810.9 1834.4 1847.1 835.3 850.5 868.4 910.4 924.9 15203.2 15230.3 15543.6 16413.3 16643.9 860.5 883.8 920.8 946.1 950.9 9085.3 9385.2 9401.8 9419.0 9619.3 390092.2 399890.0 417602.0 423757.0 423819.0 46533.8 53283.7 78960.0 80512.6 82311.6 926.9 949.4 951.5 975.3 983.0 13579.9 13840.6 14020.8 14298.1 14351.6 33938.7 34516.3 34890.2 35451.0 35693.3 45779.3 45917.3 48101.3 48167.0 48326.8 7711.0 7722.7 7838.5 7879.7 7885.0 9993.0 10910.4 10933.2 11295.3 11647.3 9582.0 9866.7 10281.4 10472.2 10586.0 10123.8 10235.9 10287.5 10808.2 10965.4 9887.6 9981.7 10102.1 10119.6 10529.0 7522.2 7708.2 7774.3 7935.9 8107.6 10003.3 10246.0 10263.0 10328.9 10478.0 05:07:49 INFO - PROCESS | 11766 | 05:07:49 INFO - PROCESS | 11766 | [#3] mootools.html Cycles:180 Average:5269.93 Median:4740.53 stddev:2964.56 (62.5%) stddev-sans-first:2958.77 05:07:49 INFO - PROCESS | 11766 | Values: 1421.8 1442.9 1557.0 1673.0 1713.8 7095.7 7505.8 7713.9 7737.7 7750.6 7847.2 7857.8 7860.1 7957.0 7973.5 2291.4 2322.0 2326.3 2332.3 2332.5 2278.2 2279.0 2311.8 2315.2 2321.7 2237.4 2263.5 2268.4 2316.0 2323.0 2241.1 2265.5 2266.8 2269.9 2275.9 2244.5 2250.4 2259.2 2262.0 2266.8 7984.4 8070.8 8135.9 8153.7 8164.8 4730.7 4915.4 4951.6 4952.0 4953.9 2935.7 2938.4 3029.4 3038.8 3051.8 982.7 989.3 998.4 1002.6 1034.6 11084.8 11108.3 11113.6 11150.7 11169.9 4273.5 4374.1 4492.9 4551.2 4624.3 9387.8 9431.0 9436.9 9462.8 9483.5 4739.2 4769.6 4776.6 4795.4 4799.2 3827.9 4332.4 4344.3 4457.8 4466.6 3706.7 3773.8 3858.7 3876.1 3909.3 1258.4 1262.2 1274.7 1280.7 1291.7 728.5 746.7 778.4 782.2 811.3 5258.3 5365.1 5447.7 5464.8 5467.3 406.5 406.9 414.6 427.7 433.9 3536.4 3607.8 3639.7 3658.0 3675.0 9793.8 10001.9 10155.2 10186.8 10189.1 10025.3 10035.6 10060.4 10106.3 10127.3 7226.4 7564.6 7622.8 7703.6 7711.7 5318.3 5349.4 5353.9 5478.4 5484.1 8693.2 8887.0 9014.3 9026.5 9046.6 8787.6 9085.2 9172.5 9242.2 9262.1 6356.4 6526.6 6696.2 6787.8 6951.2 7633.9 7661.8 7677.8 7716.8 7717.0 6675.8 6883.7 6924.0 6978.4 6998.8 6697.3 6729.6 6736.7 6749.3 6831.4 6362.8 6395.9 6430.1 6436.9 6466.1 4610.3 4632.9 4637.8 4654.5 4736.6 4514.1 4622.7 4694.5 4741.8 4761.3 05:07:49 INFO - PROCESS | 11766 | 05:07:49 INFO - PROCESS | 11766 | [#4] prototype.html Cycles:180 Average:4626.57 Median:5330.24 stddev:1359.59 (25.5%) stddev-sans-first:1324.33 05:07:49 INFO - PROCESS | 11766 | Values: 314.7 317.8 319.7 320.3 320.6 5239.0 5298.4 5360.9 5378.2 5405.7 5303.7 5318.6 5332.7 5356.8 5358.9 3883.8 4004.7 4103.7 4115.8 4121.9 4098.3 4127.5 4142.4 4147.6 4152.6 4117.9 4118.4 4129.0 4129.7 4143.9 4043.3 4102.7 4125.5 4136.2 4136.8 3596.9 3613.1 3616.4 3637.6 3651.0 4978.6 5003.4 5014.2 5030.7 5038.9 4626.3 4796.3 4800.4 4802.4 4811.9 5559.6 5584.7 5603.3 5611.5 5619.2 3976.5 3986.4 3991.8 3993.9 4019.8 5624.2 5879.6 5897.1 5900.0 5953.3 3997.1 4011.0 4012.0 4022.4 4029.7 4036.9 4041.4 4056.0 4087.7 4088.8 4593.1 4673.2 4677.4 4697.7 4702.7 5648.6 5673.3 5676.3 5678.6 5682.3 1386.3 1391.9 1407.6 1407.6 1413.8 854.4 922.8 932.6 949.7 954.3 4846.8 4861.9 4911.2 4916.4 4928.8 5739.9 5779.4 5782.9 5787.5 5833.4 4752.1 4823.6 4856.9 4868.0 4873.2 3075.3 3092.5 3093.6 3099.8 3114.2 5354.0 5376.8 5381.5 5398.9 5400.8 5773.9 5815.2 5925.3 5954.2 5969.1 5330.2 5330.3 5363.2 5378.2 5390.4 5660.9 5738.7 5746.7 5766.9 5852.4 5659.0 5663.5 5681.0 5687.2 5705.1 5350.7 5352.8 5364.6 5384.5 5414.1 3897.9 3972.5 3984.2 3993.9 3995.6 5705.9 5712.8 5728.7 5731.6 5755.6 5546.7 5551.5 5559.2 5566.6 5640.2 5370.9 5539.8 5558.8 5559.1 5573.1 5297.4 5497.8 5502.4 5510.0 5526.1 5571.5 5743.8 5747.4 5751.7 5755.3 5547.9 5550.6 5553.1 5559.8 5560.6 05:07:49 INFO - PROCESS | 11766 | 05:07:49 INFO - PROCESS | 11766 | [#5] yui.html Cycles:180 Average:12008.22 Median:45024.28 stddev:13476.58 (29.9%) stddev-sans-first:13487.09 05:07:49 INFO - PROCESS | 11766 | Values: 588.1 645.8 654.8 657.0 661.1 11489.4 11686.6 11692.0 11693.2 11878.8 11460.7 11884.9 12003.8 12023.0 12997.2 768.4 773.0 775.0 776.4 777.6 757.0 763.4 764.4 768.6 769.7 797.8 802.4 802.7 803.2 809.1 807.5 809.2 809.6 810.5 813.7 599.2 601.5 601.8 602.7 604.0 11927.5 12017.7 12051.8 12062.8 12089.6 11889.6 11907.9 12303.9 12348.3 12591.9 9216.8 9839.4 11144.6 11207.9 11483.4 3574.4 3594.2 3646.9 3653.3 3676.1 38427.0 41963.6 43649.8 45480.0 46402.5 8406.3 8482.7 8866.4 8869.0 8894.9 16365.4 16387.0 16387.3 16429.1 16479.7 6879.5 7021.1 7053.9 7063.9 7237.2 3719.9 3806.2 3844.5 3850.4 3972.2 2794.5 2817.2 2851.6 2862.3 2865.4 510.1 519.0 522.3 527.3 535.5 440.3 464.7 543.0 547.8 549.8 7165.9 7231.5 7258.9 7462.4 7540.5 470.9 567.0 569.4 591.9 600.9 3332.3 3379.9 3449.4 3521.2 3605.0 48363.3 48377.8 49168.0 49336.8 49636.5 43770.6 43855.0 43875.1 43910.0 44568.6 34659.7 38862.0 40868.0 41204.6 41348.2 7005.7 7181.6 7325.6 7394.9 7586.4 25543.9 25927.2 28229.3 29554.1 29575.4 33376.0 34823.8 35299.3 35424.5 36474.0 9605.3 9949.1 9956.0 10005.9 10210.1 10077.6 10082.6 10318.0 10392.8 10480.0 9892.2 10113.2 10165.3 10181.7 10326.8 9436.4 9686.9 10482.7 10658.2 10835.9 10216.3 10346.0 10404.6 10525.7 10643.9 8636.6 8796.9 8890.4 9194.0 9281.3 8456.4 8695.3 8834.7 9034.5 9090.1 05:07:49 INFO - PROCESS | 11766 | -------- Summary: end -------- 05:07:49 INFO - PROCESS | 11766 | 05:07:50 INFO - TEST-INFO | 11766: exit 0 05:07:50 INFO - TEST-OK | dromaeo_css | took 1115921ms 05:07:50 INFO - TEST-START | kraken 05:07:50 INFO - operating with platform_type : linux_ 05:07:50 INFO - Initialising browser for kraken test... 05:07:50 INFO - TEST-INFO | started process 11996 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvh1zlc/profile http://localhost:51642/getInfo.html) 05:07:54 INFO - PROCESS | 11996 | 05:07:54 INFO - PROCESS | 11996 | (process:12064): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:07:55 INFO - PROCESS | 11996 | __metrics Screen width/height:1600/1200 05:07:55 INFO - PROCESS | 11996 | colorDepth:24 05:07:55 INFO - PROCESS | 11996 | Browser inner width/height: 1024/697 05:07:55 INFO - PROCESS | 11996 | __metrics 05:07:56 ERROR - PROCESS | 11996 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:07:57 INFO - TEST-INFO | 11996: exit 0 05:07:57 INFO - Browser initialized. 05:07:57 INFO - Running cycle 1/1 for kraken test... 05:07:57 INFO - TEST-INFO | started process 12115 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvh1zlc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:59 INFO - PROCESS | 12115 | 05:07:59 INFO - PROCESS | 12115 | (process:12175): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:08:09 INFO - PROCESS | 12115 | RSS: Main: 164720640 05:08:09 INFO - PROCESS | 12115 | 05:08:11 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51642/tests/kraken/driver.html?testName=audio-beat-detection) 05:08:12 INFO - PROCESS | 12115 | RSS: Main: 168574976 05:08:12 INFO - PROCESS | 12115 | 05:08:15 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51642/tests/kraken/driver.html?testName=audio-dft) 05:08:16 INFO - PROCESS | 12115 | RSS: Main: 171745280 05:08:16 INFO - PROCESS | 12115 | 05:08:19 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51642/tests/kraken/driver.html?testName=audio-fft) 05:08:19 INFO - PROCESS | 12115 | RSS: Main: 165621760 05:08:19 INFO - PROCESS | 12115 | 05:08:21 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51642/tests/kraken/driver.html?testName=audio-oscillator) 05:08:22 INFO - PROCESS | 12115 | RSS: Main: 166072320 05:08:22 INFO - PROCESS | 12115 | 05:08:24 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51642/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:08:25 INFO - PROCESS | 12115 | RSS: Main: 166621184 05:08:25 INFO - PROCESS | 12115 | 05:08:28 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51642/tests/kraken/driver.html?testName=imaging-darkroom) 05:08:29 INFO - PROCESS | 12115 | RSS: Main: 167010304 05:08:29 INFO - PROCESS | 12115 | 05:08:32 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51642/tests/kraken/driver.html?testName=imaging-desaturate) 05:08:33 INFO - PROCESS | 12115 | RSS: Main: 164057088 05:08:33 INFO - PROCESS | 12115 | 05:08:36 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51642/tests/kraken/driver.html?testName=json-parse-financial) 05:08:36 INFO - PROCESS | 12115 | RSS: Main: 164347904 05:08:36 INFO - PROCESS | 12115 | 05:08:38 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51642/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:08:39 INFO - PROCESS | 12115 | RSS: Main: 165507072 05:08:39 INFO - PROCESS | 12115 | 05:08:41 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51642/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:08:41 INFO - PROCESS | 12115 | RSS: Main: 164962304 05:08:41 INFO - PROCESS | 12115 | 05:08:43 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51642/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:08:44 INFO - PROCESS | 12115 | RSS: Main: 165052416 05:08:44 INFO - PROCESS | 12115 | 05:08:47 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51642/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:08:48 INFO - PROCESS | 12115 | RSS: Main: 165662720 05:08:48 INFO - PROCESS | 12115 | 05:08:51 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51642/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:08:51 INFO - PROCESS | 12115 | RSS: Main: 165588992 05:08:51 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | Cycle 1(1): loaded http://localhost:51642/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51642/tests/kraken/driver.html?testName=ai-astar) 05:08:53 INFO - PROCESS | 12115 | RSS: Main: 167305216 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | __start_tp_report 05:08:53 INFO - PROCESS | 12115 | _x_x_mozilla_page_load 05:08:53 INFO - PROCESS | 12115 | _x_x_mozilla_page_load_details 05:08:53 INFO - PROCESS | 12115 | |i|pagename|runs| 05:08:53 INFO - PROCESS | 12115 | |0;ai-astar;113;108;113;114;114;126;115;115;112;113 05:08:53 INFO - PROCESS | 12115 | |1;audio-beat-detection;164;136;153;184;133;161;160;143;199;137 05:08:53 INFO - PROCESS | 12115 | |2;audio-dft;162;163;166;173;162;162;162;161;167;161 05:08:53 INFO - PROCESS | 12115 | |3;audio-fft;77;74;80;162;74;74;74;75;165;74 05:08:53 INFO - PROCESS | 12115 | |4;audio-oscillator;94;94;147;95;96;93;146;94;93;145 05:08:53 INFO - PROCESS | 12115 | |5;imaging-gaussian-blur;118;117;117;119;117;117;117;118;119;117 05:08:53 INFO - PROCESS | 12115 | |6;imaging-darkroom;148;147;146;148;148;148;148;147;149;147 05:08:53 INFO - PROCESS | 12115 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;94 05:08:53 INFO - PROCESS | 12115 | |8;json-parse-financial;66;66;66;66;66;68;67;85;64;64 05:08:53 INFO - PROCESS | 12115 | |9;json-stringify-tinderbox;66;64;65;64;65;64;71;64;64;65 05:08:53 INFO - PROCESS | 12115 | |10;stanford-crypto-aes;79;80;79;79;81;81;82;81;82;81 05:08:53 INFO - PROCESS | 12115 | |11;stanford-crypto-ccm;140;136;137;147;133;135;133;144;172;155 05:08:53 INFO - PROCESS | 12115 | |12;stanford-crypto-pbkdf2;175;174;179;181;183;182;173;191;180;177 05:08:53 INFO - PROCESS | 12115 | |13;stanford-crypto-sha256-iterative;74;73;72;73;72;78;75;71;73;72 05:08:53 INFO - PROCESS | 12115 | __end_tp_report 05:08:53 INFO - PROCESS | 12115 | __start_cc_report 05:08:53 INFO - PROCESS | 12115 | _x_x_mozilla_cycle_collect,960 05:08:53 INFO - PROCESS | 12115 | __end_cc_report 05:08:53 INFO - PROCESS | 12115 | __startTimestamp1461586133809__endTimestamp 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | ------- Summary: start ------- 05:08:53 INFO - PROCESS | 12115 | Number of tests: 14 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#0] ai-astar Cycles:10 Average:114.30 Median:114.00 stddev:4.57 (4.0%) stddev-sans-first:4.82 05:08:53 INFO - PROCESS | 12115 | Values: 113.0 108.0 113.0 114.0 114.0 126.0 115.0 115.0 112.0 113.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#1] audio-beat-detection Cycles:10 Average:157.00 Median:160.50 stddev:21.59 (13.5%) stddev-sans-first:22.75 05:08:53 INFO - PROCESS | 12115 | Values: 164.0 136.0 153.0 184.0 133.0 161.0 160.0 143.0 199.0 137.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#2] audio-dft Cycles:10 Average:163.90 Median:162.50 stddev:3.78 (2.3%) stddev-sans-first:3.95 05:08:53 INFO - PROCESS | 12115 | Values: 162.0 163.0 166.0 173.0 162.0 162.0 162.0 161.0 167.0 161.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#3] audio-fft Cycles:10 Average:92.90 Median:74.00 stddev:37.27 (50.4%) stddev-sans-first:39.08 05:08:53 INFO - PROCESS | 12115 | Values: 77.0 74.0 80.0 162.0 74.0 74.0 74.0 75.0 165.0 74.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#4] audio-oscillator Cycles:10 Average:109.70 Median:94.00 stddev:25.07 (26.7%) stddev-sans-first:25.94 05:08:53 INFO - PROCESS | 12115 | Values: 94.0 94.0 147.0 95.0 96.0 93.0 146.0 94.0 93.0 145.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:117.50 stddev:0.84 (0.7%) stddev-sans-first:0.88 05:08:53 INFO - PROCESS | 12115 | Values: 118.0 117.0 117.0 119.0 117.0 117.0 117.0 118.0 119.0 117.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.84 (0.6%) stddev-sans-first:0.88 05:08:53 INFO - PROCESS | 12115 | Values: 148.0 147.0 146.0 148.0 148.0 148.0 148.0 147.0 149.0 147.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 05:08:53 INFO - PROCESS | 12115 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 94.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:66.00 stddev:6.16 (9.3%) stddev-sans-first:6.50 05:08:53 INFO - PROCESS | 12115 | Values: 66.0 66.0 66.0 66.0 66.0 68.0 67.0 85.0 64.0 64.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:2.15 (3.3%) stddev-sans-first:2.26 05:08:53 INFO - PROCESS | 12115 | Values: 66.0 64.0 65.0 64.0 65.0 64.0 71.0 64.0 64.0 65.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:81.00 stddev:1.18 (1.5%) stddev-sans-first:1.12 05:08:53 INFO - PROCESS | 12115 | Values: 79.0 80.0 79.0 79.0 81.0 81.0 82.0 81.0 82.0 81.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#11] stanford-crypto-ccm Cycles:10 Average:143.20 Median:142.00 stddev:12.29 (8.7%) stddev-sans-first:12.98 05:08:53 INFO - PROCESS | 12115 | Values: 140.0 136.0 137.0 147.0 133.0 135.0 133.0 144.0 172.0 155.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:180.50 stddev:5.30 (2.9%) stddev-sans-first:5.36 05:08:53 INFO - PROCESS | 12115 | Values: 175.0 174.0 179.0 181.0 183.0 182.0 173.0 191.0 180.0 177.0 05:08:53 INFO - PROCESS | 12115 | 05:08:53 INFO - PROCESS | 12115 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:73.00 stddev:2.00 (2.7%) stddev-sans-first:2.11 05:08:53 INFO - PROCESS | 12115 | Values: 74.0 73.0 72.0 73.0 72.0 78.0 75.0 71.0 73.0 72.0 05:08:53 INFO - PROCESS | 12115 | -------- Summary: end -------- 05:08:53 INFO - PROCESS | 12115 | 05:08:54 INFO - TEST-INFO | 12115: exit 0 05:08:54 INFO - TEST-OK | kraken | took 64569ms 05:08:54 INFO - SUITE-END | took 1180s 05:08:54 INFO - Completed test suite (00:19:40) 05:08:54 INFO - javascript benchmark 05:08:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2640.1947406041154, 2688.099486671965, 2708.7020427752946, 2710.4443589064244, 2720.8639568021595, 4810.110129626018, 4831.68594041387, 4835.58897493713, 4855.126077306083, 4894.902101957959, 4689.320048592952, 4727.763611819404, 4780.450248221456, 4865.026994601077, 4866.1240976624285, 4412.933805992905, 4438.7045499702035, 4491.056878055612, 4496.595306422421, 4541.523140070294, 638.2699188184212, 4391.802368893407, 4477.0, 4505.819767209324, 4548.590626843584, 4477.731336119827, 4488.349189367524, 4501.887452813706, 4509.233430316821, 4543.386642803212, 4262.531121576624, 4545.116601819064, 4549.772511374451, 4558.817647294104, 4578.153041687298, 4343.956560434387, 4380.518143004267, 4383.736975781463, 4410.029793445437, 4473.4408198975125, 4663.440387153563, 4678.6491013174145, 4688.203005489075, 4739.099571081483, 4764.547368000034, 5102.413222479388, 5124.154514505102, 5161.638685292031, 5167.534921857014, 5251.159814429673, 5176.844694659167, 5187.8184263550975, 5205.401378841444, 5224.555912747421, 5227.764750586232, 4837.95162048382, 4838.29844672524, 4846.660733748604, 4851.733154676456, 4853.733044682505, 5314.707691077027, 5321.09541377959, 5341.599380046512, 5376.0, 5392.784288628411, 5093.516115968977, 5099.974500127476, 5109.770060347293, 5113.9744301279, 5152.046871328741, 5056.974715126401, 5181.326427564392, 5195.766190521435, 5195.766190521435, 5204.843854684365, 5148.099082660519, 5169.534741873174, 5185.2999845021195, 5186.974065129651, 5231.581473482112, 4915.803367865316, 5100.617453690988, 5128.769205385766, 5140.794368225229, 5167.844964651066, 4248.702590818613, 4310.0, 4312.654987600984, 4351.108022855307, 4369.191699535596, 3779.055236190952, 3824.6366595173413, 3833.750806197588, 3858.3247931611854, 3863.8454461821775, 4434.889127771767, 4457.153140903218, 4471.597556219956, 4471.8658440246845, 4512.165249428866, 5176.145935920531, 5191.377034755854, 5197.662151960186, 5224.9738751306, 5305.230741542532, 4461.464624245112, 4461.8399216204025, 4463.218936686067, 4480.887977800516, 4505.414296141481, 4560.270356742905, 4577.359169716243, 4583.083383323348, 4583.793729282191, 4633.559811817872, 4738.502457242009, 4860.416749989953, 4900.313956046228, 4982.352294201731, 4984.526469985346, 5163.870903227449, 5192.74036298176, 5196.350456192976, 5204.843854684365, 5210.53105220532, 4868.561829435368, 4869.123557759495, 4871.878203044952, 4876.097921884463, 4880.975595122001, 5022.146235139962, 5136.512031357015, 5158.509941555546, 5158.97420512895, 5200.60995425337, 5207.114790485551, 5231.581473482036, 5281.366236051698, 5295.841124766263, 5350.785968561214, 4921.458639549718, 5111.514406131411, 5119.539241468286, 5122.154844450624, 5130.589552835839, 4894.363732714724, 5020.071286811952, 5047.217681259411, 5083.466236045162, 5109.3613298337705, 5027.321311622884, 5036.370453693288, 5048.469910659474, 5049.444561098349, 5113.821016264413, 5006.624503162205, 5082.517160869711, 5127.615428842926, 5128.846134615818, 5182.352205974254, 4965.875853103701, 4980.32765576657, 4989.151844186569, 5025.899482010266, 5029.0], "value": 4729.145606932246, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.1404336473606, 582.3070546050201, 590.235644839932, 606.966616836074, 641.566942313938, 33151.171220719465, 34022.31955360891, 34022.65977340226, 34041.14897127573, 34096.65903340966, 34231.65768342316, 34710.305793884174, 34732.652673473254, 34783.82608086956, 34925.82537087317, 7550.282723141307, 7728.459007869451, 7738.883916741253, 7740.535567865918, 7740.729074482394, 7467.0, 7651.732189373373, 7713.73001944932, 7717.305442510173, 7721.72973945912, 7715.267049630276, 7723.53658780475, 7754.612269386536, 7781.0, 7827.099883513412, 7661.233876612322, 7681.692732290701, 7693.0, 7743.806404839867, 7743.8838417423785, 7682.692692292301, 7750.263724946121, 7783.143854176036, 7785.844283114363, 7820.921790782133, 28454.0, 35669.28661426783, 36002.09994750126, 36085.27829443396, 36156.02283748786, 2718.7009428962797, 2807.550791873311, 3002.0, 3211.341674956628, 3287.3260981498975, 26325.0, 27293.454130917467, 28536.28659283514, 28614.856925715238, 28968.27579310513, 3401.846916888746, 3565.6612621800887, 3582.9641703582893, 3606.296772129436, 3632.891013269606, 197464.0, 199763.0, 200067.0, 200189.0, 201332.99333503385, 52518.737406313485, 53178.202326965125, 53220.46779532233, 53265.2010219847, 53306.46693533015, 54317.72841135769, 60319.69840150771, 62639.373606264264, 62643.6867815658, 62757.372426276066, 13046.41291141901, 13075.476980920846, 13100.279484628438, 13159.0, 13167.736645267041, 10401.27191096616, 10467.685969420929, 10497.055265026183, 10530.57877684884, 10547.472626368652, 18481.445556633324, 18659.720104198448, 18803.435896923114, 18898.433047008613, 19665.901670491556, 7350.926490735131, 7578.227426583083, 7582.393408527305, 7698.651911167036, 7752.147263800977, 884.5577211394303, 903.1420150856582, 904.3669431398048, 974.6783561424713, 982.6511588386163, 27137.0, 27783.0, 27865.395378588055, 27874.721252787618, 28315.43369132606, 996.6661168508485, 1006.3810756384771, 1052.3843551522446, 1071.0307172009188, 1111.5331560744507, 14393.280335983369, 14932.402703891723, 15035.473758418402, 15155.166465844484, 15172.165530895685, 349368.0, 355599.0, 355748.0, 356230.0, 357156.0, 47545.286820697715, 48312.516874831505, 48346.5165348342, 48610.0, 48658.27012594814, 1186.8805242377773, 1207.6497815633543, 1227.5274019502376, 1240.2186622427994, 1240.7332423528985, 26120.0, 27823.582646260726, 27930.301742456602, 28111.578326324714, 28179.859100704365, 42200.36699449449, 42421.363679544214, 43040.35439468473, 43085.56914430815, 43122.0, 318253.0, 319001.0, 319152.0, 319795.0, 320241.0, 24770.380740481633, 25358.0, 25381.492370152864, 25381.492370152864, 38283.425748613234, 36739.0, 37398.252034959696, 37494.81252593829, 37664.0, 37792.2441551162, 31924.8403757989, 32634.34731305313, 34268.31463370768, 34881.0, 35125.824370877985, 33919.0, 33997.49003764995, 34157.31685366329, 34648.0, 34745.652543475255, 34515.4822677655, 34910.476342855385, 36946.44580331243, 38205.61794382021, 38260.234795304495, 28945.56581651235, 30337.848310758305, 30731.846340769047, 30910.0, 31316.217094571908, 23735.52528949377, 24389.0, 24438.51122977566, 24673.0, 24690.753092469564], "value": 17136.30160543818, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.59704116595236, 382.25651108593786, 385.65483891916716, 389.14582491431304, 392.6015094678903, 15636.92181539092, 15971.680566388664, 16850.32598696054, 16912.577185570368, 16989.490315290546, 13963.720725585508, 15365.462208822693, 16346.427875024376, 16346.509604711848, 16412.17939103046, 4608.193566125923, 4719.787609557578, 4776.641751868607, 4778.593819525345, 4779.450363208241, 3471.305738852227, 5133.255677926699, 5386.703731294777, 5412.648177868446, 5420.214068959998, 5386.626410265377, 5392.622516423852, 5393.865153371177, 5398.91901621476, 5411.540019098381, 4787.042591481701, 5076.720780357079, 5079.542841144278, 5118.564921981618, 5120.257562653432, 4917.606591472673, 4943.3573635427165, 4945.629077819142, 5426.538744206747, 5428.4028756836715, 18876.81123188778, 19484.80515194844, 19912.70130948037, 20112.698309525367, 20123.195072197243, 1903.4765439504108, 1945.8929758863258, 1960.6764883794158, 2032.8780273183656, 2162.48100455891, 9119.224865886408, 9228.123328283802, 9303.069693030711, 9498.667546635834, 9582.233421326277, 1692.0439951427527, 1761.6124452620531, 1761.7357396390516, 1823.2342416185102, 1833.3766519383341, 69951.65024174846, 69991.0, 70727.64636176838, 71570.28429715689, 71700.6414967927, 9935.950320248352, 10001.54993025301, 10086.949565252127, 10165.695029149138, 10371.274010819321, 14103.506377276746, 14188.148711077369, 14249.216293103978, 14265.857341426661, 14281.357338919774, 6848.623325717133, 6913.481488888354, 6957.721691132399, 7007.178133685278, 7086.397656199178, 6461.8061458156335, 6465.967670161619, 6506.154199953976, 6573.80278591643, 6627.0, 4350.434443522322, 4377.518472967971, 4417.0724147929295, 4422.469303683514, 4520.028193938319, 1714.9657006859793, 1774.4119013483119, 1810.8732388732926, 1834.3763120538813, 1847.1410793980863, 835.2941764209233, 850.5152063323847, 868.3530769576706, 910.4082346474844, 924.8520236762085, 15203.239838008056, 15230.314635841414, 15543.611409714622, 16413.261403236884, 16643.91678041602, 860.4794099569796, 883.7702197428715, 920.7790130368797, 946.1012038563392, 950.9429434233969, 9085.318601104944, 9385.202257808027, 9401.811963760687, 9419.011003844635, 9619.27855410833, 390092.2014399924, 399890.0, 417602.0, 423757.0, 423819.0, 46533.76733116313, 53283.73358133184, 78960.0, 80512.59743701361, 82311.5884420574, 926.9490178040272, 949.3829011142674, 951.5147274889717, 975.327024353194, 982.9852552211722, 13579.932100339434, 13840.584782456543, 14020.78968815489, 14298.070625409315, 14351.569452916428, 33938.66061339454, 34516.309673806885, 34890.162082171446, 35451.0, 35693.28613427665, 45779.31331029971, 45917.31124033209, 48101.278480823516, 48167.0, 48326.75836620794, 7711.0, 7722.652480638272, 7838.529688218725, 7879.684812607432, 7884.960575197088, 9993.0, 10910.399928004035, 10933.234673572773, 11295.265807722471, 11647.30116193031, 9581.952090239503, 9866.671997121473, 10281.383117012703, 10472.214583906085, 10586.0, 10123.848142277724, 10235.94882025615, 10287.485625718535, 10808.243422960632, 10965.396903170402, 9887.604495820087, 9981.650642227487, 10102.141317988053, 10119.645812396531, 10528.99974502421, 7522.210167932507, 7708.152103268748, 7774.30031297186, 7935.920640793518, 8107.554084525172, 10003.2997690161, 10246.0, 10263.0, 10328.896711032794, 10477.952204779462], "value": 8062.720936906158, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.8435972043071, 1442.8917831162662, 1556.968860622787, 1672.9711227595033, 1713.7600735896976, 7095.7161713531395, 7505.849883002336, 7713.92286077139, 7737.651805668751, 7750.57371844549, 7847.176046515105, 7857.764267071982, 7860.096088949773, 7957.0, 7973.481723687971, 2291.415688999303, 2321.9883900580476, 2326.336993956723, 2332.288651961151, 2332.54515369503, 2278.1798552521077, 2278.9886050569726, 2311.849729767568, 2315.1896836107394, 2321.721393432782, 2237.3623517297574, 2263.513344630904, 2268.376196545955, 2316.0, 2322.9883850580723, 2241.081156725743, 2265.5355652091366, 2266.7733226677365, 2269.8751568663633, 2275.8520696154865, 2244.483768733184, 2250.4036430345973, 2259.186692790594, 2261.9660705089436, 2266.7959883610556, 7984.3612510999565, 8070.838583228361, 8135.918640813576, 8153.714619988331, 8164.836703265961, 4730.668853180278, 4915.410150781893, 4951.554360107608, 4951.950480495185, 4953.851384458472, 2935.7082883531393, 2938.41231753648, 3029.3789773096464, 3038.848057597111, 3051.7711171662213, 982.6855406269997, 989.2580564576567, 998.3859926145441, 1002.5960075194706, 1034.6275340877278, 11084.833727494093, 11108.333499990027, 11113.555457781678, 11150.721231969184, 11169.888301117047, 4273.529911709709, 4374.081442896963, 4492.887677808016, 4551.249043907785, 4624.3294722265755, 9387.812243755086, 9431.009743976922, 9436.858447123299, 9462.763430914281, 9483.525823708787, 4739.241721324572, 4769.642276829251, 4776.570108690165, 4795.424549054136, 4799.232122860326, 3827.8660246891227, 4332.415123958225, 4344.261475549164, 4457.8439754608435, 4466.59800617946, 3706.666400023957, 3773.8490460381277, 3858.7105967052585, 3876.0503676599415, 3909.3158697227873, 1258.4211262819022, 1262.2174251963659, 1274.7068174319772, 1280.667026573079, 1291.7222797098668, 728.4682182007059, 746.7349091072591, 778.4006315137312, 782.1591788827034, 811.3306522119275, 5258.2638430619745, 5365.087935050972, 5447.727613619303, 5464.754086066137, 5467.261919640877, 406.5081251685435, 406.89217357400315, 414.57713132604573, 427.68351419949016, 433.92406328892315, 3536.3634545781492, 3607.845489443353, 3639.6542328478754, 3657.9634203657624, 3675.026118078711, 9793.755156121155, 10001.899981000242, 10155.187584993182, 10186.796264074677, 10189.133923616428, 10025.348352357074, 10035.598576057022, 10060.39637621745, 10106.343087699275, 10127.291089623655, 7226.421886249087, 7564.621768911533, 7622.771316860503, 7703.576303303372, 7711.691532338756, 5318.255444237732, 5349.384820745664, 5353.946460535501, 5478.397376288684, 5484.122540393678, 8693.174148455886, 8887.0, 9014.32392570573, 9026.548672566476, 9046.592903319235, 8787.604557794786, 9085.182333590008, 9172.541372931193, 9242.214411775074, 9262.120098590623, 6356.396142366468, 6526.608403495806, 6696.1629796275465, 6787.796366109024, 6951.165860096618, 7633.923660763321, 7661.770146895602, 7677.8464430709955, 7716.807079823049, 7717.0, 6675.766348177791, 6883.655817209019, 6924.030635711105, 6978.406835419046, 6998.825029374307, 6697.3302669734585, 6729.5625784323865, 6736.696848641921, 6749.257581665915, 6831.419329357061, 6362.77730279438, 6395.93604063972, 6430.067640192239, 6436.871262574816, 6466.0947467355545, 4610.2854057621125, 4632.907341853212, 4637.791299391603, 4654.511276316073, 4736.550027747358, 4514.142312960527, 4622.653301002371, 4694.483606803181, 4741.810327586858, 4761.333413322207], "value": 4179.132666949527, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.67274035003595, 317.8156669131902, 319.6739325887593, 320.34969012903787, 320.60885719422305, 5238.973805130974, 5298.390685071218, 5360.892782144355, 5378.1663842104435, 5405.729713514318, 5303.65526240794, 5318.643745844814, 5332.680039197641, 5356.812511562096, 5358.946410535894, 3883.8446462141483, 4004.719669623113, 4103.733257338278, 4115.773632450214, 4121.9381709274385, 4098.303288440958, 4127.484064491939, 4142.357934520139, 4147.585241475858, 4152.584741525853, 4117.876463706094, 4118.423420721101, 4128.958710412902, 4129.710920235585, 4143.8964025899295, 4043.2924238258483, 4102.733322334068, 4125.525564560054, 4136.172765446892, 4136.800327904904, 3596.856125754967, 3613.1147868772055, 3616.439451884962, 3637.563492380905, 3650.9817450912574, 4978.551930326288, 5003.399592048941, 5014.197728363481, 5030.748462576893, 5038.899222015576, 4626.329182268586, 4796.304535842283, 4800.423949126092, 4802.399700037496, 4811.903761924742, 5559.638623489501, 5584.738181036687, 5603.299587551556, 5611.494965453088, 5619.157126431027, 3976.5228172619472, 3986.362182050858, 3991.7604943703473, 3993.900152496182, 4019.8191081401114, 5624.21261023457, 5879.617824841372, 5897.115432685088, 5899.970500147515, 5953.315368732608, 3997.1006523532556, 4010.959890401117, 4012.0, 4022.356422972369, 4029.657479114308, 4036.858709945138, 4041.3533834586324, 4056.006278461796, 4087.6729861611, 4088.8364465421646, 4593.106343003886, 4673.228917228687, 4677.391939047902, 4697.718136911796, 4702.6708130431225, 5648.604597678123, 5673.347565030074, 5676.290463692038, 5678.602497825113, 5682.346530149003, 1386.3484162443635, 1391.87473127417, 1407.5636552668705, 1407.6340151560669, 1413.8303403591635, 854.3848429131016, 922.7646950027835, 932.5989824375448, 949.7245798718433, 954.2985905359556, 4846.7818948147415, 4861.927071093936, 4911.16510193268, 4916.4110199554525, 4928.753562321941, 5739.942600573941, 5779.3642699303045, 5782.88434231313, 5787.536997040227, 5833.358330583717, 4752.14461396945, 4823.589994850477, 4856.902861942742, 4868.030501394147, 4873.245631572631, 3075.3234288456506, 3092.4742793725122, 3093.6442309134736, 3099.7985130966417, 3114.159177022191, 5353.9732301338245, 5376.78492860289, 5381.48875856793, 5398.8920221595345, 5400.83797486076, 5773.8556536086935, 5815.185873977646, 5925.288965324189, 5954.166416701579, 5969.134475501028, 5330.200469929464, 5330.280412144389, 5363.168708850057, 5378.247045413724, 5390.380106287748, 5660.858478537904, 5738.741756620879, 5746.741396637245, 5766.913496297643, 5852.4147585241135, 5659.037963546125, 5663.518600918968, 5681.0, 5687.20379146928, 5705.115707065412, 5350.678959262456, 5352.839414817562, 5364.597655175956, 5384.461553844584, 5414.133738601805, 3897.902552436212, 3972.5431575368048, 3984.242993831163, 3993.940090898697, 3995.580464051233, 5705.885882282414, 5712.828615141552, 5728.742206600797, 5731.5701322400155, 5755.5683323750045, 5546.667199968015, 5551.500364967011, 5559.24950131727, 5566.554675625859, 5640.210370548209, 5370.946290537201, 5539.833804985857, 5558.833235002957, 5559.138333558305, 5573.108302671715, 5297.443768404262, 5497.8075767347955, 5502.367227768697, 5510.035743744748, 5526.143447765603, 5571.498565129158, 5743.827685169452, 5747.4252574742195, 5751.74117164737, 5755.309362876482, 5547.8890422192135, 5550.613236308264, 5553.111502159797, 5559.833205003856, 5560.582956278215], "value": 4189.508967420982, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.0955209694665, 645.7804346522181, 654.8493846415328, 656.9835754106148, 661.0711949710658, 11489.425528723561, 11686.649400517997, 11692.0, 11693.181477296592, 11878.7624247515, 11460.656180314583, 11884.881151188485, 12003.819942700866, 12023.0, 12997.155184912974, 768.4352001279055, 772.98840517392, 775.0428221146907, 776.4099284543731, 777.6228529163351, 756.9697212111508, 763.4083585221442, 764.4266799900075, 768.5734417398342, 769.6959700918134, 797.8085259537698, 802.4022103532847, 802.6869520886829, 803.1927912448011, 809.1261437647327, 807.4590024683476, 809.2150613904503, 809.5547448903138, 810.4610434061342, 813.6826637611277, 599.2090440618385, 601.5247954116243, 601.8104297146429, 602.6866029664594, 603.9818805435845, 11927.463264153133, 12017.699557511045, 12051.758964820741, 12062.758744825142, 12089.6373108806, 11889.643310700607, 11907.85751858432, 12303.93848030763, 12348.320842353667, 12591.874081259162, 9216.801815763974, 9839.409635421825, 11144.554217831277, 11207.887921120848, 11483.368414737186, 3574.3816060808144, 3594.1733401317583, 3646.9038734010455, 3653.2693461307845, 3676.1361080146144, 38427.0, 41963.58036419627, 43649.78175109104, 45480.0, 46402.535974640494, 8406.327493800482, 8482.703105391281, 8866.379353445325, 8869.02440731519, 8894.866577001221, 16365.427210047592, 16387.0, 16387.262573184234, 16429.096399697894, 16479.670406591802, 6879.5184337096925, 7021.087258656342, 7053.89419158713, 7063.89404158938, 7237.167725711505, 3719.888403347904, 3806.1626442182674, 3844.519435070616, 3850.383938569815, 3972.2055588881763, 2794.455081259135, 2817.1689351641317, 2851.6435445569305, 2862.298736809453, 2865.412590418959, 510.07930685112984, 519.0294149939637, 522.334024119251, 527.309224915362, 535.5260594374007, 440.33509400805013, 464.7443905851768, 542.9548119869236, 547.7896686868467, 549.8240563019796, 7165.92834071663, 7231.5299505531975, 7258.891116633255, 7462.440316976248, 7540.509866858637, 470.9105269998689, 566.980155694557, 569.3765326966965, 591.9319278283052, 600.8828278485653, 3332.250243695149, 3379.898603041913, 3449.3963556378035, 3521.154922818505, 3605.0446631642635, 48363.27455088177, 48377.75811120992, 49168.0, 49336.75331623319, 49636.503634963185, 43770.56229437728, 43855.0, 43875.12249754987, 43910.0, 44568.55431445644, 34659.653403466145, 38862.02844928843, 40868.0, 41204.587954120674, 41348.1730365385, 7005.684744186422, 7181.569105853666, 7325.560466372035, 7394.852102957803, 7586.431017673807, 25543.87228063848, 25927.22218333453, 28229.294267643472, 29554.113376598736, 29575.408491829614, 33376.0, 34823.825880870434, 35299.29401412009, 35424.46863297104, 36474.0, 9605.327627066039, 9949.104580587782, 9956.0, 10005.949970250102, 10210.132138768287, 10077.596896124072, 10082.596696132072, 10318.0, 10392.792144156923, 10480.0, 9892.208623310262, 10113.19094472455, 10165.288429809842, 10181.74545636365, 10326.845097323696, 9436.433813971184, 9686.854697179408, 10482.68551943443, 10658.200634952254, 10835.891641083488, 10216.335938163995, 10346.0, 10404.58381664725, 10525.736856578647, 10643.8935610646, 8636.611352489028, 8796.91203087961, 8890.422122562013, 9194.00667804552, 9281.303902207226, 8456.40805143634, 8695.347848911231, 8834.690785822466, 9034.503102329434, 9090.13643703847], "value": 5641.993498021779, "unit": "score"}], "name": "dromaeo_css", "value": 6333.608874156911, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 113.0, 114.0, 114.0, 126.0, 115.0, 115.0, 112.0, 113.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 136.0, 153.0, 184.0, 133.0, 161.0, 160.0, 143.0, 199.0, 137.0], "value": 157.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 166.0, 173.0, 162.0, 162.0, 162.0, 161.0, 167.0, 161.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 80.0, 162.0, 74.0, 74.0, 74.0, 75.0, 165.0, 74.0], "value": 92.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 147.0, 95.0, 96.0, 93.0, 146.0, 94.0, 93.0, 145.0], "value": 109.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 119.0, 117.0, 117.0, 117.0, 118.0, 119.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 148.0, 148.0, 148.0, 148.0, 147.0, 149.0, 147.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 68.0, 67.0, 85.0, 64.0, 64.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 64.0, 65.0, 64.0, 71.0, 64.0, 64.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 79.0, 81.0, 81.0, 82.0, 81.0, 82.0, 81.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 137.0, 147.0, 133.0, 135.0, 133.0, 144.0, 172.0, 155.0], "value": 143.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 179.0, 181.0, 183.0, 182.0, 173.0, 191.0, 180.0, 177.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 72.0, 73.0, 72.0, 78.0, 75.0, 71.0, 73.0, 72.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1607.5000000000002, "alertThreshold": 2.0}]} 05:08:55 INFO - Return code: 0 05:08:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:08:55 ERROR - # TBPL SUCCESS # 05:08:55 INFO - Running post-action listener: _resource_record_post_action 05:08:55 INFO - Running post-run listener: _resource_record_post_run 05:08:56 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 529203200; Read time: 12; Write time: 1008484 05:08:56 INFO - install - Wall time: 16s; CPU: 16.0%; Read bytes: 0; Write bytes: 234393600; Read time: 0; Write time: 498064 05:08:56 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293515264; Read time: 12; Write time: 510156 05:08:56 INFO - Running post-run listener: _upload_blobber_files 05:08:56 INFO - Blob upload gear active. 05:08:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:08:56 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 05:08:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:08:56 INFO - (blobuploader) - INFO - Open directory for files ... 05:08:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:08:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:08:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:57 INFO - (blobuploader) - INFO - Done attempting. 05:08:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:08:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:08:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:58 INFO - (blobuploader) - INFO - Done attempting. 05:08:58 INFO - (blobuploader) - INFO - Iteration through files over. 05:08:58 INFO - Return code: 0 05:08:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:08:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:08:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/14fefe4a62807c409f457719a20a8105fea61003ba0ae2c0633cede6df0f91dee2366e1e24fc903e1e03993b849f92606b3d5c36d8d8a82bf4e8d1a607a53f0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0a0e711d84a9d2e173ac83bb7cd10cdcb1dbc79c9cf1e8f268971893a6e2738ca2290c439d3b907c36b1d08c4233bc2ef7f28c5702b5240808e69ebe12f4f64c"} 05:08:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:08:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:08:58 INFO - Contents: 05:08:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/14fefe4a62807c409f457719a20a8105fea61003ba0ae2c0633cede6df0f91dee2366e1e24fc903e1e03993b849f92606b3d5c36d8d8a82bf4e8d1a607a53f0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0a0e711d84a9d2e173ac83bb7cd10cdcb1dbc79c9cf1e8f268971893a6e2738ca2290c439d3b907c36b1d08c4233bc2ef7f28c5702b5240808e69ebe12f4f64c"} 05:08:58 INFO - Running post-run listener: copy_logs_to_upload_dir 05:08:58 INFO - Copying logs to upload dir... 05:08:58 INFO - mkdir: /builds/slave/test/build/upload/logs 05:08:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.619795 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-04-25 05:08:58.155194) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 05:08:58.159172) ========= 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=8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/14fefe4a62807c409f457719a20a8105fea61003ba0ae2c0633cede6df0f91dee2366e1e24fc903e1e03993b849f92606b3d5c36d8d8a82bf4e8d1a607a53f0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0a0e711d84a9d2e173ac83bb7cd10cdcb1dbc79c9cf1e8f268971893a6e2738ca2290c439d3b907c36b1d08c4233bc2ef7f28c5702b5240808e69ebe12f4f64c"} build_url:https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011102 build_url: 'https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/14fefe4a62807c409f457719a20a8105fea61003ba0ae2c0633cede6df0f91dee2366e1e24fc903e1e03993b849f92606b3d5c36d8d8a82bf4e8d1a607a53f0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0a0e711d84a9d2e173ac83bb7cd10cdcb1dbc79c9cf1e8f268971893a6e2738ca2290c439d3b907c36b1d08c4233bc2ef7f28c5702b5240808e69ebe12f4f64c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 05:08:58.183286) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:08:58.183605) ========= 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=8783db0840cc980e80bb0bd400000236-1461584716.422304-2115308177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:08:58.241003) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:08:58.241624) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:08:58.242553) ========= ========= Total master_lag: 0.55 =========