builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-015 starttime: 1471879657.82 results: success (0) buildid: 20160822072715 builduid: fbe12f954bfa47c89bf40d0e491daa5a revision: 95e17b89b51955aabf9986ef9f2f973053630579 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:37.817612) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:37.818173) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:37.818490) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:37.833151) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:37.833448) ========= 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=20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-22 08:27:38-- 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% 37.9M=0s 2016-08-22 08:27:38 (37.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122838 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:37.970848) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:37.972337) ========= 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=20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015318 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:38.022009) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:38.022422) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 95e17b89b51955aabf9986ef9f2f973053630579 --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 95e17b89b51955aabf9986ef9f2f973053630579 --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=20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-22 08:27:38,431 truncating revision to first 12 chars 2016-08-22 08:27:38,431 Setting DEBUG logging. 2016-08-22 08:27:38,431 attempt 1/10 2016-08-22 08:27:38,431 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/95e17b89b519?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 08:27:38,769 unpacking tar archive at: fx-team-95e17b89b519/testing/mozharness/ program finished with exit code 0 elapsedTime=0.660011 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:38.696473) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:38.696838) ========= script_repo_revision: 95e17b89b51955aabf9986ef9f2f973053630579 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:38.697324) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:38.697628) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 08:27:38.707294) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-22 08:27:38.707603) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:27:39 INFO - MultiFileLogger online at 20160822 08:27:39 in /builds/slave/test 08:27:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 08:27:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:27:39 INFO - {'append_to_log': False, 08:27:39 INFO - 'base_work_dir': '/builds/slave/test', 08:27:39 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 08:27:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:27:39 INFO - 'branch': 'Fx-Team-Non-PGO', 08:27:39 INFO - 'buildbot_json_path': 'buildprops.json', 08:27:39 INFO - 'config_files': ('talos/linux_config.py',), 08:27:39 INFO - 'default_actions': ('clobber', 08:27:39 INFO - 'read-buildbot-config', 08:27:39 INFO - 'download-and-extract', 08:27:39 INFO - 'populate-webroot', 08:27:39 INFO - 'create-virtualenv', 08:27:39 INFO - 'install', 08:27:39 INFO - 'run-tests'), 08:27:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:27:39 INFO - 'download_minidump_stackwalk': True, 08:27:39 INFO - 'download_symbols': 'ondemand', 08:27:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:27:39 INFO - 'tooltool.py': '/tools/tooltool.py', 08:27:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:27:39 INFO - '/tools/misc-python/virtualenv.py')}, 08:27:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:27:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:27:39 INFO - 'installer_path': 'installer.exe', 08:27:39 INFO - 'log_level': 'info', 08:27:39 INFO - 'log_name': 'talos', 08:27:39 INFO - 'log_to_console': True, 08:27:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:27:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:27:39 INFO - 'opt_config_files': (), 08:27:39 INFO - 'pip_index': False, 08:27:39 INFO - 'sps_profile': False, 08:27:39 INFO - 'sps_profile_interval': 0, 08:27:39 INFO - 'suite': 'dromaeojs', 08:27:39 INFO - 'system_bits': '32', 08:27:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:27:39 INFO - 'title': 'talos-linux64-ix-015', 08:27:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:27:39 INFO - 'use_talos_json': True, 08:27:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:27:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:27:39 INFO - 'work_dir': 'build'} 08:27:39 INFO - [mozharness: 2016-08-22 15:27:39.215150Z] Running clobber step. 08:27:39 INFO - Running pre-action listener: _resource_record_pre_action 08:27:39 INFO - Running main action method: clobber 08:27:39 INFO - rmtree: /builds/slave/test/build 08:27:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:27:39 INFO - Running post-action listener: _resource_record_post_action 08:27:39 INFO - [mozharness: 2016-08-22 15:27:39.707339Z] Finished clobber step (success) 08:27:39 INFO - [mozharness: 2016-08-22 15:27:39.707441Z] Running read-buildbot-config step. 08:27:39 INFO - Running pre-action listener: _resource_record_pre_action 08:27:39 INFO - Running main action method: read_buildbot_config 08:27:39 INFO - Using buildbot properties: 08:27:39 INFO - { 08:27:39 INFO - "project": "", 08:27:39 INFO - "product": "firefox", 08:27:39 INFO - "script_repo_revision": "production", 08:27:39 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:27:39 INFO - "repository": "", 08:27:39 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 08:27:39 INFO - "buildid": "20160822072715", 08:27:39 INFO - "pgo_build": "False", 08:27:39 INFO - "basedir": "/builds/slave/test", 08:27:39 INFO - "buildnumber": 2109, 08:27:39 INFO - "slavename": "talos-linux64-ix-015", 08:27:39 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:27:39 INFO - "platform": "ubuntu64_hw", 08:27:39 INFO - "scheduler": "tests-fx-team-linux64-talos", 08:27:39 INFO - "branch": "fx-team", 08:27:39 INFO - "repo_path": "integration/fx-team", 08:27:39 INFO - "revision": "95e17b89b51955aabf9986ef9f2f973053630579", 08:27:39 INFO - "stage_platform": "linux64", 08:27:39 INFO - "builduid": "fbe12f954bfa47c89bf40d0e491daa5a", 08:27:39 INFO - "slavebuilddir": "test" 08:27:39 INFO - } 08:27:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:27:39 INFO - Running post-action listener: _resource_record_post_action 08:27:39 INFO - [mozharness: 2016-08-22 15:27:39.709849Z] Finished read-buildbot-config step (success) 08:27:39 INFO - [mozharness: 2016-08-22 15:27:39.709937Z] Running download-and-extract step. 08:27:39 INFO - Running pre-action listener: _resource_record_pre_action 08:27:39 INFO - Running main action method: download_and_extract 08:27:39 INFO - mkdir: /builds/slave/test/build/tests 08:27:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:39 INFO - trying https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:27:39 INFO - Downloading https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:27:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:27:40 INFO - Downloaded 1361 bytes. 08:27:40 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:27:40 INFO - Using the following test package requirements: 08:27:40 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:27:40 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:27:40 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:27:40 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:40 INFO - u'jsshell-linux-x86_64.zip'], 08:27:40 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:27:40 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:27:40 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:27:40 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:27:40 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:27:40 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:27:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:27:40 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:40 INFO - trying https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:27:40 INFO - Downloading https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:27:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:27:41 INFO - Downloaded 20330902 bytes. 08:27:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 08:27:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:45 INFO - trying https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:27:45 INFO - Downloading https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:27:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:27:46 INFO - Downloaded 20330902 bytes. 08:27:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 08:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:50 INFO - trying https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:27:50 INFO - Downloading https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:27:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:27:51 INFO - Downloaded 14413981 bytes. 08:27:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 08:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:51 INFO - trying https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:27:51 INFO - Downloading https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:27:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:27:53 INFO - Downloaded 55760748 bytes. 08:27:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:27:53 INFO - mkdir: /builds/slave/test/properties 08:27:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:27:53 INFO - Writing to file /builds/slave/test/properties/build_url 08:27:53 INFO - Contents: 08:27:53 INFO - build_url:https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:27:53 INFO - Running post-action listener: _resource_record_post_action 08:27:53 INFO - Running post-action listener: set_extra_try_arguments 08:27:53 INFO - [mozharness: 2016-08-22 15:27:53.713378Z] Finished download-and-extract step (success) 08:27:53 INFO - [mozharness: 2016-08-22 15:27:53.713602Z] Running populate-webroot step. 08:27:53 INFO - Running pre-action listener: _resource_record_pre_action 08:27:53 INFO - Running main action method: populate_webroot 08:27:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:27:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:27:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:27:53 INFO - '--tppagecycles', 08:27:53 INFO - '7'], 08:27:53 INFO - 'tests': ['tsvgm']}}, 08:27:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:27:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:27:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:27:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:27:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:53 INFO - 'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:27:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:27:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:53 INFO - 'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['damp', 'tps']}, 08:27:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:53 INFO - 'tests': ['damp', 'tps']}, 08:27:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['dromaeo_dom']}, 08:27:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:27:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['basic_compositor_video']}, 08:27:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:27:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['a11yr', 08:27:53 INFO - 'ts_paint', 08:27:53 INFO - 'tpaint', 08:27:53 INFO - 'sessionrestore', 08:27:53 INFO - 'sessionrestore_no_auto_restore', 08:27:53 INFO - 'tabpaint']}, 08:27:53 INFO - 'other-e10s': {'tests': ['a11yr', 08:27:53 INFO - 'ts_paint', 08:27:53 INFO - 'tpaint', 08:27:53 INFO - 'sessionrestore', 08:27:53 INFO - 'sessionrestore_no_auto_restore', 08:27:53 INFO - 'tabpaint']}, 08:27:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:27:53 INFO - 'ts_paint', 08:27:53 INFO - 'tpaint', 08:27:53 INFO - 'sessionrestore', 08:27:53 INFO - 'sessionrestore_no_auto_restore', 08:27:53 INFO - 'tabpaint']}, 08:27:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:27:53 INFO - 'ts_paint', 08:27:53 INFO - 'tpaint', 08:27:53 INFO - 'sessionrestore', 08:27:53 INFO - 'sessionrestore_no_auto_restore', 08:27:53 INFO - 'tabpaint']}, 08:27:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['a11yr', 08:27:53 INFO - 'ts_paint', 08:27:53 INFO - 'tpaint', 08:27:53 INFO - 'sessionrestore', 08:27:53 INFO - 'sessionrestore_no_auto_restore', 08:27:53 INFO - 'tabpaint']}, 08:27:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['a11yr', 08:27:53 INFO - 'ts_paint', 08:27:53 INFO - 'tpaint', 08:27:53 INFO - 'sessionrestore', 08:27:53 INFO - 'sessionrestore_no_auto_restore', 08:27:53 INFO - 'tabpaint']}, 08:27:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['tsvgx', 08:27:53 INFO - 'tsvgr_opacity', 08:27:53 INFO - 'tart', 08:27:53 INFO - 'tscrollx', 08:27:53 INFO - 'cart']}, 08:27:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:27:53 INFO - 'tsvgr_opacity', 08:27:53 INFO - 'tart', 08:27:53 INFO - 'tscrollx', 08:27:53 INFO - 'cart']}, 08:27:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:53 INFO - 'talos_options': ['--disable-e10s'], 08:27:53 INFO - 'tests': ['tp5o']}, 08:27:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:53 INFO - 'tests': ['tp5o']}, 08:27:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:53 INFO - 'talos_options': ['--disable-e10s', 08:27:53 INFO - '--xperf_path', 08:27:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:27:53 INFO - 'tests': ['tp5n']}, 08:27:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:27:53 INFO - 'talos_options': ['--xperf_path', 08:27:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:27:53 INFO - 'tests': ['tp5n']}}, 08:27:53 INFO - 'talos.zip': {'path': '', 08:27:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:27:53 INFO - Running post-action listener: _resource_record_post_action 08:27:53 INFO - [mozharness: 2016-08-22 15:27:53.732462Z] Finished populate-webroot step (success) 08:27:53 INFO - [mozharness: 2016-08-22 15:27:53.732549Z] Running create-virtualenv step. 08:27:53 INFO - Running pre-action listener: _resource_record_pre_action 08:27:53 INFO - Running main action method: create_virtualenv 08:27:53 INFO - Creating virtualenv /builds/slave/test/build/venv 08:27:53 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 08:27:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:27:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:27:53 INFO - Using real prefix '/usr' 08:27:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:27:54 INFO - Installing distribute.............................................................................................................................................................................................done. 08:27:56 INFO - Installing pip.................done. 08:27:56 INFO - Return code: 0 08:27:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:27:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:56 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 0x7f69cc275f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, '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': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', '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 08:27:56 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 08:27:56 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 08:27:56 INFO - Using env: {'DISPLAY': ':0', 08:27:56 INFO - 'HOME': '/home/cltbld', 08:27:56 INFO - 'LANG': 'en_US.UTF-8', 08:27:56 INFO - 'LANGUAGE': 'en_US:en', 08:27:56 INFO - 'LOGNAME': 'cltbld', 08:27:56 INFO - 'MAIL': '/var/mail/cltbld', 08:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:56 INFO - 'MOZ_NO_REMOTE': '1', 08:27:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:56 INFO - 'NO_EM_RESTART': '1', 08:27:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:56 INFO - 'PWD': '/builds/slave/test', 08:27:56 INFO - 'SHELL': '/bin/bash', 08:27:56 INFO - 'SHLVL': '1', 08:27:56 INFO - 'TERM': 'linux', 08:27:56 INFO - 'TMOUT': '86400', 08:27:56 INFO - 'USER': 'cltbld', 08:27:56 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:56 INFO - '_': '/tools/buildbot/bin/python'} 08:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:56 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 08:27:56 INFO - Cleaning up... 08:27:56 INFO - Return code: 0 08:27:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:27:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:56 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f69cc275f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, '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': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', '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 08:27:56 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>=3.1.1'] in /builds/slave/test/build 08:27:56 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>=3.1.1 08:27:56 INFO - Using env: {'DISPLAY': ':0', 08:27:56 INFO - 'HOME': '/home/cltbld', 08:27:56 INFO - 'LANG': 'en_US.UTF-8', 08:27:56 INFO - 'LANGUAGE': 'en_US:en', 08:27:56 INFO - 'LOGNAME': 'cltbld', 08:27:56 INFO - 'MAIL': '/var/mail/cltbld', 08:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:56 INFO - 'MOZ_NO_REMOTE': '1', 08:27:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:56 INFO - 'NO_EM_RESTART': '1', 08:27:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:56 INFO - 'PWD': '/builds/slave/test', 08:27:56 INFO - 'SHELL': '/bin/bash', 08:27:56 INFO - 'SHLVL': '1', 08:27:56 INFO - 'TERM': 'linux', 08:27:56 INFO - 'TMOUT': '86400', 08:27:56 INFO - 'USER': 'cltbld', 08:27:56 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:56 INFO - '_': '/tools/buildbot/bin/python'} 08:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:56 INFO - Downloading/unpacking psutil>=3.1.1 08:27: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 08:27: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 08:27: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 08:27: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 08:27: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 08:27: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 08:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:27:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:27:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:27:58 INFO - Installing collected packages: psutil 08:27:58 INFO - Running setup.py install for psutil 08:27:58 INFO - building 'psutil._psutil_linux' extension 08:27:58 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 08:27:58 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 08:27:59 INFO - building 'psutil._psutil_posix' extension 08:27:59 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 08:27:59 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 08:27:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:27:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:27:59 INFO - Successfully installed psutil 08:27:59 INFO - Cleaning up... 08:27:59 INFO - Return code: 0 08:27:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:27:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:59 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f69cc275f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, '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': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', '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 08:27:59 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.3'] in /builds/slave/test/build 08:27:59 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.3 08:27:59 INFO - Using env: {'DISPLAY': ':0', 08:27:59 INFO - 'HOME': '/home/cltbld', 08:27:59 INFO - 'LANG': 'en_US.UTF-8', 08:27:59 INFO - 'LANGUAGE': 'en_US:en', 08:27:59 INFO - 'LOGNAME': 'cltbld', 08:27:59 INFO - 'MAIL': '/var/mail/cltbld', 08:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:59 INFO - 'MOZ_NO_REMOTE': '1', 08:27:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:27:59 INFO - 'NO_EM_RESTART': '1', 08:27:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:59 INFO - 'PWD': '/builds/slave/test', 08:27:59 INFO - 'SHELL': '/bin/bash', 08:27:59 INFO - 'SHLVL': '1', 08:27:59 INFO - 'TERM': 'linux', 08:27:59 INFO - 'TMOUT': '86400', 08:27:59 INFO - 'USER': 'cltbld', 08:27:59 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:59 INFO - '_': '/tools/buildbot/bin/python'} 08:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:27:59 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 08:27:59 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 08:27:59 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 08:27:59 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 08:27:59 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 08:27:59 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 08:28:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:28:01 INFO - Installing collected packages: mozsystemmonitor 08:28:01 INFO - Running setup.py install for mozsystemmonitor 08:28:01 INFO - Successfully installed mozsystemmonitor 08:28:01 INFO - Cleaning up... 08:28:01 INFO - Return code: 0 08:28:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:28:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:01 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f69cc275f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, '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': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', '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 08:28:01 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==2.5.1'] in /builds/slave/test/build 08:28:01 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==2.5.1 08:28:01 INFO - Using env: {'DISPLAY': ':0', 08:28:01 INFO - 'HOME': '/home/cltbld', 08:28:01 INFO - 'LANG': 'en_US.UTF-8', 08:28:01 INFO - 'LANGUAGE': 'en_US:en', 08:28:01 INFO - 'LOGNAME': 'cltbld', 08:28:01 INFO - 'MAIL': '/var/mail/cltbld', 08:28:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:01 INFO - 'MOZ_NO_REMOTE': '1', 08:28:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:01 INFO - 'NO_EM_RESTART': '1', 08:28:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:01 INFO - 'PWD': '/builds/slave/test', 08:28:01 INFO - 'SHELL': '/bin/bash', 08:28:01 INFO - 'SHLVL': '1', 08:28:01 INFO - 'TERM': 'linux', 08:28:01 INFO - 'TMOUT': '86400', 08:28:01 INFO - 'USER': 'cltbld', 08:28:01 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:28:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:01 INFO - '_': '/tools/buildbot/bin/python'} 08:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:01 INFO - Downloading/unpacking jsonschema==2.5.1 08:28:01 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 08:28:01 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 08:28:01 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 08:28:01 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 08:28:01 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 08:28:01 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 08:28:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:28:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:28:03 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 08:28:03 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 08:28:03 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 08:28:03 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 08:28:03 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 08:28:03 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 08:28:03 INFO - Downloading functools32-3.2.3-2.tar.gz 08:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:28:03 INFO - warning: no files found matching '*.txt' 08:28:03 INFO - no previously-included directories found matching 'build' 08:28:03 INFO - no previously-included directories found matching 'dist' 08:28:03 INFO - no previously-included directories found matching '.git*' 08:28:03 INFO - Installing collected packages: jsonschema, functools32 08:28:04 INFO - Running setup.py install for functools32 08:28:04 INFO - warning: no files found matching '*.txt' 08:28:04 INFO - no previously-included directories found matching 'build' 08:28:04 INFO - no previously-included directories found matching 'dist' 08:28:04 INFO - no previously-included directories found matching '.git*' 08:28:04 INFO - Successfully installed jsonschema functools32 08:28:04 INFO - Cleaning up... 08:28:04 INFO - Return code: 0 08:28:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f69cc275f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, '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': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', '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 08:28:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:28:04 INFO - Using env: {'DISPLAY': ':0', 08:28:04 INFO - 'HOME': '/home/cltbld', 08:28:04 INFO - 'LANG': 'en_US.UTF-8', 08:28:04 INFO - 'LANGUAGE': 'en_US:en', 08:28:04 INFO - 'LOGNAME': 'cltbld', 08:28:04 INFO - 'MAIL': '/var/mail/cltbld', 08:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:04 INFO - 'MOZ_NO_REMOTE': '1', 08:28:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:04 INFO - 'NO_EM_RESTART': '1', 08:28:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:04 INFO - 'PWD': '/builds/slave/test', 08:28:04 INFO - 'SHELL': '/bin/bash', 08:28:04 INFO - 'SHLVL': '1', 08:28:04 INFO - 'TERM': 'linux', 08:28:04 INFO - 'TMOUT': '86400', 08:28:04 INFO - 'USER': 'cltbld', 08:28:04 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:04 INFO - '_': '/tools/buildbot/bin/python'} 08:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:28:04 INFO - Cleaning up... 08:28:04 INFO - Return code: 0 08:28:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f69cc275f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, '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': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', '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 08:28:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:28:04 INFO - Using env: {'DISPLAY': ':0', 08:28:04 INFO - 'HOME': '/home/cltbld', 08:28:04 INFO - 'LANG': 'en_US.UTF-8', 08:28:04 INFO - 'LANGUAGE': 'en_US:en', 08:28:04 INFO - 'LOGNAME': 'cltbld', 08:28:04 INFO - 'MAIL': '/var/mail/cltbld', 08:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:04 INFO - 'MOZ_NO_REMOTE': '1', 08:28:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:04 INFO - 'NO_EM_RESTART': '1', 08:28:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:04 INFO - 'PWD': '/builds/slave/test', 08:28:04 INFO - 'SHELL': '/bin/bash', 08:28:04 INFO - 'SHLVL': '1', 08:28:04 INFO - 'TERM': 'linux', 08:28:04 INFO - 'TMOUT': '86400', 08:28:04 INFO - 'USER': 'cltbld', 08:28:04 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:04 INFO - '_': '/tools/buildbot/bin/python'} 08:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:04 INFO - Downloading/unpacking blobuploader==1.2.4 08:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:06 INFO - Downloading blobuploader-1.2.4.tar.gz 08:28:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:28:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:28:06 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 08:28:06 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 08:28:06 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 08:28:06 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 08:28:06 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 08:28:06 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 08:28:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:28:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:28:06 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 08:28:06 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 08:28:06 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 08:28:06 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 08:28:06 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 08:28:06 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 08:28:07 INFO - Downloading docopt-0.6.1.tar.gz 08:28:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:28:07 INFO - Installing collected packages: blobuploader, requests, docopt 08:28:07 INFO - Running setup.py install for blobuploader 08:28:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:28:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:28:07 INFO - Running setup.py install for requests 08:28:07 INFO - Running setup.py install for docopt 08:28:07 INFO - Successfully installed blobuploader requests docopt 08:28:07 INFO - Cleaning up... 08:28:07 INFO - Return code: 0 08:28:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:07 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 0x7f69cc275f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, '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': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', '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 08:28:07 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 08:28:07 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 08:28:07 INFO - Using env: {'DISPLAY': ':0', 08:28:07 INFO - 'HOME': '/home/cltbld', 08:28:07 INFO - 'LANG': 'en_US.UTF-8', 08:28:07 INFO - 'LANGUAGE': 'en_US:en', 08:28:07 INFO - 'LOGNAME': 'cltbld', 08:28:07 INFO - 'MAIL': '/var/mail/cltbld', 08:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:07 INFO - 'MOZ_NO_REMOTE': '1', 08:28:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:07 INFO - 'NO_EM_RESTART': '1', 08:28:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:07 INFO - 'PWD': '/builds/slave/test', 08:28:07 INFO - 'SHELL': '/bin/bash', 08:28:07 INFO - 'SHLVL': '1', 08:28:07 INFO - 'TERM': 'linux', 08:28:07 INFO - 'TMOUT': '86400', 08:28:07 INFO - 'USER': 'cltbld', 08:28:07 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:07 INFO - '_': '/tools/buildbot/bin/python'} 08:28:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:28:08 INFO - Running setup.py (path:/tmp/pip-Hf_Huj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:28:08 INFO - Running setup.py (path:/tmp/pip-K9fd1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:28:08 INFO - Running setup.py (path:/tmp/pip-yfs4Zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:28:08 INFO - Running setup.py (path:/tmp/pip-u3T4wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:28:08 INFO - Running setup.py (path:/tmp/pip-ySaEkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:28:08 INFO - Running setup.py (path:/tmp/pip-J7gswR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:28:08 INFO - Running setup.py (path:/tmp/pip-bBD746-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:28:08 INFO - Running setup.py (path:/tmp/pip-GgMyFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:28:08 INFO - Running setup.py (path:/tmp/pip-IG3Fpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:28:08 INFO - Running setup.py (path:/tmp/pip-CbTUrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:28:08 INFO - Running setup.py (path:/tmp/pip-OJBK9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:28:08 INFO - Running setup.py (path:/tmp/pip-gTlMog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:28:08 INFO - Running setup.py (path:/tmp/pip-ZY4OTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:28:09 INFO - Running setup.py (path:/tmp/pip-oBaER0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:09 INFO - Running setup.py (path:/tmp/pip-r4zdo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:28:09 INFO - Running setup.py (path:/tmp/pip-4BLa2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:28:09 INFO - Running setup.py (path:/tmp/pip-NUIhbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:28:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:28:09 INFO - Running setup.py install for manifestparser 08:28:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:28:09 INFO - Running setup.py install for mozcrash 08:28:09 INFO - Running setup.py install for mozdebug 08:28:09 INFO - Running setup.py install for mozdevice 08:28:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:28:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:28:09 INFO - Running setup.py install for mozfile 08:28:09 INFO - Running setup.py install for mozhttpd 08:28:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:28:09 INFO - Running setup.py install for mozinfo 08:28:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Running setup.py install for mozInstall 08:28:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Running setup.py install for mozleak 08:28:10 INFO - Running setup.py install for mozlog 08:28:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Running setup.py install for moznetwork 08:28:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Running setup.py install for mozprocess 08:28:10 INFO - Running setup.py install for mozprofile 08:28:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Running setup.py install for mozrunner 08:28:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:28:10 INFO - Running setup.py install for mozscreenshot 08:28:10 INFO - Running setup.py install for moztest 08:28:11 INFO - Running setup.py install for mozversion 08:28:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:28:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:28:11 INFO - Cleaning up... 08:28:11 INFO - Return code: 0 08:28:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:11 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 0x7f69cc275f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, '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': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', '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 08:28:11 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 08:28:11 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 08:28:11 INFO - Using env: {'DISPLAY': ':0', 08:28:11 INFO - 'HOME': '/home/cltbld', 08:28:11 INFO - 'LANG': 'en_US.UTF-8', 08:28:11 INFO - 'LANGUAGE': 'en_US:en', 08:28:11 INFO - 'LOGNAME': 'cltbld', 08:28:11 INFO - 'MAIL': '/var/mail/cltbld', 08:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:11 INFO - 'MOZ_NO_REMOTE': '1', 08:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:11 INFO - 'NO_EM_RESTART': '1', 08:28:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:11 INFO - 'PWD': '/builds/slave/test', 08:28:11 INFO - 'SHELL': '/bin/bash', 08:28:11 INFO - 'SHLVL': '1', 08:28:11 INFO - 'TERM': 'linux', 08:28:11 INFO - 'TMOUT': '86400', 08:28:11 INFO - 'USER': 'cltbld', 08:28:11 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:11 INFO - '_': '/tools/buildbot/bin/python'} 08:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:28:11 INFO - Running setup.py (path:/tmp/pip-wX9GiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:28:11 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)) 08:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:28:11 INFO - Running setup.py (path:/tmp/pip-ZJkdRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:28:11 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)) 08:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:28:11 INFO - Running setup.py (path:/tmp/pip-K9nR0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:28:11 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)) 08:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:28:11 INFO - Running setup.py (path:/tmp/pip-UYMtIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:28:11 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)) 08:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:28:11 INFO - Running setup.py (path:/tmp/pip-2kSURA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:28:11 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)) 08:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:28:11 INFO - Running setup.py (path:/tmp/pip-asySS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:28:11 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)) 08:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:28:11 INFO - Running setup.py (path:/tmp/pip-a7HGvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:28:11 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)) 08:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:28:11 INFO - Running setup.py (path:/tmp/pip-Bohpf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:28:11 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)) 08:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:28:11 INFO - Running setup.py (path:/tmp/pip-w3PmyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:28:11 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)) 08:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:28:12 INFO - Running setup.py (path:/tmp/pip-SaH515-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 08:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:28:12 INFO - Running setup.py (path:/tmp/pip-4MA4Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:28:12 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)) 08:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:28:12 INFO - Running setup.py (path:/tmp/pip-TjPzeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:28:12 INFO - Running setup.py (path:/tmp/pip-HSOZqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:28:12 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)) 08:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:28:12 INFO - Running setup.py (path:/tmp/pip-ip88q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 08:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:12 INFO - Running setup.py (path:/tmp/pip-zeEjmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:12 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)) 08:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:28:12 INFO - Running setup.py (path:/tmp/pip-DzDsz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:28:12 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)) 08:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:28:12 INFO - Running setup.py (path:/tmp/pip-MLaUkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:28:12 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)) 08:28:12 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)) 08:28:12 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)) 08:28:12 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)) 08:28:12 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)) 08:28:12 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)) 08:28:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:28:12 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 08:28:12 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 08:28:12 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 08:28:12 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 08:28:12 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 08:28:12 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 08:28:14 INFO - Downloading blessings-1.6.tar.gz 08:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:28:14 INFO - Installing collected packages: blessings 08:28:14 INFO - Running setup.py install for blessings 08:28:14 INFO - Successfully installed blessings 08:28:14 INFO - Cleaning up... 08:28:14 INFO - Return code: 0 08:28:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:28:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:28:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:28:15 INFO - Reading from file tmpfile_stdout 08:28:15 INFO - Current package versions: 08:28:15 INFO - argparse == 1.2.1 08:28:15 INFO - blessings == 1.6 08:28:15 INFO - blobuploader == 1.2.4 08:28:15 INFO - docopt == 0.6.1 08:28:15 INFO - functools32 == 3.2.3-2 08:28:15 INFO - jsonschema == 2.5.1 08:28:15 INFO - manifestparser == 1.1 08:28:15 INFO - mozInstall == 1.12 08:28:15 INFO - mozcrash == 0.17 08:28:15 INFO - mozdebug == 0.1 08:28:15 INFO - mozdevice == 0.48 08:28:15 INFO - mozfile == 1.2 08:28:15 INFO - mozhttpd == 0.7 08:28:15 INFO - mozinfo == 0.9 08:28:15 INFO - mozleak == 0.1 08:28:15 INFO - mozlog == 3.3 08:28:15 INFO - moznetwork == 0.27 08:28:15 INFO - mozprocess == 0.23 08:28:15 INFO - mozprofile == 0.28 08:28:15 INFO - mozrunner == 6.12 08:28:15 INFO - mozscreenshot == 0.1 08:28:15 INFO - mozsystemmonitor == 0.3 08:28:15 INFO - moztest == 0.7 08:28:15 INFO - mozversion == 1.4 08:28:15 INFO - psutil == 3.1.1 08:28:15 INFO - requests == 1.2.3 08:28:15 INFO - wsgiref == 0.1.2 08:28:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:15 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 0x7f69cc275f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, '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': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', '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 08:28:15 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 08:28:15 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 08:28:15 INFO - Using env: {'DISPLAY': ':0', 08:28:15 INFO - 'HOME': '/home/cltbld', 08:28:15 INFO - 'LANG': 'en_US.UTF-8', 08:28:15 INFO - 'LANGUAGE': 'en_US:en', 08:28:15 INFO - 'LOGNAME': 'cltbld', 08:28:15 INFO - 'MAIL': '/var/mail/cltbld', 08:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:15 INFO - 'MOZ_NO_REMOTE': '1', 08:28:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:15 INFO - 'NO_EM_RESTART': '1', 08:28:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:15 INFO - 'PWD': '/builds/slave/test', 08:28:15 INFO - 'SHELL': '/bin/bash', 08:28:15 INFO - 'SHLVL': '1', 08:28:15 INFO - 'TERM': 'linux', 08:28:15 INFO - 'TMOUT': '86400', 08:28:15 INFO - 'USER': 'cltbld', 08:28:15 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:15 INFO - '_': '/tools/buildbot/bin/python'} 08:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:15 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)) 08:28:15 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)) 08:28:15 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)) 08:28:15 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)) 08:28:15 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)) 08:28:15 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)) 08:28:15 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)) 08:28:15 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)) 08:28:15 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)) 08:28:15 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)) 08:28:15 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)) 08:28:15 INFO - Cleaning up... 08:28:15 INFO - Return code: 0 08:28:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:15 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 0x7f69cc275f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21dd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, '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': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', '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 08:28:15 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 08:28:15 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 08:28:15 INFO - Using env: {'DISPLAY': ':0', 08:28:15 INFO - 'HOME': '/home/cltbld', 08:28:15 INFO - 'LANG': 'en_US.UTF-8', 08:28:15 INFO - 'LANGUAGE': 'en_US:en', 08:28:15 INFO - 'LOGNAME': 'cltbld', 08:28:15 INFO - 'MAIL': '/var/mail/cltbld', 08:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:15 INFO - 'MOZ_NO_REMOTE': '1', 08:28:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:15 INFO - 'NO_EM_RESTART': '1', 08:28:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:15 INFO - 'PWD': '/builds/slave/test', 08:28:15 INFO - 'SHELL': '/bin/bash', 08:28:15 INFO - 'SHLVL': '1', 08:28:15 INFO - 'TERM': 'linux', 08:28:15 INFO - 'TMOUT': '86400', 08:28:15 INFO - 'USER': 'cltbld', 08:28:15 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:15 INFO - '_': '/tools/buildbot/bin/python'} 08:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:28:15 INFO - Cleaning up... 08:28:15 INFO - Return code: 0 08:28:15 INFO - Running post-action listener: _resource_record_post_action 08:28:15 INFO - Running post-action listener: _start_resource_monitoring 08:28:15 INFO - Starting resource monitoring. 08:28:15 INFO - [mozharness: 2016-08-22 15:28:15.613707Z] Finished create-virtualenv step (success) 08:28:15 INFO - [mozharness: 2016-08-22 15:28:15.614311Z] Running install step. 08:28:15 INFO - Running pre-action listener: _resource_record_pre_action 08:28:15 INFO - Running main action method: install 08:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:28:15 INFO - Reading from file tmpfile_stdout 08:28:15 INFO - Detecting whether we're running mozinstall >=1.0... 08:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:28:15 INFO - Reading from file tmpfile_stdout 08:28:15 INFO - Output received: 08:28:15 INFO - Usage: mozinstall [options] installer 08:28:15 INFO - Options: 08:28:15 INFO - -h, --help show this help message and exit 08:28:15 INFO - -d DEST, --destination=DEST 08:28:15 INFO - Directory to install application into. [default: 08:28:15 INFO - "/builds/slave/test"] 08:28:15 INFO - --app=APP Application being installed. [default: firefox] 08:28:15 INFO - mkdir: /builds/slave/test/build/application 08:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:28:31 INFO - Reading from file tmpfile_stdout 08:28:31 INFO - Output received: 08:28:31 INFO - /builds/slave/test/build/application/firefox/firefox 08:28:32 INFO - Running post-action listener: _resource_record_post_action 08:28:32 INFO - [mozharness: 2016-08-22 15:28:32.000752Z] Finished install step (success) 08:28:32 INFO - [mozharness: 2016-08-22 15:28:32.001002Z] Running run-tests step. 08:28:32 INFO - Running pre-action listener: _resource_record_pre_action 08:28:32 INFO - Running main action method: run_tests 08:28:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:28:32 INFO - Python 2.7.3 08:28:32 INFO - Return code: 0 08:28:32 INFO - grabbing minidump binary from tooltool 08:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:32 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d7090>, '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 0x21da9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21dae40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:28:32 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 08:28:32 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 08:28:32 INFO - Calling ['/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'] with output_timeout 600 08:28:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:28:32 INFO - Return code: 0 08:28:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:28:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:28:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:28:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:28:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:28:32 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:28:32 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:28:32 INFO - Using env: {'DISPLAY': ':0', 08:28:32 INFO - 'HOME': '/home/cltbld', 08:28:32 INFO - 'LANG': 'en_US.UTF-8', 08:28:32 INFO - 'LANGUAGE': 'en_US:en', 08:28:32 INFO - 'LOGNAME': 'cltbld', 08:28:32 INFO - 'MAIL': '/var/mail/cltbld', 08:28:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:28:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:32 INFO - 'MOZ_NO_REMOTE': '1', 08:28:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:28:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:28:32 INFO - 'NO_EM_RESTART': '1', 08:28:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:32 INFO - 'PWD': '/builds/slave/test', 08:28:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:28:32 INFO - 'SHELL': '/bin/bash', 08:28:32 INFO - 'SHLVL': '1', 08:28:32 INFO - 'TERM': 'linux', 08:28:32 INFO - 'TMOUT': '86400', 08:28:32 INFO - 'USER': 'cltbld', 08:28:32 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776', 08:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:32 INFO - '_': '/tools/buildbot/bin/python'} 08:28:32 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:28:32 INFO - mozversion application_buildid: 20160822072715 08:28:32 INFO - mozversion application_changeset: 95e17b89b51955aabf9986ef9f2f973053630579 08:28:32 INFO - mozversion application_display_name: Nightly 08:28:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:28:32 INFO - mozversion application_name: Firefox 08:28:32 INFO - mozversion application_remotingname: firefox 08:28:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:28:32 INFO - mozversion application_vendor: Mozilla 08:28:32 INFO - mozversion application_version: 51.0a1 08:28:32 INFO - mozversion platform_buildid: 20160822072715 08:28:32 INFO - mozversion platform_changeset: 95e17b89b51955aabf9986ef9f2f973053630579 08:28:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:28:32 INFO - mozversion platform_version: 51.0a1 08:28:32 INFO - using testdate: 1471879712 08:28:32 INFO - actual date: 1471879712 08:28:32 INFO - starting webserver on 'localhost:39379' 08:28:32 INFO - SUITE-START | Running 2 tests 08:28:32 INFO - TEST-START | dromaeo_css 08:28:32 INFO - operating with platform_type : linux_ 08:28:32 INFO - Initialising browser for dromaeo_css test... 08:28:32 INFO - TEST-INFO | started process 7067 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLYDzzh/profile http://localhost:39379/getInfo.html) 08:28:36 INFO - PROCESS | 7067 | __metrics Screen width/height:1600/1200 08:28:36 INFO - PROCESS | 7067 | colorDepth:24 08:28:36 INFO - PROCESS | 7067 | Browser inner width/height: 1024/697 08:28:36 INFO - PROCESS | 7067 | __metrics 08:28:37 INFO - PROCESS | 7067 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:28:37 INFO - PROCESS | 7067 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:28:37 INFO - PROCESS | 7067 | console.error: PushService: 08:28:37 INFO - PROCESS | 7067 | stateChangeProcessEnqueue: Error transitioning state 08:28:37 INFO - PROCESS | 7067 | UnknownError 08:28:38 INFO - TEST-INFO | 7067: exit 0 08:28:38 INFO - Browser initialized. 08:28:38 INFO - Running cycle 1/1 for dromaeo_css test... 08:28:38 INFO - TEST-INFO | started process 7148 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLYDzzh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:50 INFO - PROCESS | 7148 | RSS: Main: 175341568 08:28:50 INFO - PROCESS | 7148 | 08:29:42 INFO - PROCESS | 7148 | 1471879782277 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] 08:31:53 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:39379/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39379/tests/dromaeo/cssquery-ext.html) 08:31:54 INFO - PROCESS | 7148 | RSS: Main: 264572928 08:31:54 INFO - PROCESS | 7148 | 08:34:57 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:39379/tests/dromaeo/cssquery-ext.html (next: http://localhost:39379/tests/dromaeo/cssquery-jquery.html) 08:34:58 INFO - PROCESS | 7148 | RSS: Main: 269152256 08:34:58 INFO - PROCESS | 7148 | 08:38:00 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:39379/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39379/tests/dromaeo/cssquery-mootools.html) 08:38:01 INFO - PROCESS | 7148 | RSS: Main: 260075520 08:38:01 INFO - PROCESS | 7148 | 08:41:03 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:39379/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39379/tests/dromaeo/cssquery-prototype.html) 08:41:04 INFO - PROCESS | 7148 | RSS: Main: 220815360 08:41:04 INFO - PROCESS | 7148 | 08:44:07 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:39379/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39379/tests/dromaeo/cssquery-yui.html) 08:44:08 INFO - PROCESS | 7148 | RSS: Main: 219795456 08:44:08 INFO - PROCESS | 7148 | 08:47:11 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:39379/tests/dromaeo/cssquery-yui.html (next: http://localhost:39379/tests/dromaeo/cssquery-dojo.html) 08:47:11 INFO - PROCESS | 7148 | RSS: Main: 308944896 08:47:11 INFO - PROCESS | 7148 | 08:47:11 INFO - PROCESS | 7148 | __start_tp_report 08:47:11 INFO - PROCESS | 7148 | _x_x_mozilla_page_load 08:47:11 INFO - PROCESS | 7148 | _x_x_mozilla_page_load_details 08:47:11 INFO - PROCESS | 7148 | |i|pagename|runs| 08:47:11 INFO - PROCESS | 7148 | |0;dojo.html;2548.2610043087507;2606.6741657292837;2609.321576390138;2616.2151354593616;2629.513539995101;4520.751358675271;4580.244259697154;4654.860354189375;4720.055988802236;4737.336772851803;4429.889252768674;4434.091011342675;4443.24464840977;4574.83988060418;4662.9533704662945;4031.4355990161403;4075.510938687362;4082.632563069323;4112.115895082557;4173.770442625654;4082.795860206978;4152.854650087247;4165.604267594589;4175.728577642459;4181.790910454481;4111.506619205685;4185.686073544466;4187.497500299953;4214.325707886724;4250.659947204216;4261.829526818923;4265.4881414230495;4267.786610669454;4269.060806622538;4272.166927449149;4221.5567365426805;4232.957670423318;4238.470191226097;4242.321228603409;4247.299195632748;4421.823127074913;4550.203714349976;4585.495595484527;4598.310253461974;4643.048175124091;4900.730459824708;4937.456879743226;4941.876453088702;4944.728039957801;4966.701997880139;4991.6256280778725;5017.799288028475;5025.497450254945;5025.597952163818;5028.823991160329;4475.30632751924;4589.151007063704;4601.8849528761375;4609.792559334838;4676.883077923079;5084.872878178001;5108.591312694975;5113.0285245803925;5120.078385890501;5121.334226550524;4889.926651100236;4899.167141585939;4910.926336104962;4914.483979182206;4919.434265059493;4811.542903424169;4855.368802055781;4892.755362231874;4902.681325713817;4983;4922.75386230687;4932.654714169974;4934.703917764946;4936.506349365035;4939.777710002986;4923.261510773341;4931.728754918442;4937.629677774181;4938.728369939688;4946.975265123651;4131.400946862688;4167.499900011957;4170.52039015511;4205.873823785291;4300.634446072057;3621.764585301947;3658.926821463609;3683.3922402803237;3731.738778285494;3794.6205379461835;4305.97847010763;4307.935380969288;4316;4317.740106219847;4322.546132656089;4947.035328111056;4983.426905905795;4984.27727979441;4996.3504744383;4998.900021999612;4177.248095342868;4257.74453532789;4269.722468039629;4283.293256612624;4345.847895323712;4507.143642707875;4540.8637740867825;4550.112728018071;4551.840685575989;4558.680892337505;4749.240121580599;4755.429348478136;4773.164696178224;4774.164521208774;4775.42694876617;4784.593309568587;4818.10864989978;4862.6839255448285;4874.658773885794;4939.53074457926;4665.976670116627;4673.626109911267;4681.204195286878;4690.1792186366565;4710.411198600175;4861.805527778849;4868.099401610713;4870.4155501340065;4872.561469467765;4876.609871210366;4807.471178170328;4860.3195552623365;4888.633352498506;4903.092927808367;4974.726390048582;4821.831235906727;4852.927206091841;4855.441624213119;4857.9028419432125;4857.9514204858915;4835.709857408567;4851.417829860298;4852.102361063215;4852.126617208936;4857.247126695368;4619.745913974763;4706.8117275308605;4720.079584480994;4751.049790041936;4765.833195838129;4840.685355451885;4866.1484240257105;4871.318015477907;4878.829240976549;4883.023395321021;4737.857864264078;4743.810247590058;4746.596539294199;4764.071006153906;4773.164696178085 08:47:11 INFO - PROCESS | 7148 | |1;ext.html;560.32480860563;561.9241402410674;582.5688990147293;586.7506309818326;587.553459370878;37075.81462092689;38357.80821095891;38412.46988012038;42271.365929511085;42294;38992;39211.607883921155;39484.60515394853;39616.60383396165;39666.206675866466;7570.735024274138;7710.267524585155;7712.961435192816;7717.189695081992;7717.768466946;7617.162112167657;7700.306972372486;7700.730474433396;7705.075390953093;7707.07515098189;7701.576413297266;7734.226577342277;7740.419468539854;7743.5353878767455;7752.224777522231;7718.961405192967;7749.806254843617;7750.534967901944;7755.689772409097;7773.766786996399;7482.792576190037;7540.132884718273;7541.6983320667105;7543.660535275871;7548.358389536896;36657.63342366569;36995;37015.074623134366;37051.25897482062;37133.62866371329;2971.658259300165;3132.8276944768027;3393.2874096439778;3440.7419443541835;3476.8087755173456;28555.57166642502;28592.857035714896;28777.712222877923;29444.8527757362;30039;3303.4803990164555;3316.2372654289647;3318.6183588887347;3346.6987971082476;3347.748918831073;239231;239338;239804;240443;241335;27385.31536711558;28047.71952280492;44399.778001109786;44526.55473445289;44627;32006.67993320037;32410.83794581012;32451.675483245337;32475.18812029718;32483;12607.747845043048;12726.045546584044;12759.106862519535;12764.936175319064;12772.297523636289;10305.175585953106;10325;10329.690109296733;10331.741706457398;10337.276390652733;7704.229577042251;7722.266384693445;7736.574488403079;7738.7678369649;7803.180666030099;3069.3554353585996;3080.383923215366;3096.814191148493;3143.654198038214;3143.8899638512544;873.4540911930044;914.3279689428265;959.2757468111531;969.6170012844887;972.2999440402899;25429.491410172064;26991.86504067467;27348;27617.585736213972;27653.723462765516;942.9151376376159;958.4872093430121;1001.9849702254472;1022.8005538919988;1023.22746326523;13888.583342499742;14679.633009174857;14736.26318684083;14836.184009879344;15025.474108406153;391286;394051;394616;394948;394974;47157.292640610416;47642;47698;47701.807454813905;47709;1087.4508373271447;1134.8411222428701;1135.795556799784;1201.627495476405;1215.5927764199093;24717.50564988654;28330;28861.278468037628;28871.56692649654;29079.56380654334;39880.40179397253;40440.59559404486;40449.79775101106;40500.1899962005;40510.392344115455;299573;300060;300098;300583;302523;21304.147834086463;23433.88283058574;24287.149949751674;24397.26808195757;24521;39871.60128398795;39903.40144897887;40010.399844002946;40022.59977400189;40890;32237.722716320906;33598.328033439684;34157.65842341545;34251.828740857134;34966;33936.66063339335;34402.13994650054;34473.65526344805;34522.82738586291;34595.13512162216;33980.150496237795;34222.828885856405;34464.65535344614;34738.65261347455;34756.30487390289;28671.71328286774;30938;31156.844215778776;31213.219669508442;31286.687133128376;23614.763852361943;24287.842669845333;24306.392340191636;24331.39171520726;24458.633120503564 08:47:11 INFO - PROCESS | 7148 | |2;jquery.html;366.21812430460966;366.6297039989611;369.5417682074229;385.0604524959101;392.74864086984337;15915.522534323974;16691.248893799777;17248.223829927636;17321.82678173218;17390.130493475306;16111.83888161118;16745;16878;16952.152392380394;16981.91509042456;5006.97496512517;5269.051570717269;5270.631055826094;5272.051030814452;5279.604029697769;3847.153626202231;4044.170944956292;5201.635885488017;5914.260717410324;6181.59819611726;6272.466840318579;6276.807406592756;6279.811605651815;6281.183446151994;6283.3088360280335;5952.851178720545;6100.755969761204;6117.816465506042;6130.080487926802;6134.294556126036;5218.817341393071;5373.113436283009;5373.973130134364;5374.462553744634;5467.781288748445;20976.685349719766;21105.894470527703;21223;21232;21405.892970535202;1930.806919308072;2172.902219400115;2277.906604829691;2305.8616483010906;2409.4096946248283;13256.138351007154;13335.533256335983;13356.465741370333;13628.318584070757;14070.085444446078;1741.8954862708279;1766.4612293250555;1815.7911840138422;1841.235887106849;1851.0652120679106;65460;67162;67987;68223;69186.30813691899;9394.389364691273;9427.952860235791;9750.219982401391;9752.756181095387;9968.501574921225;13671.316434178252;13690.247036413093;13709.725805483835;13759.036867419385;13767;7091.82270443243;7268.6729097190755;7305.3790427813165;7465.70137194517;7589.468737188344;6628.304028076983;6848.554843935129;6963.617001064888;6979.89530157048;7121.537100088478;6036.215292012011;6152.692740222416;6153.139020174012;6296.118543403927;6347.55567110307;1657.3453485873254;1767.4167524716813;1782.97325540117;1801.8378345948665;1818.1908751724875;693.7398475571661;832.76682528892;851.9190676885686;895.2524641923936;981.764376549623;16781.147204975183;17081.914590427215;17390.65218695619;18166.727499087523;19079.427617171506;803.1326167738828;837.4556538250064;856.4304737349593;880.4541184465672;890.3233542507611;9064.728058158265;10001.449920254456;10009.249306302056;10195.387683005796;10200.3369780964;434594;436736;437961;438288;439464;74995.62502187454;76453;77978;78491.60754196307;81810.59094704608;840.8318336332758;922.1424075609614;933.2114363362947;936.6159874451442;947.5783276441917;15824.683506330039;15907.602309942344;16188;16450.75323870119;16579.253933572778;36403.45394819133;37277.25445491129;37952.620473796014;38272.61727382691;38363.040923977125;47090;49862.750686246334;50134.7493262546;50162.74918625529;52177.47822521726;6930.445564354739;7132.108486439195;7170.856582868418;7440.665170067252;7569.091708994956;9230.307726920373;11195;11336.433178340885;11349.602763903224;11505.80384792123;9894.455804930798;9922.156616687374;10168.694939151837;10206.8514592537;10357;9661.323707340418;9783.657571984946;9813.754656133367;10249.38503689781;10381.792364152825;9159.175674189355;9856.162226210852;9968.950155249177;10184.185265178916;10450.895491044992;6200.56596038273;7175.2465991070185;7178.113450901687;7234.095738032746;7349.816254593464;8149.674013039649;9626.759331016781;9985.800283994135;10581.576736930436;10618.415987120474 08:47:11 INFO - PROCESS | 7148 | |3;mootools.html;1249.9375031248435;1358.2868993778266;1395.3930040432413;1467.3176972707695;1701.1154199816087;6550.115734375864;7131.893021604674;7459.104907411104;7470.140933792609;7698.461107722448;7515.473916825825;7567.167611562723;7567.394608431326;7567.508111972732;7569.545827250355;2186.5736181444663;2214.457457922811;2215.3575463115676;2215.7119574455296;2217.168561789329;2145.420736401171;2176.1513009926143;2198.4503874031493;2198.604251234777;2200.9889950550223;2129.265403435812;2145.871247725134;2146.2488129154826;2147.473868902121;2179.923702670407;2169.7504786949467;2174.7716489768663;2175.510510135223;2180.661997390407;2182;2134.4770531219797;2176.967345489819;2186.0709198590694;2187.562487502506;2188.4310079379316;7404.296591823822;7440.962795186044;7457.552546847207;7553.1313898901235;7553.32020118192;4500.482444518857;4657.068586282757;4662.347271382009;4662.883427914329;4669.369635099252;2792.8743206555755;2904.6804851466322;2928.8096273742353;2954.778391620637;2974.6430428348735;947.7630592351912;964.9903500964971;968.4625033106623;974.2303580171656;980.7057882635181;10726.624568140078;10765.62320318793;10774.838377424345;10780.245382823205;10805.189610779224;3978.3435733103715;4125.690573206962;4184.225918205142;4337.503561271356;4349.173657005159;7947.642356093989;8050.637721302555;8067.435279530494;8075.878861817077;8076;4511.007578332761;4511.751853648081;4514.68397212192;4515.412996310459;4515.503294637587;3473.565804274466;3610.7291953103095;3724.6275372462537;4174.707770456039;4175.749455032707;3502.5796904371637;3574.660407261306;3617;3644.817759112034;3646.5077214575695;1081.5565618096543;1093.2292733622935;1105.0772604874858;1106.9667909962714;1118.9216754827246;727.4071631620212;736.5543845973109;736.9852602948018;751.7256201486418;768.8616049111214;4974.602031837444;5060.569851562658;5128.8717782055;5137.6660517066275;5154.871128221825;397.52098721041074;403.98788036358957;415.49932331540424;417.35935339254706;419.06339331593864;3445.3453843769603;3516.4549494828343;3533.1343820763564;3546.2021045265124;3564.091156755011;9168.174864262248;9587.424754514752;9613.951930240304;9638.518074096268;9651.372660777028;9352.906470935339;9568.09103135201;9634.084761947604;9652.951735241419;9670.661526846527;7310.268973102754;7389.224131466226;7389.556626602421;7396;7404.222556631584;5042.672226305279;5069.619778516552;5102.668326558762;5145.639805213599;5370.919436208538;8784.165504277084;8819.338549608678;8859.64561417536;8883.911160888309;8952.194302512806;7799.103103143211;8762.868556971524;9052.81894362096;9152.862707059532;9155.633774648939;5143.8456846294675;5928.9407105928385;6028.3971602839365;6031.215941927521;6048.727807248596;7404.111506619241;7460.738874139379;7462.589557574169;7471.066116735408;7472.29013243741;6589.24223714279;6722.932770672426;6770.796876093725;6797.524173307821;6845.418139458202;6452.645104519296;6466.418022378009;6510.348965103642;6510.902336464862;6579.17760279965;6000;6057.363976782374;6121.510279177566;6121.816345509464;6123.14276001369;4375.321825117112;4422.601965823092;4455.665825063068;4472.932906006347;4476.239039363251;4465.1962646723905;4558.407407037196;4636;4668.743219122845;4860.149473842134 08:47:11 INFO - PROCESS | 7148 | |4;prototype.html;299.5282430172478;305.45323870272216;306.28482493377976;308.75454014058823;309.1205520294762;4721.78751956162;4783.545563171498;4787.8324258650955;4792.688475249106;4810.975945120278;4728.1962066448705;4740.69185502942;4757;4757.167495688258;4760.214919405223;3534.8055856927795;3628.5827129880136;3654;3688.5389326334207;3725.5715592706915;3723.90144907253;3724.2365315110337;3740.2519496100754;3753.8123093845334;3760.135168911152;3698.685611723007;3702.592714801383;3719.0516418313427;3731.738778285521;3744.2698673758628;3523.453864650983;3607.6753092221825;3728.4034554471154;3750.79370634615;3758.39865621502;3315.7015868571702;3324.6675332466803;3327.2846338037198;3335.599048399678;3338.3490219407227;4588.0959401848195;4590.954090459086;4603.49361570227;4603.608693261077;4604.884877878047;4111.157212771374;4225.894352641178;4260.616544510978;4267.381229721673;4274.952636604052;4856.271559266103;4868.53748893858;4873.171560834631;4893.486183950669;4901.8774530636665;3586.946195807065;3601.8654123950873;3603.5315408996926;3622.1487950331652;3635.127569383329;5041.344625198737;5077.64456488046;5120.692758434506;5157.716325602128;5160.277561141481;3334.2664613785414;3540.964590354115;3556.2396613676397;3556.87550935716;3571.714262858965;3598.604153543108;3604.945925811115;3606.2787442511603;3610.7613279766188;3613.6205698401814;4101.630853223224;4137.937930931039;4154.335306350969;4161.8335266589;4163.91672166555;4728.38530990969;4911.336969509142;4949.678270912379;4964.801407943714;4968.130577148984;1229.7110179107904;1257.3398965543163;1258.1381753498883;1259.6850787303174;1261.5521489871146;801.2527601725066;807.6648191000786;822.761399194227;824.7814329202766;826.1903334731996;4311.805968731352;4426.3581780641625;4430.468343798765;4432.360026790044;4445.488768791631;5021.673591216632;5063.746812659426;5073.213651883964;5085.288059671649;5094.974525127424;4404.955950440519;4408.140412619508;4411.117776444724;4423.469183697976;4423.60187583119;2526.1685278173545;2678.1429942418627;2687.965133423607;2688.9596656050176;2700.189943017087;4833.178359678863;4842.031593681278;4855.32025516428;4855.5387238212315;4882.194437917775;5025.974870125626;5182.6113041521285;5187;5207.583393328525;5231.607629427808;4808.447028591687;4811.230203167476;4814.903701926012;4834.540718631724;4871.926921096187;5005.899882002413;5019.272205530105;5032.320636714143;5040;5077.92383114261;4774.379330686988;4944.8763780905765;4978.551930326288;5045;5054.256281582848;4831.154547954024;4836.403986684456;4887.383336331019;4887.43794463627;4893.951060489349;3617.258462015228;3617.5297211362354;3624.0939765058733;3631.763935344194;3641.217138315275;5105.897882042413;5123.180291153547;5130.58955283569;5138.511841375063;5144.356955380577;4673.9298910517045;4920.573033820152;4957.281194226889;4960.74286199771;4980.850574482772;4945.032980211844;4959.256111583291;4970.552650261494;4975.303457515878;4990.700557966534;4879.39007624047;4922.630802689741;4932.28481870134;4938.185199442196;4949.381327334083;4767.880802979953;4842.104210721028;4906.681065730716;4948.134076536519;4951.539295907788;4810.783514741915;4813.638977076664;4825.203841366276;4827.348307978378;4831.492693267156 08:47:11 INFO - PROCESS | 7148 | |5;yui.html;600.2436929468869;632.977845775398;681.9693113809876;689.7930620813761;691.730225212167;11826.113041521889;13035.478580856778;13136.671583210424;13203.603891883235;13214.735705285888;13495.73008539831;13659.180449173056;13665.521706740266;13679.658008549766;13944.93027534861;752.0411475368921;770.1912991359051;772.8067983004249;774.6436639146;777.525709317316;628.7830698409065;636.748484348682;750.4371721209093;771.7106085218044;772.818387678895;812.9065157506903;813.2030610002201;814.8003739083932;815.9796005099861;817.8078151634361;767.6046835879526;808.9150639182918;808.9352851771844;809.5102463009853;810;480.4498848818084;572.6249306704116;579.9623024503394;585.5988647776244;594.7739858853652;12715.618531444072;12735.044871634664;12736.681582960407;12741.745165096647;12752.681182970407;12056.34760352721;12541.749165016648;12785.424655890507;12868.613941581767;13513.797293040514;9345.345825792198;9633.291182894636;11256.437178141061;11322.830157547643;11422.143339249506;4003.4195041718785;4048.352263637804;4063.065494936181;4088.632023117904;4180.561041090672;59012.70493647504;59537.40462595362;59677.40322596762;59768.701156494375;60534.697326513524;9368.90631093694;9605.567749451291;9667.27495437845;9791.134395721254;10017.545701303226;17354;17504.562385940455;18123.728144077584;18177.36379226747;18984.905075474533;6205.100260462298;6338.271098823694;6366.904496432558;6390.424861762371;6617.073609694647;3642.5446819147605;3661.469086982426;3825.923481530354;3829.4064420014943;3878.282517734172;2825.6467941507312;2954.2023653613405;2955.866985985636;2957.807742496731;2963.6147300850753;526.2264471227289;543.2910052381601;546.4863028753039;552.1524459953952;564.9237352957298;496.4786973677624;556.6854727079187;575.9078547432391;591.9644821310735;609.515435228994;8037.718679846177;8414.6213420395;8628.39601227908;8844.955775221082;9018.909810901807;567.3787203012695;570.1191658887029;591.5238233222211;603.1194456094152;611.135243630267;3665.413533834627;3931.81288694796;4020.8994775130855;4038.1116154445976;4242.830286788494;102972.48513757384;103545;104829;105074.7578285083;106020;67369.66315168393;69358.65320673466;70414.64792676004;70457;83450.58274708709;42904.356434653506;59191.40808591859;61581.692091539255;67384.6630766843;70649.64675176592;7671.693132274647;8042.396820238622;8245.95877020635;8357.582120894052;8363.707270245512;29694.406111877208;30833.8458307716;31392.843035784674;37262.62737372592;41364.1727165449;54645.72677136589;55575.444245557024;56178;56224.43775562192;56937.43062569487;10005.649802257178;10340.327878687862;10482.68551943443;10622.73443163927;11131.10951123892;11234.191452136965;11438.142139339416;12097.334646594521;13390.263535505252;13496.662583435493;10844.837327440253;10962.068224200713;11685.474153662935;11699.122565807429;11710;10846.457677115955;11227.831582526433;11357.829632555684;12050.698732531757;12051.81922271149;11081.944590276997;11134.498947547218;11234.719132021764;11706;12088.758224835277;9348.392354496937;9871.950640246752;10047.246456515644;10128.848067279145;10271.897281027394;8971.372003959661;9084.454932704059;9299.349045566745;9942.30403871722;10047.296689231684 08:47:11 INFO - PROCESS | 7148 | __end_tp_report 08:47:11 INFO - PROCESS | 7148 | __start_cc_report 08:47:11 INFO - PROCESS | 7148 | _x_x_mozilla_cycle_collect,922 08:47:11 INFO - PROCESS | 7148 | __end_cc_report 08:47:11 INFO - PROCESS | 7148 | __startTimestamp1471880831578__endTimestamp 08:47:11 INFO - PROCESS | 7148 | 08:47:11 INFO - PROCESS | 7148 | ------- Summary: start ------- 08:47:11 INFO - PROCESS | 7148 | Number of tests: 6 08:47:11 INFO - PROCESS | 7148 | 08:47:11 INFO - PROCESS | 7148 | [#0] dojo.html Cycles:165 Average:4565.70 Median:4737.34 stddev:482.79 (10.2%) stddev-sans-first:457.60 08:47:11 INFO - PROCESS | 7148 | Values: 2548.3 2606.7 2609.3 2616.2 2629.5 4520.8 4580.2 4654.9 4720.1 4737.3 4429.9 4434.1 4443.2 4574.8 4663.0 4031.4 4075.5 4082.6 4112.1 4173.8 4082.8 4152.9 4165.6 4175.7 4181.8 4111.5 4185.7 4187.5 4214.3 4250.7 4261.8 4265.5 4267.8 4269.1 4272.2 4221.6 4233.0 4238.5 4242.3 4247.3 4421.8 4550.2 4585.5 4598.3 4643.0 4900.7 4937.5 4941.9 4944.7 4966.7 4991.6 5017.8 5025.5 5025.6 5028.8 4475.3 4589.2 4601.9 4609.8 4676.9 5084.9 5108.6 5113.0 5120.1 5121.3 4889.9 4899.2 4910.9 4914.5 4919.4 4811.5 4855.4 4892.8 4902.7 4983.0 4922.8 4932.7 4934.7 4936.5 4939.8 4923.3 4931.7 4937.6 4938.7 4947.0 4131.4 4167.5 4170.5 4205.9 4300.6 3621.8 3658.9 3683.4 3731.7 3794.6 4306.0 4307.9 4316.0 4317.7 4322.5 4947.0 4983.4 4984.3 4996.4 4998.9 4177.2 4257.7 4269.7 4283.3 4345.8 4507.1 4540.9 4550.1 4551.8 4558.7 4749.2 4755.4 4773.2 4774.2 4775.4 4784.6 4818.1 4862.7 4874.7 4939.5 4666.0 4673.6 4681.2 4690.2 4710.4 4861.8 4868.1 4870.4 4872.6 4876.6 4807.5 4860.3 4888.6 4903.1 4974.7 4821.8 4852.9 4855.4 4857.9 4858.0 4835.7 4851.4 4852.1 4852.1 4857.2 4619.7 4706.8 4720.1 4751.0 4765.8 4840.7 4866.1 4871.3 4878.8 4883.0 4737.9 4743.8 4746.6 4764.1 4773.2 08:47:11 INFO - PROCESS | 7148 | 08:47:11 INFO - PROCESS | 7148 | [#1] ext.html Cycles:180 Average:44755.92 Median:18921.44 stddev:83973.02 (443.8%) stddev-sans-first:84143.03 08:47:11 INFO - PROCESS | 7148 | Values: 560.3 561.9 582.6 586.8 587.6 37075.8 38357.8 38412.5 42271.4 42294.0 38992.0 39211.6 39484.6 39616.6 39666.2 7570.7 7710.3 7713.0 7717.2 7717.8 7617.2 7700.3 7700.7 7705.1 7707.1 7701.6 7734.2 7740.4 7743.5 7752.2 7719.0 7749.8 7750.5 7755.7 7773.8 7482.8 7540.1 7541.7 7543.7 7548.4 36657.6 36995.0 37015.1 37051.3 37133.6 2971.7 3132.8 3393.3 3440.7 3476.8 28555.6 28592.9 28777.7 29444.9 30039.0 3303.5 3316.2 3318.6 3346.7 3347.7 239231.0 239338.0 239804.0 240443.0 241335.0 27385.3 28047.7 44399.8 44526.6 44627.0 32006.7 32410.8 32451.7 32475.2 32483.0 12607.7 12726.0 12759.1 12764.9 12772.3 10305.2 10325.0 10329.7 10331.7 10337.3 7704.2 7722.3 7736.6 7738.8 7803.2 3069.4 3080.4 3096.8 3143.7 3143.9 873.5 914.3 959.3 969.6 972.3 25429.5 26991.9 27348.0 27617.6 27653.7 942.9 958.5 1002.0 1022.8 1023.2 13888.6 14679.6 14736.3 14836.2 15025.5 391286.0 394051.0 394616.0 394948.0 394974.0 47157.3 47642.0 47698.0 47701.8 47709.0 1087.5 1134.8 1135.8 1201.6 1215.6 24717.5 28330.0 28861.3 28871.6 29079.6 39880.4 40440.6 40449.8 40500.2 40510.4 299573.0 300060.0 300098.0 300583.0 302523.0 21304.1 23433.9 24287.1 24397.3 24521.0 39871.6 39903.4 40010.4 40022.6 40890.0 32237.7 33598.3 34157.7 34251.8 34966.0 33936.7 34402.1 34473.7 34522.8 34595.1 33980.2 34222.8 34464.7 34738.7 34756.3 28671.7 30938.0 31156.8 31213.2 31286.7 23614.8 24287.8 24306.4 24331.4 24458.6 08:47:11 INFO - PROCESS | 7148 | 08:47:11 INFO - PROCESS | 7148 | [#2] jquery.html Cycles:180 Average:25784.68 Median:28698.15 stddev:71894.39 (250.5%) stddev-sans-first:72070.74 08:47:11 INFO - PROCESS | 7148 | Values: 366.2 366.6 369.5 385.1 392.7 15915.5 16691.2 17248.2 17321.8 17390.1 16111.8 16745.0 16878.0 16952.2 16981.9 5007.0 5269.1 5270.6 5272.1 5279.6 3847.2 4044.2 5201.6 5914.3 6181.6 6272.5 6276.8 6279.8 6281.2 6283.3 5952.9 6100.8 6117.8 6130.1 6134.3 5218.8 5373.1 5374.0 5374.5 5467.8 20976.7 21105.9 21223.0 21232.0 21405.9 1930.8 2172.9 2277.9 2305.9 2409.4 13256.1 13335.5 13356.5 13628.3 14070.1 1741.9 1766.5 1815.8 1841.2 1851.1 65460.0 67162.0 67987.0 68223.0 69186.3 9394.4 9428.0 9750.2 9752.8 9968.5 13671.3 13690.2 13709.7 13759.0 13767.0 7091.8 7268.7 7305.4 7465.7 7589.5 6628.3 6848.6 6963.6 6979.9 7121.5 6036.2 6152.7 6153.1 6296.1 6347.6 1657.3 1767.4 1783.0 1801.8 1818.2 693.7 832.8 851.9 895.3 981.8 16781.1 17081.9 17390.7 18166.7 19079.4 803.1 837.5 856.4 880.5 890.3 9064.7 10001.4 10009.2 10195.4 10200.3 434594.0 436736.0 437961.0 438288.0 439464.0 74995.6 76453.0 77978.0 78491.6 81810.6 840.8 922.1 933.2 936.6 947.6 15824.7 15907.6 16188.0 16450.8 16579.3 36403.5 37277.3 37952.6 38272.6 38363.0 47090.0 49862.8 50134.7 50162.7 52177.5 6930.4 7132.1 7170.9 7440.7 7569.1 9230.3 11195.0 11336.4 11349.6 11505.8 9894.5 9922.2 10168.7 10206.9 10357.0 9661.3 9783.7 9813.8 10249.4 10381.8 9159.2 9856.2 9969.0 10184.2 10450.9 6200.6 7175.2 7178.1 7234.1 7349.8 8149.7 9626.8 9985.8 10581.6 10618.4 08:47:11 INFO - PROCESS | 7148 | 08:47:11 INFO - PROCESS | 7148 | [#3] mootools.html Cycles:180 Average:4998.28 Median:5008.64 stddev:2822.75 (56.4%) stddev-sans-first:2816.62 08:47:11 INFO - PROCESS | 7148 | Values: 1249.9 1358.3 1395.4 1467.3 1701.1 6550.1 7131.9 7459.1 7470.1 7698.5 7515.5 7567.2 7567.4 7567.5 7569.5 2186.6 2214.5 2215.4 2215.7 2217.2 2145.4 2176.2 2198.5 2198.6 2201.0 2129.3 2145.9 2146.2 2147.5 2179.9 2169.8 2174.8 2175.5 2180.7 2182.0 2134.5 2177.0 2186.1 2187.6 2188.4 7404.3 7441.0 7457.6 7553.1 7553.3 4500.5 4657.1 4662.3 4662.9 4669.4 2792.9 2904.7 2928.8 2954.8 2974.6 947.8 965.0 968.5 974.2 980.7 10726.6 10765.6 10774.8 10780.2 10805.2 3978.3 4125.7 4184.2 4337.5 4349.2 7947.6 8050.6 8067.4 8075.9 8076.0 4511.0 4511.8 4514.7 4515.4 4515.5 3473.6 3610.7 3724.6 4174.7 4175.7 3502.6 3574.7 3617.0 3644.8 3646.5 1081.6 1093.2 1105.1 1107.0 1118.9 727.4 736.6 737.0 751.7 768.9 4974.6 5060.6 5128.9 5137.7 5154.9 397.5 404.0 415.5 417.4 419.1 3445.3 3516.5 3533.1 3546.2 3564.1 9168.2 9587.4 9614.0 9638.5 9651.4 9352.9 9568.1 9634.1 9653.0 9670.7 7310.3 7389.2 7389.6 7396.0 7404.2 5042.7 5069.6 5102.7 5145.6 5370.9 8784.2 8819.3 8859.6 8883.9 8952.2 7799.1 8762.9 9052.8 9152.9 9155.6 5143.8 5928.9 6028.4 6031.2 6048.7 7404.1 7460.7 7462.6 7471.1 7472.3 6589.2 6722.9 6770.8 6797.5 6845.4 6452.6 6466.4 6510.3 6510.9 6579.2 6000.0 6057.4 6121.5 6121.8 6123.1 4375.3 4422.6 4455.7 4472.9 4476.2 4465.2 4558.4 4636.0 4668.7 4860.1 08:47:11 INFO - PROCESS | 7148 | 08:47:11 INFO - PROCESS | 7148 | [#4] prototype.html Cycles:180 Average:4114.29 Median:4757.08 stddev:1193.66 (25.1%) stddev-sans-first:1162.16 08:47:11 INFO - PROCESS | 7148 | Values: 299.5 305.5 306.3 308.8 309.1 4721.8 4783.5 4787.8 4792.7 4811.0 4728.2 4740.7 4757.0 4757.2 4760.2 3534.8 3628.6 3654.0 3688.5 3725.6 3723.9 3724.2 3740.3 3753.8 3760.1 3698.7 3702.6 3719.1 3731.7 3744.3 3523.5 3607.7 3728.4 3750.8 3758.4 3315.7 3324.7 3327.3 3335.6 3338.3 4588.1 4591.0 4603.5 4603.6 4604.9 4111.2 4225.9 4260.6 4267.4 4275.0 4856.3 4868.5 4873.2 4893.5 4901.9 3586.9 3601.9 3603.5 3622.1 3635.1 5041.3 5077.6 5120.7 5157.7 5160.3 3334.3 3541.0 3556.2 3556.9 3571.7 3598.6 3604.9 3606.3 3610.8 3613.6 4101.6 4137.9 4154.3 4161.8 4163.9 4728.4 4911.3 4949.7 4964.8 4968.1 1229.7 1257.3 1258.1 1259.7 1261.6 801.3 807.7 822.8 824.8 826.2 4311.8 4426.4 4430.5 4432.4 4445.5 5021.7 5063.7 5073.2 5085.3 5095.0 4405.0 4408.1 4411.1 4423.5 4423.6 2526.2 2678.1 2688.0 2689.0 2700.2 4833.2 4842.0 4855.3 4855.5 4882.2 5026.0 5182.6 5187.0 5207.6 5231.6 4808.4 4811.2 4814.9 4834.5 4871.9 5005.9 5019.3 5032.3 5040.0 5077.9 4774.4 4944.9 4978.6 5045.0 5054.3 4831.2 4836.4 4887.4 4887.4 4894.0 3617.3 3617.5 3624.1 3631.8 3641.2 5105.9 5123.2 5130.6 5138.5 5144.4 4673.9 4920.6 4957.3 4960.7 4980.9 4945.0 4959.3 4970.6 4975.3 4990.7 4879.4 4922.6 4932.3 4938.2 4949.4 4767.9 4842.1 4906.7 4948.1 4951.5 4810.8 4813.6 4825.2 4827.3 4831.5 08:47:11 INFO - PROCESS | 7148 | 08:47:11 INFO - PROCESS | 7148 | [#5] yui.html Cycles:180 Average:16600.49 Median:511.35 stddev:23725.55 (4639.8%) stddev-sans-first:23761.69 08:47:11 INFO - PROCESS | 7148 | Values: 600.2 633.0 682.0 689.8 691.7 11826.1 13035.5 13136.7 13203.6 13214.7 13495.7 13659.2 13665.5 13679.7 13944.9 752.0 770.2 772.8 774.6 777.5 628.8 636.7 750.4 771.7 772.8 812.9 813.2 814.8 816.0 817.8 767.6 808.9 808.9 809.5 810.0 480.4 572.6 580.0 585.6 594.8 12715.6 12735.0 12736.7 12741.7 12752.7 12056.3 12541.7 12785.4 12868.6 13513.8 9345.3 9633.3 11256.4 11322.8 11422.1 4003.4 4048.4 4063.1 4088.6 4180.6 59012.7 59537.4 59677.4 59768.7 60534.7 9368.9 9605.6 9667.3 9791.1 10017.5 17354.0 17504.6 18123.7 18177.4 18984.9 6205.1 6338.3 6366.9 6390.4 6617.1 3642.5 3661.5 3825.9 3829.4 3878.3 2825.6 2954.2 2955.9 2957.8 2963.6 526.2 543.3 546.5 552.2 564.9 496.5 556.7 575.9 592.0 609.5 8037.7 8414.6 8628.4 8845.0 9018.9 567.4 570.1 591.5 603.1 611.1 3665.4 3931.8 4020.9 4038.1 4242.8 102972.5 103545.0 104829.0 105074.8 106020.0 67369.7 69358.7 70414.6 70457.0 83450.6 42904.4 59191.4 61581.7 67384.7 70649.6 7671.7 8042.4 8246.0 8357.6 8363.7 29694.4 30833.8 31392.8 37262.6 41364.2 54645.7 55575.4 56178.0 56224.4 56937.4 10005.6 10340.3 10482.7 10622.7 11131.1 11234.2 11438.1 12097.3 13390.3 13496.7 10844.8 10962.1 11685.5 11699.1 11710.0 10846.5 11227.8 11357.8 12050.7 12051.8 11081.9 11134.5 11234.7 11706.0 12088.8 9348.4 9872.0 10047.2 10128.8 10271.9 8971.4 9084.5 9299.3 9942.3 10047.3 08:47:11 INFO - PROCESS | 7148 | -------- Summary: end -------- 08:47:11 INFO - PROCESS | 7148 | 08:47:12 INFO - TEST-INFO | 7148: exit 0 08:47:12 INFO - TEST-OK | dromaeo_css | took 1120315ms 08:47:12 INFO - TEST-START | kraken 08:47:12 INFO - operating with platform_type : linux_ 08:47:12 INFO - Initialising browser for kraken test... 08:47:12 INFO - TEST-INFO | started process 7458 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0b4fow/profile http://localhost:39379/getInfo.html) 08:47:16 INFO - PROCESS | 7458 | __metrics Screen width/height:1600/1200 08:47:16 INFO - PROCESS | 7458 | colorDepth:24 08:47:16 INFO - PROCESS | 7458 | Browser inner width/height: 1024/697 08:47:16 INFO - PROCESS | 7458 | __metrics 08:47:17 INFO - PROCESS | 7458 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:47:17 INFO - PROCESS | 7458 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:47:17 INFO - PROCESS | 7458 | console.error: PushService: 08:47:17 INFO - PROCESS | 7458 | stateChangeProcessEnqueue: Error transitioning state 08:47:17 INFO - PROCESS | 7458 | UnknownError 08:47:18 INFO - TEST-INFO | 7458: exit 0 08:47:18 INFO - Browser initialized. 08:47:18 INFO - Running cycle 1/1 for kraken test... 08:47:18 INFO - TEST-INFO | started process 7540 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0b4fow/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:30 INFO - PROCESS | 7540 | RSS: Main: 175247360 08:47:30 INFO - PROCESS | 7540 | 08:47:33 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39379/tests/kraken/driver.html?testName=audio-beat-detection) 08:47:34 INFO - PROCESS | 7540 | RSS: Main: 243961856 08:47:34 INFO - PROCESS | 7540 | 08:47:37 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39379/tests/kraken/driver.html?testName=audio-dft) 08:47:37 INFO - PROCESS | 7540 | RSS: Main: 226689024 08:47:37 INFO - PROCESS | 7540 | 08:47:41 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39379/tests/kraken/driver.html?testName=audio-fft) 08:47:41 INFO - PROCESS | 7540 | RSS: Main: 301506560 08:47:41 INFO - PROCESS | 7540 | 08:47:43 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39379/tests/kraken/driver.html?testName=audio-oscillator) 08:47:44 INFO - PROCESS | 7540 | RSS: Main: 210243584 08:47:44 INFO - PROCESS | 7540 | 08:47:46 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39379/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:47:47 INFO - PROCESS | 7540 | RSS: Main: 214536192 08:47:47 INFO - PROCESS | 7540 | 08:47:50 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39379/tests/kraken/driver.html?testName=imaging-darkroom) 08:47:51 INFO - PROCESS | 7540 | RSS: Main: 250335232 08:47:51 INFO - PROCESS | 7540 | 08:47:54 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39379/tests/kraken/driver.html?testName=imaging-desaturate) 08:47:55 INFO - PROCESS | 7540 | RSS: Main: 244707328 08:47:55 INFO - PROCESS | 7540 | 08:47:58 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39379/tests/kraken/driver.html?testName=json-parse-financial) 08:47:58 INFO - PROCESS | 7540 | RSS: Main: 246894592 08:47:58 INFO - PROCESS | 7540 | 08:48:00 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39379/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:48:01 INFO - PROCESS | 7540 | RSS: Main: 211333120 08:48:01 INFO - PROCESS | 7540 | 08:48:03 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39379/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:48:03 INFO - PROCESS | 7540 | RSS: Main: 217206784 08:48:03 INFO - PROCESS | 7540 | 08:48:05 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39379/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:48:06 INFO - PROCESS | 7540 | RSS: Main: 223125504 08:48:06 INFO - PROCESS | 7540 | 08:48:09 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39379/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:48:10 INFO - PROCESS | 7540 | RSS: Main: 218755072 08:48:10 INFO - PROCESS | 7540 | 08:48:13 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39379/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:48:13 INFO - PROCESS | 7540 | RSS: Main: 220585984 08:48:13 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | Cycle 1(1): loaded http://localhost:39379/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39379/tests/kraken/driver.html?testName=ai-astar) 08:48:15 INFO - PROCESS | 7540 | RSS: Main: 272838656 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | __start_tp_report 08:48:15 INFO - PROCESS | 7540 | _x_x_mozilla_page_load 08:48:15 INFO - PROCESS | 7540 | _x_x_mozilla_page_load_details 08:48:15 INFO - PROCESS | 7540 | |i|pagename|runs| 08:48:15 INFO - PROCESS | 7540 | |0;ai-astar;101;113;111;115;115;115;120;116;115;113 08:48:15 INFO - PROCESS | 7540 | |1;audio-beat-detection;152;124;156;185;123;153;163;125;179;120 08:48:15 INFO - PROCESS | 7540 | |2;audio-dft;161;161;161;160;161;161;161;162;161;162 08:48:15 INFO - PROCESS | 7540 | |3;audio-fft;76;76;76;169;77;77;77;115;111;77 08:48:15 INFO - PROCESS | 7540 | |4;audio-oscillator;85;85;150;87;86;87;134;85;85;137 08:48:15 INFO - PROCESS | 7540 | |5;imaging-gaussian-blur;118;118;118;118;118;118;118;118;117;117 08:48:15 INFO - PROCESS | 7540 | |6;imaging-darkroom;146;147;146;146;146;147;146;146;147;147 08:48:15 INFO - PROCESS | 7540 | |7;imaging-desaturate;95;95;94;94;95;95;95;95;94;95 08:48:15 INFO - PROCESS | 7540 | |8;json-parse-financial;65;64;67;66;66;67;68;66;66;67 08:48:15 INFO - PROCESS | 7540 | |9;json-stringify-tinderbox;68;66;66;66;68;66;66;66;66;67 08:48:15 INFO - PROCESS | 7540 | |10;stanford-crypto-aes;92;85;83;81;86;83;83;86;82;83 08:48:15 INFO - PROCESS | 7540 | |11;stanford-crypto-ccm;145;134;131;141;139;131;136;133;160;135 08:48:15 INFO - PROCESS | 7540 | |12;stanford-crypto-pbkdf2;174;172;171;179;172;174;179;179;176;179 08:48:15 INFO - PROCESS | 7540 | |13;stanford-crypto-sha256-iterative;72;68;70;69;73;69;68;70;71;68 08:48:15 INFO - PROCESS | 7540 | __end_tp_report 08:48:15 INFO - PROCESS | 7540 | __start_cc_report 08:48:15 INFO - PROCESS | 7540 | _x_x_mozilla_cycle_collect,1993 08:48:15 INFO - PROCESS | 7540 | __end_cc_report 08:48:15 INFO - PROCESS | 7540 | __startTimestamp1471880895771__endTimestamp 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | ------- Summary: start ------- 08:48:15 INFO - PROCESS | 7540 | Number of tests: 14 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#0] ai-astar Cycles:10 Average:113.40 Median:115.00 stddev:4.95 (4.3%) stddev-sans-first:2.49 08:48:15 INFO - PROCESS | 7540 | Values: 101.0 113.0 111.0 115.0 115.0 115.0 120.0 116.0 115.0 113.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#1] audio-beat-detection Cycles:10 Average:148.00 Median:154.50 stddev:23.98 (15.5%) stddev-sans-first:25.39 08:48:15 INFO - PROCESS | 7540 | Values: 152.0 124.0 156.0 185.0 123.0 153.0 163.0 125.0 179.0 120.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 08:48:15 INFO - PROCESS | 7540 | Values: 161.0 161.0 161.0 160.0 161.0 161.0 161.0 162.0 161.0 162.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#3] audio-fft Cycles:10 Average:93.10 Median:76.50 stddev:30.69 (40.1%) stddev-sans-first:31.92 08:48:15 INFO - PROCESS | 7540 | Values: 76.0 76.0 76.0 169.0 77.0 77.0 77.0 115.0 111.0 77.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#4] audio-oscillator Cycles:10 Average:102.10 Median:85.00 stddev:26.70 (31.4%) stddev-sans-first:27.59 08:48:15 INFO - PROCESS | 7540 | Values: 85.0 85.0 150.0 87.0 86.0 87.0 134.0 85.0 85.0 137.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:48:15 INFO - PROCESS | 7540 | Values: 118.0 118.0 118.0 118.0 118.0 118.0 118.0 118.0 117.0 117.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 08:48:15 INFO - PROCESS | 7540 | Values: 146.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 147.0 147.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 08:48:15 INFO - PROCESS | 7540 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:66.50 stddev:1.14 (1.7%) stddev-sans-first:1.12 08:48:15 INFO - PROCESS | 7540 | Values: 65.0 64.0 67.0 66.0 66.0 67.0 68.0 66.0 66.0 67.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:66.00 stddev:0.85 (1.3%) stddev-sans-first:0.71 08:48:15 INFO - PROCESS | 7540 | Values: 68.0 66.0 66.0 66.0 68.0 66.0 66.0 66.0 66.0 67.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.00 stddev:3.13 (3.7%) stddev-sans-first:1.74 08:48:15 INFO - PROCESS | 7540 | Values: 92.0 85.0 83.0 81.0 86.0 83.0 83.0 86.0 82.0 83.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:137.50 stddev:8.77 (6.4%) stddev-sans-first:8.98 08:48:15 INFO - PROCESS | 7540 | Values: 145.0 134.0 131.0 141.0 139.0 131.0 136.0 133.0 160.0 135.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.50 Median:177.50 stddev:3.31 (1.9%) stddev-sans-first:3.46 08:48:15 INFO - PROCESS | 7540 | Values: 174.0 172.0 171.0 179.0 172.0 174.0 179.0 179.0 176.0 179.0 08:48:15 INFO - PROCESS | 7540 | 08:48:15 INFO - PROCESS | 7540 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:70.00 stddev:1.75 (2.5%) stddev-sans-first:1.67 08:48:15 INFO - PROCESS | 7540 | Values: 72.0 68.0 70.0 69.0 73.0 69.0 68.0 70.0 71.0 68.0 08:48:15 INFO - PROCESS | 7540 | -------- Summary: end -------- 08:48:15 INFO - PROCESS | 7540 | 08:48:17 INFO - TEST-INFO | 7540: exit 0 08:48:17 INFO - TEST-OK | kraken | took 64465ms 08:48:17 INFO - SUITE-END | took 1184s 08:48:17 INFO - Completed test suite (00:19:45) 08:48:17 INFO - javascript benchmark 08:48:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2548.2610043087507, 2606.6741657292837, 2609.321576390138, 2616.2151354593616, 2629.513539995101, 4520.751358675271, 4580.244259697154, 4654.860354189375, 4720.055988802236, 4737.336772851803, 4429.889252768674, 4434.091011342675, 4443.24464840977, 4574.83988060418, 4662.9533704662945, 4031.4355990161403, 4075.510938687362, 4082.632563069323, 4112.115895082557, 4173.770442625654, 4082.795860206978, 4152.854650087247, 4165.604267594589, 4175.728577642459, 4181.790910454481, 4111.506619205685, 4185.686073544466, 4187.497500299953, 4214.325707886724, 4250.659947204216, 4261.829526818923, 4265.4881414230495, 4267.786610669454, 4269.060806622538, 4272.166927449149, 4221.5567365426805, 4232.957670423318, 4238.470191226097, 4242.321228603409, 4247.299195632748, 4421.823127074913, 4550.203714349976, 4585.495595484527, 4598.310253461974, 4643.048175124091, 4900.730459824708, 4937.456879743226, 4941.876453088702, 4944.728039957801, 4966.701997880139, 4991.6256280778725, 5017.799288028475, 5025.497450254945, 5025.597952163818, 5028.823991160329, 4475.30632751924, 4589.151007063704, 4601.8849528761375, 4609.792559334838, 4676.883077923079, 5084.872878178001, 5108.591312694975, 5113.0285245803925, 5120.078385890501, 5121.334226550524, 4889.926651100236, 4899.167141585939, 4910.926336104962, 4914.483979182206, 4919.434265059493, 4811.542903424169, 4855.368802055781, 4892.755362231874, 4902.681325713817, 4983.0, 4922.75386230687, 4932.654714169974, 4934.703917764946, 4936.506349365035, 4939.777710002986, 4923.261510773341, 4931.728754918442, 4937.629677774181, 4938.728369939688, 4946.975265123651, 4131.400946862688, 4167.499900011957, 4170.52039015511, 4205.873823785291, 4300.634446072057, 3621.764585301947, 3658.926821463609, 3683.3922402803237, 3731.738778285494, 3794.6205379461835, 4305.97847010763, 4307.935380969288, 4316.0, 4317.740106219847, 4322.546132656089, 4947.035328111056, 4983.426905905795, 4984.27727979441, 4996.3504744383, 4998.900021999612, 4177.248095342868, 4257.74453532789, 4269.722468039629, 4283.293256612624, 4345.847895323712, 4507.143642707875, 4540.8637740867825, 4550.112728018071, 4551.840685575989, 4558.680892337505, 4749.240121580599, 4755.429348478136, 4773.164696178224, 4774.164521208774, 4775.42694876617, 4784.593309568587, 4818.10864989978, 4862.6839255448285, 4874.658773885794, 4939.53074457926, 4665.976670116627, 4673.626109911267, 4681.204195286878, 4690.1792186366565, 4710.411198600175, 4861.805527778849, 4868.099401610713, 4870.4155501340065, 4872.561469467765, 4876.609871210366, 4807.471178170328, 4860.3195552623365, 4888.633352498506, 4903.092927808367, 4974.726390048582, 4821.831235906727, 4852.927206091841, 4855.441624213119, 4857.9028419432125, 4857.9514204858915, 4835.709857408567, 4851.417829860298, 4852.102361063215, 4852.126617208936, 4857.247126695368, 4619.745913974763, 4706.8117275308605, 4720.079584480994, 4751.049790041936, 4765.833195838129, 4840.685355451885, 4866.1484240257105, 4871.318015477907, 4878.829240976549, 4883.023395321021, 4737.857864264078, 4743.810247590058, 4746.596539294199, 4764.071006153906, 4773.164696178085], "value": 4534.900000857612, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.32480860563, 561.9241402410674, 582.5688990147293, 586.7506309818326, 587.553459370878, 37075.81462092689, 38357.80821095891, 38412.46988012038, 42271.365929511085, 42294.0, 38992.0, 39211.607883921155, 39484.60515394853, 39616.60383396165, 39666.206675866466, 7570.735024274138, 7710.267524585155, 7712.961435192816, 7717.189695081992, 7717.768466946, 7617.162112167657, 7700.306972372486, 7700.730474433396, 7705.075390953093, 7707.07515098189, 7701.576413297266, 7734.226577342277, 7740.419468539854, 7743.5353878767455, 7752.224777522231, 7718.961405192967, 7749.806254843617, 7750.534967901944, 7755.689772409097, 7773.766786996399, 7482.792576190037, 7540.132884718273, 7541.6983320667105, 7543.660535275871, 7548.358389536896, 36657.63342366569, 36995.0, 37015.074623134366, 37051.25897482062, 37133.62866371329, 2971.658259300165, 3132.8276944768027, 3393.2874096439778, 3440.7419443541835, 3476.8087755173456, 28555.57166642502, 28592.857035714896, 28777.712222877923, 29444.8527757362, 30039.0, 3303.4803990164555, 3316.2372654289647, 3318.6183588887347, 3346.6987971082476, 3347.748918831073, 239231.0, 239338.0, 239804.0, 240443.0, 241335.0, 27385.31536711558, 28047.71952280492, 44399.778001109786, 44526.55473445289, 44627.0, 32006.67993320037, 32410.83794581012, 32451.675483245337, 32475.18812029718, 32483.0, 12607.747845043048, 12726.045546584044, 12759.106862519535, 12764.936175319064, 12772.297523636289, 10305.175585953106, 10325.0, 10329.690109296733, 10331.741706457398, 10337.276390652733, 7704.229577042251, 7722.266384693445, 7736.574488403079, 7738.7678369649, 7803.180666030099, 3069.3554353585996, 3080.383923215366, 3096.814191148493, 3143.654198038214, 3143.8899638512544, 873.4540911930044, 914.3279689428265, 959.2757468111531, 969.6170012844887, 972.2999440402899, 25429.491410172064, 26991.86504067467, 27348.0, 27617.585736213972, 27653.723462765516, 942.9151376376159, 958.4872093430121, 1001.9849702254472, 1022.8005538919988, 1023.22746326523, 13888.583342499742, 14679.633009174857, 14736.26318684083, 14836.184009879344, 15025.474108406153, 391286.0, 394051.0, 394616.0, 394948.0, 394974.0, 47157.292640610416, 47642.0, 47698.0, 47701.807454813905, 47709.0, 1087.4508373271447, 1134.8411222428701, 1135.795556799784, 1201.627495476405, 1215.5927764199093, 24717.50564988654, 28330.0, 28861.278468037628, 28871.56692649654, 29079.56380654334, 39880.40179397253, 40440.59559404486, 40449.79775101106, 40500.1899962005, 40510.392344115455, 299573.0, 300060.0, 300098.0, 300583.0, 302523.0, 21304.147834086463, 23433.88283058574, 24287.149949751674, 24397.26808195757, 24521.0, 39871.60128398795, 39903.40144897887, 40010.399844002946, 40022.59977400189, 40890.0, 32237.722716320906, 33598.328033439684, 34157.65842341545, 34251.828740857134, 34966.0, 33936.66063339335, 34402.13994650054, 34473.65526344805, 34522.82738586291, 34595.13512162216, 33980.150496237795, 34222.828885856405, 34464.65535344614, 34738.65261347455, 34756.30487390289, 28671.71328286774, 30938.0, 31156.844215778776, 31213.219669508442, 31286.687133128376, 23614.763852361943, 24287.842669845333, 24306.392340191636, 24331.39171520726, 24458.633120503564], "value": 15950.330531292291, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.21812430460966, 366.6297039989611, 369.5417682074229, 385.0604524959101, 392.74864086984337, 15915.522534323974, 16691.248893799777, 17248.223829927636, 17321.82678173218, 17390.130493475306, 16111.83888161118, 16745.0, 16878.0, 16952.152392380394, 16981.91509042456, 5006.97496512517, 5269.051570717269, 5270.631055826094, 5272.051030814452, 5279.604029697769, 3847.153626202231, 4044.170944956292, 5201.635885488017, 5914.260717410324, 6181.59819611726, 6272.466840318579, 6276.807406592756, 6279.811605651815, 6281.183446151994, 6283.3088360280335, 5952.851178720545, 6100.755969761204, 6117.816465506042, 6130.080487926802, 6134.294556126036, 5218.817341393071, 5373.113436283009, 5373.973130134364, 5374.462553744634, 5467.781288748445, 20976.685349719766, 21105.894470527703, 21223.0, 21232.0, 21405.892970535202, 1930.806919308072, 2172.902219400115, 2277.906604829691, 2305.8616483010906, 2409.4096946248283, 13256.138351007154, 13335.533256335983, 13356.465741370333, 13628.318584070757, 14070.085444446078, 1741.8954862708279, 1766.4612293250555, 1815.7911840138422, 1841.235887106849, 1851.0652120679106, 65460.0, 67162.0, 67987.0, 68223.0, 69186.30813691899, 9394.389364691273, 9427.952860235791, 9750.219982401391, 9752.756181095387, 9968.501574921225, 13671.316434178252, 13690.247036413093, 13709.725805483835, 13759.036867419385, 13767.0, 7091.82270443243, 7268.6729097190755, 7305.3790427813165, 7465.70137194517, 7589.468737188344, 6628.304028076983, 6848.554843935129, 6963.617001064888, 6979.89530157048, 7121.537100088478, 6036.215292012011, 6152.692740222416, 6153.139020174012, 6296.118543403927, 6347.55567110307, 1657.3453485873254, 1767.4167524716813, 1782.97325540117, 1801.8378345948665, 1818.1908751724875, 693.7398475571661, 832.76682528892, 851.9190676885686, 895.2524641923936, 981.764376549623, 16781.147204975183, 17081.914590427215, 17390.65218695619, 18166.727499087523, 19079.427617171506, 803.1326167738828, 837.4556538250064, 856.4304737349593, 880.4541184465672, 890.3233542507611, 9064.728058158265, 10001.449920254456, 10009.249306302056, 10195.387683005796, 10200.3369780964, 434594.0, 436736.0, 437961.0, 438288.0, 439464.0, 74995.62502187454, 76453.0, 77978.0, 78491.60754196307, 81810.59094704608, 840.8318336332758, 922.1424075609614, 933.2114363362947, 936.6159874451442, 947.5783276441917, 15824.683506330039, 15907.602309942344, 16188.0, 16450.75323870119, 16579.253933572778, 36403.45394819133, 37277.25445491129, 37952.620473796014, 38272.61727382691, 38363.040923977125, 47090.0, 49862.750686246334, 50134.7493262546, 50162.74918625529, 52177.47822521726, 6930.445564354739, 7132.108486439195, 7170.856582868418, 7440.665170067252, 7569.091708994956, 9230.307726920373, 11195.0, 11336.433178340885, 11349.602763903224, 11505.80384792123, 9894.455804930798, 9922.156616687374, 10168.694939151837, 10206.8514592537, 10357.0, 9661.323707340418, 9783.657571984946, 9813.754656133367, 10249.38503689781, 10381.792364152825, 9159.175674189355, 9856.162226210852, 9968.950155249177, 10184.185265178916, 10450.895491044992, 6200.56596038273, 7175.2465991070185, 7178.113450901687, 7234.095738032746, 7349.816254593464, 8149.674013039649, 9626.759331016781, 9985.800283994135, 10581.576736930436, 10618.415987120474], "value": 8433.621771697013, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1249.9375031248435, 1358.2868993778266, 1395.3930040432413, 1467.3176972707695, 1701.1154199816087, 6550.115734375864, 7131.893021604674, 7459.104907411104, 7470.140933792609, 7698.461107722448, 7515.473916825825, 7567.167611562723, 7567.394608431326, 7567.508111972732, 7569.545827250355, 2186.5736181444663, 2214.457457922811, 2215.3575463115676, 2215.7119574455296, 2217.168561789329, 2145.420736401171, 2176.1513009926143, 2198.4503874031493, 2198.604251234777, 2200.9889950550223, 2129.265403435812, 2145.871247725134, 2146.2488129154826, 2147.473868902121, 2179.923702670407, 2169.7504786949467, 2174.7716489768663, 2175.510510135223, 2180.661997390407, 2182.0, 2134.4770531219797, 2176.967345489819, 2186.0709198590694, 2187.562487502506, 2188.4310079379316, 7404.296591823822, 7440.962795186044, 7457.552546847207, 7553.1313898901235, 7553.32020118192, 4500.482444518857, 4657.068586282757, 4662.347271382009, 4662.883427914329, 4669.369635099252, 2792.8743206555755, 2904.6804851466322, 2928.8096273742353, 2954.778391620637, 2974.6430428348735, 947.7630592351912, 964.9903500964971, 968.4625033106623, 974.2303580171656, 980.7057882635181, 10726.624568140078, 10765.62320318793, 10774.838377424345, 10780.245382823205, 10805.189610779224, 3978.3435733103715, 4125.690573206962, 4184.225918205142, 4337.503561271356, 4349.173657005159, 7947.642356093989, 8050.637721302555, 8067.435279530494, 8075.878861817077, 8076.0, 4511.007578332761, 4511.751853648081, 4514.68397212192, 4515.412996310459, 4515.503294637587, 3473.565804274466, 3610.7291953103095, 3724.6275372462537, 4174.707770456039, 4175.749455032707, 3502.5796904371637, 3574.660407261306, 3617.0, 3644.817759112034, 3646.5077214575695, 1081.5565618096543, 1093.2292733622935, 1105.0772604874858, 1106.9667909962714, 1118.9216754827246, 727.4071631620212, 736.5543845973109, 736.9852602948018, 751.7256201486418, 768.8616049111214, 4974.602031837444, 5060.569851562658, 5128.8717782055, 5137.6660517066275, 5154.871128221825, 397.52098721041074, 403.98788036358957, 415.49932331540424, 417.35935339254706, 419.06339331593864, 3445.3453843769603, 3516.4549494828343, 3533.1343820763564, 3546.2021045265124, 3564.091156755011, 9168.174864262248, 9587.424754514752, 9613.951930240304, 9638.518074096268, 9651.372660777028, 9352.906470935339, 9568.09103135201, 9634.084761947604, 9652.951735241419, 9670.661526846527, 7310.268973102754, 7389.224131466226, 7389.556626602421, 7396.0, 7404.222556631584, 5042.672226305279, 5069.619778516552, 5102.668326558762, 5145.639805213599, 5370.919436208538, 8784.165504277084, 8819.338549608678, 8859.64561417536, 8883.911160888309, 8952.194302512806, 7799.103103143211, 8762.868556971524, 9052.81894362096, 9152.862707059532, 9155.633774648939, 5143.8456846294675, 5928.9407105928385, 6028.3971602839365, 6031.215941927521, 6048.727807248596, 7404.111506619241, 7460.738874139379, 7462.589557574169, 7471.066116735408, 7472.29013243741, 6589.24223714279, 6722.932770672426, 6770.796876093725, 6797.524173307821, 6845.418139458202, 6452.645104519296, 6466.418022378009, 6510.348965103642, 6510.902336464862, 6579.17760279965, 6000.0, 6057.363976782374, 6121.510279177566, 6121.816345509464, 6123.14276001369, 4375.321825117112, 4422.601965823092, 4455.665825063068, 4472.932906006347, 4476.239039363251, 4465.1962646723905, 4558.407407037196, 4636.0, 4668.743219122845, 4860.149473842134], "value": 3961.477726677565, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.5282430172478, 305.45323870272216, 306.28482493377976, 308.75454014058823, 309.1205520294762, 4721.78751956162, 4783.545563171498, 4787.8324258650955, 4792.688475249106, 4810.975945120278, 4728.1962066448705, 4740.69185502942, 4757.0, 4757.167495688258, 4760.214919405223, 3534.8055856927795, 3628.5827129880136, 3654.0, 3688.5389326334207, 3725.5715592706915, 3723.90144907253, 3724.2365315110337, 3740.2519496100754, 3753.8123093845334, 3760.135168911152, 3698.685611723007, 3702.592714801383, 3719.0516418313427, 3731.738778285521, 3744.2698673758628, 3523.453864650983, 3607.6753092221825, 3728.4034554471154, 3750.79370634615, 3758.39865621502, 3315.7015868571702, 3324.6675332466803, 3327.2846338037198, 3335.599048399678, 3338.3490219407227, 4588.0959401848195, 4590.954090459086, 4603.49361570227, 4603.608693261077, 4604.884877878047, 4111.157212771374, 4225.894352641178, 4260.616544510978, 4267.381229721673, 4274.952636604052, 4856.271559266103, 4868.53748893858, 4873.171560834631, 4893.486183950669, 4901.8774530636665, 3586.946195807065, 3601.8654123950873, 3603.5315408996926, 3622.1487950331652, 3635.127569383329, 5041.344625198737, 5077.64456488046, 5120.692758434506, 5157.716325602128, 5160.277561141481, 3334.2664613785414, 3540.964590354115, 3556.2396613676397, 3556.87550935716, 3571.714262858965, 3598.604153543108, 3604.945925811115, 3606.2787442511603, 3610.7613279766188, 3613.6205698401814, 4101.630853223224, 4137.937930931039, 4154.335306350969, 4161.8335266589, 4163.91672166555, 4728.38530990969, 4911.336969509142, 4949.678270912379, 4964.801407943714, 4968.130577148984, 1229.7110179107904, 1257.3398965543163, 1258.1381753498883, 1259.6850787303174, 1261.5521489871146, 801.2527601725066, 807.6648191000786, 822.761399194227, 824.7814329202766, 826.1903334731996, 4311.805968731352, 4426.3581780641625, 4430.468343798765, 4432.360026790044, 4445.488768791631, 5021.673591216632, 5063.746812659426, 5073.213651883964, 5085.288059671649, 5094.974525127424, 4404.955950440519, 4408.140412619508, 4411.117776444724, 4423.469183697976, 4423.60187583119, 2526.1685278173545, 2678.1429942418627, 2687.965133423607, 2688.9596656050176, 2700.189943017087, 4833.178359678863, 4842.031593681278, 4855.32025516428, 4855.5387238212315, 4882.194437917775, 5025.974870125626, 5182.6113041521285, 5187.0, 5207.583393328525, 5231.607629427808, 4808.447028591687, 4811.230203167476, 4814.903701926012, 4834.540718631724, 4871.926921096187, 5005.899882002413, 5019.272205530105, 5032.320636714143, 5040.0, 5077.92383114261, 4774.379330686988, 4944.8763780905765, 4978.551930326288, 5045.0, 5054.256281582848, 4831.154547954024, 4836.403986684456, 4887.383336331019, 4887.43794463627, 4893.951060489349, 3617.258462015228, 3617.5297211362354, 3624.0939765058733, 3631.763935344194, 3641.217138315275, 5105.897882042413, 5123.180291153547, 5130.58955283569, 5138.511841375063, 5144.356955380577, 4673.9298910517045, 4920.573033820152, 4957.281194226889, 4960.74286199771, 4980.850574482772, 4945.032980211844, 4959.256111583291, 4970.552650261494, 4975.303457515878, 4990.700557966534, 4879.39007624047, 4922.630802689741, 4932.28481870134, 4938.185199442196, 4949.381327334083, 4767.880802979953, 4842.104210721028, 4906.681065730716, 4948.134076536519, 4951.539295907788, 4810.783514741915, 4813.638977076664, 4825.203841366276, 4827.348307978378, 4831.492693267156], "value": 3735.688967463476, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.2436929468869, 632.977845775398, 681.9693113809876, 689.7930620813761, 691.730225212167, 11826.113041521889, 13035.478580856778, 13136.671583210424, 13203.603891883235, 13214.735705285888, 13495.73008539831, 13659.180449173056, 13665.521706740266, 13679.658008549766, 13944.93027534861, 752.0411475368921, 770.1912991359051, 772.8067983004249, 774.6436639146, 777.525709317316, 628.7830698409065, 636.748484348682, 750.4371721209093, 771.7106085218044, 772.818387678895, 812.9065157506903, 813.2030610002201, 814.8003739083932, 815.9796005099861, 817.8078151634361, 767.6046835879526, 808.9150639182918, 808.9352851771844, 809.5102463009853, 810.0, 480.4498848818084, 572.6249306704116, 579.9623024503394, 585.5988647776244, 594.7739858853652, 12715.618531444072, 12735.044871634664, 12736.681582960407, 12741.745165096647, 12752.681182970407, 12056.34760352721, 12541.749165016648, 12785.424655890507, 12868.613941581767, 13513.797293040514, 9345.345825792198, 9633.291182894636, 11256.437178141061, 11322.830157547643, 11422.143339249506, 4003.4195041718785, 4048.352263637804, 4063.065494936181, 4088.632023117904, 4180.561041090672, 59012.70493647504, 59537.40462595362, 59677.40322596762, 59768.701156494375, 60534.697326513524, 9368.90631093694, 9605.567749451291, 9667.27495437845, 9791.134395721254, 10017.545701303226, 17354.0, 17504.562385940455, 18123.728144077584, 18177.36379226747, 18984.905075474533, 6205.100260462298, 6338.271098823694, 6366.904496432558, 6390.424861762371, 6617.073609694647, 3642.5446819147605, 3661.469086982426, 3825.923481530354, 3829.4064420014943, 3878.282517734172, 2825.6467941507312, 2954.2023653613405, 2955.866985985636, 2957.807742496731, 2963.6147300850753, 526.2264471227289, 543.2910052381601, 546.4863028753039, 552.1524459953952, 564.9237352957298, 496.4786973677624, 556.6854727079187, 575.9078547432391, 591.9644821310735, 609.515435228994, 8037.718679846177, 8414.6213420395, 8628.39601227908, 8844.955775221082, 9018.909810901807, 567.3787203012695, 570.1191658887029, 591.5238233222211, 603.1194456094152, 611.135243630267, 3665.413533834627, 3931.81288694796, 4020.8994775130855, 4038.1116154445976, 4242.830286788494, 102972.48513757384, 103545.0, 104829.0, 105074.7578285083, 106020.0, 67369.66315168393, 69358.65320673466, 70414.64792676004, 70457.0, 83450.58274708709, 42904.356434653506, 59191.40808591859, 61581.692091539255, 67384.6630766843, 70649.64675176592, 7671.693132274647, 8042.396820238622, 8245.95877020635, 8357.582120894052, 8363.707270245512, 29694.406111877208, 30833.8458307716, 31392.843035784674, 37262.62737372592, 41364.1727165449, 54645.72677136589, 55575.444245557024, 56178.0, 56224.43775562192, 56937.43062569487, 10005.649802257178, 10340.327878687862, 10482.68551943443, 10622.73443163927, 11131.10951123892, 11234.191452136965, 11438.142139339416, 12097.334646594521, 13390.263535505252, 13496.662583435493, 10844.837327440253, 10962.068224200713, 11685.474153662935, 11699.122565807429, 11710.0, 10846.457677115955, 11227.831582526433, 11357.829632555684, 12050.698732531757, 12051.81922271149, 11081.944590276997, 11134.498947547218, 11234.719132021764, 11706.0, 12088.758224835277, 9348.392354496937, 9871.950640246752, 10047.246456515644, 10128.848067279145, 10271.897281027394, 8971.372003959661, 9084.454932704059, 9299.349045566745, 9942.30403871722, 10047.296689231684], "value": 6387.598406850369, "unit": "score"}], "name": "dromaeo_css", "value": 6215.761816184884, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 111.0, 115.0, 115.0, 115.0, 120.0, 116.0, 115.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 156.0, 185.0, 123.0, 153.0, 163.0, 125.0, 179.0, 120.0], "value": 148.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 161.0, 161.0, 161.0, 162.0, 161.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 169.0, 77.0, 77.0, 77.0, 115.0, 111.0, 77.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 150.0, 87.0, 86.0, 87.0, 134.0, 85.0, 85.0, 137.0], "value": 102.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 117.0, 117.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 67.0, 66.0, 66.0, 67.0, 68.0, 66.0, 66.0, 67.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 66.0, 68.0, 66.0, 66.0, 66.0, 66.0, 67.0], "value": 66.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 85.0, 83.0, 81.0, 86.0, 83.0, 83.0, 86.0, 82.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 134.0, 131.0, 141.0, 139.0, 131.0, 136.0, 133.0, 160.0, 135.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 172.0, 171.0, 179.0, 172.0, 174.0, 179.0, 179.0, 176.0, 179.0], "value": 175.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 70.0, 69.0, 73.0, 69.0, 68.0, 70.0, 71.0, 68.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1577.5, "alertThreshold": 2.0}]} 08:48:17 INFO - Return code: 0 08:48:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:48:17 INFO - # TBPL SUCCESS # 08:48:17 INFO - Running post-action listener: _resource_record_post_action 08:48:17 INFO - [mozharness: 2016-08-22 15:48:17.596043Z] Finished run-tests step (success) 08:48:17 INFO - Running post-run listener: _resource_record_post_run 08:48:17 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 414060544; Read time: 12; Write time: 302164 08:48:17 INFO - TinderboxPrint: CPU usage
13.2% 08:48:17 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:48:17 INFO - TinderboxPrint: I/O write bytes / time
414,060,544 / 302,164 08:48:17 INFO - TinderboxPrint: CPU idle
8,313.9 (86.7%) 08:48:17 INFO - TinderboxPrint: CPU user
1,203.9 (12.6%) 08:48:17 INFO - TinderboxPrint: Swap in / out
0 / 0 08:48:17 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 121450496; Read time: 0; Write time: 103612 08:48:17 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 292610048; Read time: 0; Write time: 198552 08:48:18 INFO - Running post-run listener: _upload_blobber_files 08:48:18 INFO - Blob upload gear active. 08:48:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:48:18 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 08:48:18 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'] 08:48:18 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 08:48:18 INFO - (blobuploader) - INFO - Open directory for files ... 08:48:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:48:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:48:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:22 INFO - (blobuploader) - INFO - Done attempting. 08:48:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:48:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:48:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:23 INFO - (blobuploader) - INFO - Done attempting. 08:48:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:48:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:48:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:23 INFO - (blobuploader) - INFO - Done attempting. 08:48:23 INFO - (blobuploader) - INFO - Iteration through files over. 08:48:23 INFO - Return code: 0 08:48:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:48:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:48:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/168a86686e3a4ded0bbb8274aa976be165d485f57f36e8e06b563c69db8b4af52cae4ee1d6d6f9d3747a44d75b4b56affb4414aba79aecd1782af7fd942ca288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/545c3119a17a01399f427501f71e8378538f15c7492f5f841cbacbe47b94bae81b0c4284a9121c9d9ad014fff3b247b3b536f309168ffab146a3e88594401246", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/05e635ba995556861b8f6958623ced356750466ea18b3b962ec7f85f2e563929268284b07f49daa54a0cd365108a66504b7ef21e9f1db3288d3b39416dcd80d1"} 08:48:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:48:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:48:23 INFO - Contents: 08:48:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/168a86686e3a4ded0bbb8274aa976be165d485f57f36e8e06b563c69db8b4af52cae4ee1d6d6f9d3747a44d75b4b56affb4414aba79aecd1782af7fd942ca288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/545c3119a17a01399f427501f71e8378538f15c7492f5f841cbacbe47b94bae81b0c4284a9121c9d9ad014fff3b247b3b536f309168ffab146a3e88594401246", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/05e635ba995556861b8f6958623ced356750466ea18b3b962ec7f85f2e563929268284b07f49daa54a0cd365108a66504b7ef21e9f1db3288d3b39416dcd80d1"} 08:48:23 INFO - Running post-run listener: copy_logs_to_upload_dir 08:48:23 INFO - Copying logs to upload dir... 08:48:23 INFO - mkdir: /builds/slave/test/build/upload/logs 08:48:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.913454 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-22 08:48:23.642661) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 08:48:23.646557) ========= 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=20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/168a86686e3a4ded0bbb8274aa976be165d485f57f36e8e06b563c69db8b4af52cae4ee1d6d6f9d3747a44d75b4b56affb4414aba79aecd1782af7fd942ca288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/545c3119a17a01399f427501f71e8378538f15c7492f5f841cbacbe47b94bae81b0c4284a9121c9d9ad014fff3b247b3b536f309168ffab146a3e88594401246", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/05e635ba995556861b8f6958623ced356750466ea18b3b962ec7f85f2e563929268284b07f49daa54a0cd365108a66504b7ef21e9f1db3288d3b39416dcd80d1"} build_url:https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011330 build_url: 'https://queue.taskcluster.net/v1/task/O3jLwylvS_-dPgnZfdSiNg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/168a86686e3a4ded0bbb8274aa976be165d485f57f36e8e06b563c69db8b4af52cae4ee1d6d6f9d3747a44d75b4b56affb4414aba79aecd1782af7fd942ca288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/545c3119a17a01399f427501f71e8378538f15c7492f5f841cbacbe47b94bae81b0c4284a9121c9d9ad014fff3b247b3b536f309168ffab146a3e88594401246", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/05e635ba995556861b8f6958623ced356750466ea18b3b962ec7f85f2e563929268284b07f49daa54a0cd365108a66504b7ef21e9f1db3288d3b39416dcd80d1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 08:48:23.672209) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:48:23.672552) ========= 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=20e666a3d4cfbb71505a330e000001b4-1471879657.460049-1269167776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004448 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:48:23.758476) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 08:48:23.758827) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 08:48:23.759170) ========= ========= Total master_lag: 0.18 =========