builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-042 starttime: 1472719723.11 results: success (0) buildid: 20160901002821 builduid: 3b062532be8f497480990402f1cab803 revision: 444b8a745f0433a467d41229261751ea5aa7f692 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:43.108352) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:43.108827) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:43.109227) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:43.125388) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:43.125657) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 01:48:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2016-09-01 01:48:43 (102 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.377667 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:43.516778) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:43.517087) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017197 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:43.566566) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:43.566897) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 444b8a745f0433a467d41229261751ea5aa7f692 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 444b8a745f0433a467d41229261751ea5aa7f692 --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=74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 01:48:43,823 truncating revision to first 12 chars 2016-09-01 01:48:43,823 Setting DEBUG logging. 2016-09-01 01:48:43,823 attempt 1/10 2016-09-01 01:48:43,823 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/444b8a745f04?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 01:48:44,141 unpacking tar archive at: autoland-444b8a745f04/testing/mozharness/ program finished with exit code 0 elapsedTime=0.661658 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:44.244375) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:44.245020) ========= script_repo_revision: 444b8a745f0433a467d41229261751ea5aa7f692 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:44.245739) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:44.246235) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 01:48:44.256754) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-09-01 01:48:44.257706) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:48:44 INFO - MultiFileLogger online at 20160901 01:48:44 in /builds/slave/test 01:48:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 01:48:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:48:44 INFO - {'append_to_log': False, 01:48:44 INFO - 'base_work_dir': '/builds/slave/test', 01:48:44 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 01:48:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:48:44 INFO - 'branch': 'Autoland-Non-PGO', 01:48:44 INFO - 'buildbot_json_path': 'buildprops.json', 01:48:44 INFO - 'config_files': ('talos/linux_config.py',), 01:48:44 INFO - 'default_actions': ('clobber', 01:48:44 INFO - 'read-buildbot-config', 01:48:44 INFO - 'download-and-extract', 01:48:44 INFO - 'populate-webroot', 01:48:44 INFO - 'create-virtualenv', 01:48:44 INFO - 'install', 01:48:44 INFO - 'run-tests'), 01:48:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:48:44 INFO - 'download_minidump_stackwalk': True, 01:48:44 INFO - 'download_symbols': 'ondemand', 01:48:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:48:44 INFO - 'tooltool.py': '/tools/tooltool.py', 01:48:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:48:44 INFO - '/tools/misc-python/virtualenv.py')}, 01:48:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:48:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:48:44 INFO - 'installer_path': 'installer.exe', 01:48:44 INFO - 'log_level': 'info', 01:48:44 INFO - 'log_name': 'talos', 01:48:44 INFO - 'log_to_console': True, 01:48:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:48:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:48:44 INFO - 'opt_config_files': (), 01:48:44 INFO - 'pip_index': False, 01:48:44 INFO - 'sps_profile': False, 01:48:44 INFO - 'sps_profile_interval': 0, 01:48:44 INFO - 'suite': 'dromaeojs', 01:48:44 INFO - 'system_bits': '32', 01:48:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:48:44 INFO - 'title': 'talos-linux64-ix-042', 01:48:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:48:44 INFO - 'use_talos_json': True, 01:48:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:48:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:48:44 INFO - 'work_dir': 'build'} 01:48:44 INFO - [mozharness: 2016-09-01 08:48:44.615128Z] Running clobber step. 01:48:44 INFO - Running pre-action listener: _resource_record_pre_action 01:48:44 INFO - Running main action method: clobber 01:48:44 INFO - rmtree: /builds/slave/test/build 01:48:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:48:45 INFO - Running post-action listener: _resource_record_post_action 01:48:45 INFO - [mozharness: 2016-09-01 08:48:45.078522Z] Finished clobber step (success) 01:48:45 INFO - [mozharness: 2016-09-01 08:48:45.078636Z] Running read-buildbot-config step. 01:48:45 INFO - Running pre-action listener: _resource_record_pre_action 01:48:45 INFO - Running main action method: read_buildbot_config 01:48:45 INFO - Using buildbot properties: 01:48:45 INFO - { 01:48:45 INFO - "project": "", 01:48:45 INFO - "product": "firefox", 01:48:45 INFO - "script_repo_revision": "production", 01:48:45 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 01:48:45 INFO - "repository": "", 01:48:45 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 01:48:45 INFO - "buildid": "20160901002821", 01:48:45 INFO - "pgo_build": "False", 01:48:45 INFO - "basedir": "/builds/slave/test", 01:48:45 INFO - "buildnumber": 385, 01:48:45 INFO - "slavename": "talos-linux64-ix-042", 01:48:45 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:48:45 INFO - "platform": "ubuntu64_hw", 01:48:45 INFO - "scheduler": "tests-autoland-linux64-talos", 01:48:45 INFO - "branch": "autoland", 01:48:45 INFO - "repo_path": "integration/autoland", 01:48:45 INFO - "revision": "444b8a745f0433a467d41229261751ea5aa7f692", 01:48:45 INFO - "stage_platform": "linux64", 01:48:45 INFO - "builduid": "3b062532be8f497480990402f1cab803", 01:48:45 INFO - "slavebuilddir": "test" 01:48:45 INFO - } 01:48:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 01:48:45 INFO - Running post-action listener: _resource_record_post_action 01:48:45 INFO - [mozharness: 2016-09-01 08:48:45.081095Z] Finished read-buildbot-config step (success) 01:48:45 INFO - [mozharness: 2016-09-01 08:48:45.081184Z] Running download-and-extract step. 01:48:45 INFO - Running pre-action listener: _resource_record_pre_action 01:48:45 INFO - Running main action method: download_and_extract 01:48:45 INFO - mkdir: /builds/slave/test/build/tests 01:48: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')]} 01:48:45 INFO - trying https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:48:45 INFO - Downloading https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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 01:48:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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 01:48:45 INFO - Downloaded 1361 bytes. 01:48:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:48:45 INFO - Using the following test package requirements: 01:48:45 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:48:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:48:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:48:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:48:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:48:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:48:45 INFO - u'jsshell-linux-x86_64.zip'], 01:48:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:48:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:48:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:48:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:48:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:48:45 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:48:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:48:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:48:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:48:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:48:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:48:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:48: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')]} 01:48:45 INFO - trying https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:48:45 INFO - Downloading https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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 01:48:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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 01:48:47 INFO - Downloaded 20333697 bytes. 01:48:47 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 01:48:51 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 01:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:48:51 INFO - trying https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:48:51 INFO - Downloading https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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 01:48:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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 01:48:52 INFO - Downloaded 20333697 bytes. 01:48:52 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 01:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:48:57 INFO - trying https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 01:48:57 INFO - Downloading https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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 01:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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 01:48:58 INFO - Downloaded 14413998 bytes. 01:48:58 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 01:48: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')]} 01:48:59 INFO - trying https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:48:59 INFO - Downloading https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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 01:48:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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 01:49:01 INFO - Downloaded 55868345 bytes. 01:49:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:49:01 INFO - mkdir: /builds/slave/test/properties 01:49:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:49:01 INFO - Writing to file /builds/slave/test/properties/build_url 01:49:01 INFO - Contents: 01:49:01 INFO - build_url:https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:49:01 INFO - Running post-action listener: _resource_record_post_action 01:49:01 INFO - Running post-action listener: set_extra_try_arguments 01:49:01 INFO - [mozharness: 2016-09-01 08:49:01.857553Z] Finished download-and-extract step (success) 01:49:01 INFO - [mozharness: 2016-09-01 08:49:01.857780Z] Running populate-webroot step. 01:49:01 INFO - Running pre-action listener: _resource_record_pre_action 01:49:01 INFO - Running main action method: populate_webroot 01:49:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:49:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:49:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:49:01 INFO - '--tppagecycles', 01:49:01 INFO - '7'], 01:49:01 INFO - 'tests': ['tsvgm']}}, 01:49:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:49:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:49:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:49:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:49:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:01 INFO - 'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:49:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:49:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:01 INFO - 'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['damp', 'tps']}, 01:49:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:01 INFO - 'tests': ['damp', 'tps']}, 01:49:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['dromaeo_dom']}, 01:49:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:49:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['basic_compositor_video']}, 01:49:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:49:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['a11yr', 01:49:01 INFO - 'ts_paint', 01:49:01 INFO - 'tpaint', 01:49:01 INFO - 'sessionrestore', 01:49:01 INFO - 'sessionrestore_no_auto_restore', 01:49:01 INFO - 'tabpaint']}, 01:49:01 INFO - 'other-e10s': {'tests': ['a11yr', 01:49:01 INFO - 'ts_paint', 01:49:01 INFO - 'tpaint', 01:49:01 INFO - 'sessionrestore', 01:49:01 INFO - 'sessionrestore_no_auto_restore', 01:49:01 INFO - 'tabpaint']}, 01:49:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:49:01 INFO - 'ts_paint', 01:49:01 INFO - 'tpaint', 01:49:01 INFO - 'sessionrestore', 01:49:01 INFO - 'sessionrestore_no_auto_restore', 01:49:01 INFO - 'tabpaint']}, 01:49:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:49:01 INFO - 'ts_paint', 01:49:01 INFO - 'tpaint', 01:49:01 INFO - 'sessionrestore', 01:49:01 INFO - 'sessionrestore_no_auto_restore', 01:49:01 INFO - 'tabpaint']}, 01:49:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['a11yr', 01:49:01 INFO - 'ts_paint', 01:49:01 INFO - 'tpaint', 01:49:01 INFO - 'sessionrestore', 01:49:01 INFO - 'sessionrestore_no_auto_restore', 01:49:01 INFO - 'tabpaint']}, 01:49:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['a11yr', 01:49:01 INFO - 'ts_paint', 01:49:01 INFO - 'tpaint', 01:49:01 INFO - 'sessionrestore', 01:49:01 INFO - 'sessionrestore_no_auto_restore', 01:49:01 INFO - 'tabpaint']}, 01:49:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['tsvgx', 01:49:01 INFO - 'tsvgr_opacity', 01:49:01 INFO - 'tart', 01:49:01 INFO - 'tscrollx', 01:49:01 INFO - 'cart']}, 01:49:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:49:01 INFO - 'tsvgr_opacity', 01:49:01 INFO - 'tart', 01:49:01 INFO - 'tscrollx', 01:49:01 INFO - 'cart']}, 01:49:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:01 INFO - 'talos_options': ['--disable-e10s'], 01:49:01 INFO - 'tests': ['tp5o']}, 01:49:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:01 INFO - 'tests': ['tp5o']}, 01:49:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:01 INFO - 'talos_options': ['--disable-e10s', 01:49:01 INFO - '--xperf_path', 01:49:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:49:01 INFO - 'tests': ['tp5n']}, 01:49:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:01 INFO - 'talos_options': ['--xperf_path', 01:49:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:49:01 INFO - 'tests': ['tp5n']}}, 01:49:01 INFO - 'talos.zip': {'path': '', 01:49:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:49:01 INFO - Running post-action listener: _resource_record_post_action 01:49:01 INFO - [mozharness: 2016-09-01 08:49:01.879296Z] Finished populate-webroot step (success) 01:49:01 INFO - [mozharness: 2016-09-01 08:49:01.879386Z] Running create-virtualenv step. 01:49:01 INFO - Running pre-action listener: _resource_record_pre_action 01:49:01 INFO - Running main action method: create_virtualenv 01:49:01 INFO - Creating virtualenv /builds/slave/test/build/venv 01:49:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:49:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:49:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:49:01 INFO - Using real prefix '/usr' 01:49:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:49:02 INFO - Installing distribute.............................................................................................................................................................................................done. 01:49:04 INFO - Installing pip.................done. 01:49:04 INFO - Return code: 0 01:49:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:49: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')]} 01:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49: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')]} 01:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4bb4bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1642230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, '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': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', '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 01:49: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', 'pip>=1.5'] in /builds/slave/test/build 01:49: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 pip>=1.5 01:49:04 INFO - Using env: {'DISPLAY': ':0', 01:49:04 INFO - 'HOME': '/home/cltbld', 01:49:04 INFO - 'LANG': 'en_US.UTF-8', 01:49:04 INFO - 'LANGUAGE': 'en_US:en', 01:49:04 INFO - 'LOGNAME': 'cltbld', 01:49:04 INFO - 'MAIL': '/var/mail/cltbld', 01:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:04 INFO - 'MOZ_NO_REMOTE': '1', 01:49:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:04 INFO - 'NO_EM_RESTART': '1', 01:49:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:04 INFO - 'PWD': '/builds/slave/test', 01:49:04 INFO - 'SHELL': '/bin/bash', 01:49:04 INFO - 'SHLVL': '1', 01:49:04 INFO - 'TERM': 'linux', 01:49:04 INFO - 'TMOUT': '86400', 01:49:04 INFO - 'USER': 'cltbld', 01:49:04 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:04 INFO - '_': '/tools/buildbot/bin/python'} 01:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:49:04 INFO - Cleaning up... 01:49:04 INFO - Return code: 0 01:49:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:49: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')]} 01:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49: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')]} 01:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4bb4bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1642230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, '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': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', '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 01:49:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:49:04 INFO - Using env: {'DISPLAY': ':0', 01:49:04 INFO - 'HOME': '/home/cltbld', 01:49:04 INFO - 'LANG': 'en_US.UTF-8', 01:49:04 INFO - 'LANGUAGE': 'en_US:en', 01:49:04 INFO - 'LOGNAME': 'cltbld', 01:49:04 INFO - 'MAIL': '/var/mail/cltbld', 01:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:04 INFO - 'MOZ_NO_REMOTE': '1', 01:49:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:04 INFO - 'NO_EM_RESTART': '1', 01:49:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:04 INFO - 'PWD': '/builds/slave/test', 01:49:04 INFO - 'SHELL': '/bin/bash', 01:49:04 INFO - 'SHLVL': '1', 01:49:04 INFO - 'TERM': 'linux', 01:49:04 INFO - 'TMOUT': '86400', 01:49:04 INFO - 'USER': 'cltbld', 01:49:04 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:04 INFO - '_': '/tools/buildbot/bin/python'} 01:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:04 INFO - Downloading/unpacking psutil>=3.1.1 01:49: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 01:49: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 01:49: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 01:49: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 01:49: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 01:49: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 01:49:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:49:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:49:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:49:06 INFO - Installing collected packages: psutil 01:49:06 INFO - Running setup.py install for psutil 01:49:06 INFO - building 'psutil._psutil_linux' extension 01:49:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 01:49:07 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 01:49:07 INFO - building 'psutil._psutil_posix' extension 01:49:07 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 01:49:07 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 01:49:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:49:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:49:07 INFO - Successfully installed psutil 01:49:07 INFO - Cleaning up... 01:49:07 INFO - Return code: 0 01:49:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:49: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')]} 01:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49: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')]} 01:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:07 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 0x7f9b4bb4bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1642230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, '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': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', '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 01:49:07 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 01:49:07 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 01:49:07 INFO - Using env: {'DISPLAY': ':0', 01:49:07 INFO - 'HOME': '/home/cltbld', 01:49:07 INFO - 'LANG': 'en_US.UTF-8', 01:49:07 INFO - 'LANGUAGE': 'en_US:en', 01:49:07 INFO - 'LOGNAME': 'cltbld', 01:49:07 INFO - 'MAIL': '/var/mail/cltbld', 01:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:07 INFO - 'MOZ_NO_REMOTE': '1', 01:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:07 INFO - 'NO_EM_RESTART': '1', 01:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:07 INFO - 'PWD': '/builds/slave/test', 01:49:07 INFO - 'SHELL': '/bin/bash', 01:49:07 INFO - 'SHLVL': '1', 01:49:07 INFO - 'TERM': 'linux', 01:49:07 INFO - 'TMOUT': '86400', 01:49:07 INFO - 'USER': 'cltbld', 01:49:07 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:07 INFO - '_': '/tools/buildbot/bin/python'} 01:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:49:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:49:09 INFO - Installing collected packages: mozsystemmonitor 01:49:09 INFO - Running setup.py install for mozsystemmonitor 01:49:09 INFO - Successfully installed mozsystemmonitor 01:49:09 INFO - Cleaning up... 01:49:09 INFO - Return code: 0 01:49:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9b4bb4bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1642230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, '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': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', '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 01:49:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:49:09 INFO - Using env: {'DISPLAY': ':0', 01:49:09 INFO - 'HOME': '/home/cltbld', 01:49:09 INFO - 'LANG': 'en_US.UTF-8', 01:49:09 INFO - 'LANGUAGE': 'en_US:en', 01:49:09 INFO - 'LOGNAME': 'cltbld', 01:49:09 INFO - 'MAIL': '/var/mail/cltbld', 01:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:09 INFO - 'MOZ_NO_REMOTE': '1', 01:49:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:09 INFO - 'NO_EM_RESTART': '1', 01:49:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:09 INFO - 'PWD': '/builds/slave/test', 01:49:09 INFO - 'SHELL': '/bin/bash', 01:49:09 INFO - 'SHLVL': '1', 01:49:09 INFO - 'TERM': 'linux', 01:49:09 INFO - 'TMOUT': '86400', 01:49:09 INFO - 'USER': 'cltbld', 01:49:09 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:09 INFO - '_': '/tools/buildbot/bin/python'} 01:49:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:09 INFO - Downloading/unpacking jsonschema==2.5.1 01:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:49:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:12 INFO - Downloading functools32-3.2.3-2.tar.gz 01:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:49:12 INFO - warning: no files found matching '*.txt' 01:49:12 INFO - no previously-included directories found matching 'build' 01:49:12 INFO - no previously-included directories found matching 'dist' 01:49:12 INFO - no previously-included directories found matching '.git*' 01:49:12 INFO - Installing collected packages: jsonschema, functools32 01:49:12 INFO - Running setup.py install for functools32 01:49:12 INFO - warning: no files found matching '*.txt' 01:49:12 INFO - no previously-included directories found matching 'build' 01:49:12 INFO - no previously-included directories found matching 'dist' 01:49:12 INFO - no previously-included directories found matching '.git*' 01:49:12 INFO - Successfully installed jsonschema functools32 01:49:12 INFO - Cleaning up... 01:49:12 INFO - Return code: 0 01:49:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:12 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 0x7f9b4bb4bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1642230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, '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': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', '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 01:49:12 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 01:49:12 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 01:49:12 INFO - Using env: {'DISPLAY': ':0', 01:49:12 INFO - 'HOME': '/home/cltbld', 01:49:12 INFO - 'LANG': 'en_US.UTF-8', 01:49:12 INFO - 'LANGUAGE': 'en_US:en', 01:49:12 INFO - 'LOGNAME': 'cltbld', 01:49:12 INFO - 'MAIL': '/var/mail/cltbld', 01:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:12 INFO - 'MOZ_NO_REMOTE': '1', 01:49:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:12 INFO - 'NO_EM_RESTART': '1', 01:49:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:12 INFO - 'PWD': '/builds/slave/test', 01:49:12 INFO - 'SHELL': '/bin/bash', 01:49:12 INFO - 'SHLVL': '1', 01:49:12 INFO - 'TERM': 'linux', 01:49:12 INFO - 'TMOUT': '86400', 01:49:12 INFO - 'USER': 'cltbld', 01:49:12 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:12 INFO - '_': '/tools/buildbot/bin/python'} 01:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:49:12 INFO - Cleaning up... 01:49:12 INFO - Return code: 0 01:49:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:12 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 0x7f9b4bb4bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1642230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, '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': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', '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 01:49:12 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 01:49:12 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 01:49:12 INFO - Using env: {'DISPLAY': ':0', 01:49:12 INFO - 'HOME': '/home/cltbld', 01:49:12 INFO - 'LANG': 'en_US.UTF-8', 01:49:12 INFO - 'LANGUAGE': 'en_US:en', 01:49:12 INFO - 'LOGNAME': 'cltbld', 01:49:12 INFO - 'MAIL': '/var/mail/cltbld', 01:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:12 INFO - 'MOZ_NO_REMOTE': '1', 01:49:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:12 INFO - 'NO_EM_RESTART': '1', 01:49:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:12 INFO - 'PWD': '/builds/slave/test', 01:49:12 INFO - 'SHELL': '/bin/bash', 01:49:12 INFO - 'SHLVL': '1', 01:49:12 INFO - 'TERM': 'linux', 01:49:12 INFO - 'TMOUT': '86400', 01:49:12 INFO - 'USER': 'cltbld', 01:49:12 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:12 INFO - '_': '/tools/buildbot/bin/python'} 01:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:12 INFO - Downloading/unpacking blobuploader==1.2.4 01:49: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 01:49: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 01:49: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 01:49: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 01:49: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 01:49: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 01:49:14 INFO - Downloading blobuploader-1.2.4.tar.gz 01:49:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:49:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:49:14 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 01:49:14 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 01:49:14 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 01:49:14 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 01:49:14 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 01:49:14 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 01:49:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:49:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:49:15 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 01:49:15 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 01:49:15 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 01:49:15 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 01:49:15 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 01:49:15 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 01:49:15 INFO - Downloading docopt-0.6.1.tar.gz 01:49:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:49:15 INFO - Installing collected packages: blobuploader, requests, docopt 01:49:15 INFO - Running setup.py install for blobuploader 01:49:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:49:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:49:15 INFO - Running setup.py install for requests 01:49:15 INFO - Running setup.py install for docopt 01:49:15 INFO - Successfully installed blobuploader requests docopt 01:49:15 INFO - Cleaning up... 01:49:16 INFO - Return code: 0 01:49:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:16 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 0x7f9b4bb4bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1642230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, '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': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', '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 01:49:16 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 01:49:16 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 01:49:16 INFO - Using env: {'DISPLAY': ':0', 01:49:16 INFO - 'HOME': '/home/cltbld', 01:49:16 INFO - 'LANG': 'en_US.UTF-8', 01:49:16 INFO - 'LANGUAGE': 'en_US:en', 01:49:16 INFO - 'LOGNAME': 'cltbld', 01:49:16 INFO - 'MAIL': '/var/mail/cltbld', 01:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:16 INFO - 'MOZ_NO_REMOTE': '1', 01:49:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:16 INFO - 'NO_EM_RESTART': '1', 01:49:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:16 INFO - 'PWD': '/builds/slave/test', 01:49:16 INFO - 'SHELL': '/bin/bash', 01:49:16 INFO - 'SHLVL': '1', 01:49:16 INFO - 'TERM': 'linux', 01:49:16 INFO - 'TMOUT': '86400', 01:49:16 INFO - 'USER': 'cltbld', 01:49:16 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:16 INFO - '_': '/tools/buildbot/bin/python'} 01:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:49:16 INFO - Running setup.py (path:/tmp/pip-41ULeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:49:16 INFO - Running setup.py (path:/tmp/pip-Tx5Ivv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:49:16 INFO - Running setup.py (path:/tmp/pip-cH7du0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:49:16 INFO - Running setup.py (path:/tmp/pip-8iW5M_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:49:16 INFO - Running setup.py (path:/tmp/pip-BCtRrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:49:16 INFO - Running setup.py (path:/tmp/pip-yW4ifm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:49:16 INFO - Running setup.py (path:/tmp/pip-iaUnwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:49:16 INFO - Running setup.py (path:/tmp/pip-J94qbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:49:16 INFO - Running setup.py (path:/tmp/pip-5GB5ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:49:16 INFO - Running setup.py (path:/tmp/pip-g2QXl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:49:16 INFO - Running setup.py (path:/tmp/pip-vSBA10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:49:17 INFO - Running setup.py (path:/tmp/pip-BmF7rW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:49:17 INFO - Running setup.py (path:/tmp/pip-fCqrmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:49:17 INFO - Running setup.py (path:/tmp/pip-ii7AV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:49:17 INFO - Running setup.py (path:/tmp/pip-C8lzUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:49:17 INFO - Running setup.py (path:/tmp/pip-OaEICh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:49:17 INFO - Running setup.py (path:/tmp/pip-naOPFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:49:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:49:17 INFO - Running setup.py install for manifestparser 01:49:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:49:17 INFO - Running setup.py install for mozcrash 01:49:17 INFO - Running setup.py install for mozdebug 01:49:17 INFO - Running setup.py install for mozdevice 01:49:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:49:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:49:17 INFO - Running setup.py install for mozfile 01:49:18 INFO - Running setup.py install for mozhttpd 01:49:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Running setup.py install for mozinfo 01:49:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Running setup.py install for mozInstall 01:49:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Running setup.py install for mozleak 01:49:18 INFO - Running setup.py install for mozlog 01:49:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Running setup.py install for moznetwork 01:49:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Running setup.py install for mozprocess 01:49:18 INFO - Running setup.py install for mozprofile 01:49:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Running setup.py install for mozrunner 01:49:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:49:18 INFO - Running setup.py install for mozscreenshot 01:49:19 INFO - Running setup.py install for moztest 01:49:19 INFO - Running setup.py install for mozversion 01:49:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:49:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:49:19 INFO - Cleaning up... 01:49:19 INFO - Return code: 0 01:49:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:19 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 0x7f9b4bb4bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1642230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, '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': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', '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 01:49:19 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 01:49:19 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 01:49:19 INFO - Using env: {'DISPLAY': ':0', 01:49:19 INFO - 'HOME': '/home/cltbld', 01:49:19 INFO - 'LANG': 'en_US.UTF-8', 01:49:19 INFO - 'LANGUAGE': 'en_US:en', 01:49:19 INFO - 'LOGNAME': 'cltbld', 01:49:19 INFO - 'MAIL': '/var/mail/cltbld', 01:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:19 INFO - 'MOZ_NO_REMOTE': '1', 01:49:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:19 INFO - 'NO_EM_RESTART': '1', 01:49:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:19 INFO - 'PWD': '/builds/slave/test', 01:49:19 INFO - 'SHELL': '/bin/bash', 01:49:19 INFO - 'SHLVL': '1', 01:49:19 INFO - 'TERM': 'linux', 01:49:19 INFO - 'TMOUT': '86400', 01:49:19 INFO - 'USER': 'cltbld', 01:49:19 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:19 INFO - '_': '/tools/buildbot/bin/python'} 01:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:49:19 INFO - Running setup.py (path:/tmp/pip-OkKb4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:49:19 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)) 01:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:49:19 INFO - Running setup.py (path:/tmp/pip-MC9bKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:49:19 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)) 01:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:49:19 INFO - Running setup.py (path:/tmp/pip-RN51tT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:49:19 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)) 01:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:49:19 INFO - Running setup.py (path:/tmp/pip-vdARbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:49:19 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)) 01:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:49:19 INFO - Running setup.py (path:/tmp/pip-2AXX_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:49:19 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)) 01:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:49:19 INFO - Running setup.py (path:/tmp/pip-002lk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:49:19 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)) 01:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:49:19 INFO - Running setup.py (path:/tmp/pip-Ey_V2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:49:20 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)) 01:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:49:20 INFO - Running setup.py (path:/tmp/pip-vNF_fG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:49:20 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)) 01:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:49:20 INFO - Running setup.py (path:/tmp/pip-G4RXQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:49:20 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)) 01:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:49:20 INFO - Running setup.py (path:/tmp/pip-LteFOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:49:20 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)) 01:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:49:20 INFO - Running setup.py (path:/tmp/pip-XZFR6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:49:20 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)) 01:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:49:20 INFO - Running setup.py (path:/tmp/pip-wxOO98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:49:20 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)) 01:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:49:20 INFO - Running setup.py (path:/tmp/pip-00vwez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:49:20 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)) 01:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:49:20 INFO - Running setup.py (path:/tmp/pip-QL_O0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:49:20 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)) 01:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:49:20 INFO - Running setup.py (path:/tmp/pip-S5biva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:49:20 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)) 01:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:49:20 INFO - Running setup.py (path:/tmp/pip-Uj6Vlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:49:20 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)) 01:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:49:20 INFO - Running setup.py (path:/tmp/pip-F8cP2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:49:20 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)) 01:49:20 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)) 01:49:20 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)) 01:49:20 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)) 01:49:20 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)) 01:49:20 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)) 01:49:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:22 INFO - Downloading blessings-1.6.tar.gz 01:49:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:49:22 INFO - Installing collected packages: blessings 01:49:22 INFO - Running setup.py install for blessings 01:49:22 INFO - Successfully installed blessings 01:49:22 INFO - Cleaning up... 01:49:22 INFO - Return code: 0 01:49:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:49:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:49:23 INFO - Reading from file tmpfile_stdout 01:49:23 INFO - Current package versions: 01:49:23 INFO - argparse == 1.2.1 01:49:23 INFO - blessings == 1.6 01:49:23 INFO - blobuploader == 1.2.4 01:49:23 INFO - docopt == 0.6.1 01:49:23 INFO - functools32 == 3.2.3-2 01:49:23 INFO - jsonschema == 2.5.1 01:49:23 INFO - manifestparser == 1.1 01:49:23 INFO - mozInstall == 1.12 01:49:23 INFO - mozcrash == 0.17 01:49:23 INFO - mozdebug == 0.1 01:49:23 INFO - mozdevice == 0.48 01:49:23 INFO - mozfile == 1.2 01:49:23 INFO - mozhttpd == 0.7 01:49:23 INFO - mozinfo == 0.9 01:49:23 INFO - mozleak == 0.1 01:49:23 INFO - mozlog == 3.3 01:49:23 INFO - moznetwork == 0.27 01:49:23 INFO - mozprocess == 0.23 01:49:23 INFO - mozprofile == 0.28 01:49:23 INFO - mozrunner == 6.12 01:49:23 INFO - mozscreenshot == 0.1 01:49:23 INFO - mozsystemmonitor == 0.3 01:49:23 INFO - moztest == 0.7 01:49:23 INFO - mozversion == 1.4 01:49:23 INFO - psutil == 3.1.1 01:49:23 INFO - requests == 1.2.3 01:49:23 INFO - wsgiref == 0.1.2 01:49:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:49:23 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')]} 01:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:23 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')]} 01:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:23 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 0x7f9b4bb4bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1642230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, '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': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', '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 01:49:23 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 01:49:23 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 01:49:23 INFO - Using env: {'DISPLAY': ':0', 01:49:23 INFO - 'HOME': '/home/cltbld', 01:49:23 INFO - 'LANG': 'en_US.UTF-8', 01:49:23 INFO - 'LANGUAGE': 'en_US:en', 01:49:23 INFO - 'LOGNAME': 'cltbld', 01:49:23 INFO - 'MAIL': '/var/mail/cltbld', 01:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:23 INFO - 'MOZ_NO_REMOTE': '1', 01:49:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:23 INFO - 'NO_EM_RESTART': '1', 01:49:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:23 INFO - 'PWD': '/builds/slave/test', 01:49:23 INFO - 'SHELL': '/bin/bash', 01:49:23 INFO - 'SHLVL': '1', 01:49:23 INFO - 'TERM': 'linux', 01:49:23 INFO - 'TMOUT': '86400', 01:49:23 INFO - 'USER': 'cltbld', 01:49:23 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:23 INFO - '_': '/tools/buildbot/bin/python'} 01:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:23 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)) 01:49:23 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)) 01:49:23 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)) 01:49:23 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)) 01:49:23 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)) 01:49:23 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)) 01:49:23 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)) 01:49:23 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)) 01:49:23 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)) 01:49:23 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)) 01:49:23 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)) 01:49:23 INFO - Cleaning up... 01:49:23 INFO - Return code: 0 01:49:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:49:23 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')]} 01:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:23 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')]} 01:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:23 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 0x7f9b4bb4bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1642230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, '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': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', '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 01:49:23 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 01:49:23 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 01:49:23 INFO - Using env: {'DISPLAY': ':0', 01:49:23 INFO - 'HOME': '/home/cltbld', 01:49:23 INFO - 'LANG': 'en_US.UTF-8', 01:49:23 INFO - 'LANGUAGE': 'en_US:en', 01:49:23 INFO - 'LOGNAME': 'cltbld', 01:49:23 INFO - 'MAIL': '/var/mail/cltbld', 01:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:23 INFO - 'MOZ_NO_REMOTE': '1', 01:49:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:23 INFO - 'NO_EM_RESTART': '1', 01:49:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:23 INFO - 'PWD': '/builds/slave/test', 01:49:23 INFO - 'SHELL': '/bin/bash', 01:49:23 INFO - 'SHLVL': '1', 01:49:23 INFO - 'TERM': 'linux', 01:49:23 INFO - 'TMOUT': '86400', 01:49:23 INFO - 'USER': 'cltbld', 01:49:23 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:23 INFO - '_': '/tools/buildbot/bin/python'} 01:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:49:23 INFO - Cleaning up... 01:49:23 INFO - Return code: 0 01:49:23 INFO - Running post-action listener: _resource_record_post_action 01:49:23 INFO - Running post-action listener: _start_resource_monitoring 01:49:23 INFO - Starting resource monitoring. 01:49:23 INFO - [mozharness: 2016-09-01 08:49:23.568831Z] Finished create-virtualenv step (success) 01:49:23 INFO - [mozharness: 2016-09-01 08:49:23.569283Z] Running install step. 01:49:23 INFO - Running pre-action listener: _resource_record_pre_action 01:49:23 INFO - Running main action method: install 01:49:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:49:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:49:23 INFO - Reading from file tmpfile_stdout 01:49:23 INFO - Detecting whether we're running mozinstall >=1.0... 01:49:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:49:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:49:23 INFO - Reading from file tmpfile_stdout 01:49:23 INFO - Output received: 01:49:23 INFO - Usage: mozinstall [options] installer 01:49:23 INFO - Options: 01:49:23 INFO - -h, --help show this help message and exit 01:49:23 INFO - -d DEST, --destination=DEST 01:49:23 INFO - Directory to install application into. [default: 01:49:23 INFO - "/builds/slave/test"] 01:49:23 INFO - --app=APP Application being installed. [default: firefox] 01:49:23 INFO - mkdir: /builds/slave/test/build/application 01:49:23 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'] 01:49:23 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 01:49:39 INFO - Reading from file tmpfile_stdout 01:49:39 INFO - Output received: 01:49:39 INFO - /builds/slave/test/build/application/firefox/firefox 01:49:39 INFO - Running post-action listener: _resource_record_post_action 01:49:39 INFO - [mozharness: 2016-09-01 08:49:39.992546Z] Finished install step (success) 01:49:39 INFO - [mozharness: 2016-09-01 08:49:39.992890Z] Running run-tests step. 01:49:39 INFO - Running pre-action listener: _resource_record_pre_action 01:49:39 INFO - Running main action method: run_tests 01:49:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:49:39 INFO - Python 2.7.3 01:49:39 INFO - Return code: 0 01:49:39 INFO - grabbing minidump binary from tooltool 01:49: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')]} 01:49:40 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 0x163c090>, '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 0x163f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fe40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:49:40 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 01:49:40 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 01:49:40 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 01:49:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:49:40 INFO - Return code: 0 01:49:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:49:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:49:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:49:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:49:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:49:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 01:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --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 01:49:40 INFO - Using env: {'DISPLAY': ':0', 01:49:40 INFO - 'HOME': '/home/cltbld', 01:49:40 INFO - 'LANG': 'en_US.UTF-8', 01:49:40 INFO - 'LANGUAGE': 'en_US:en', 01:49:40 INFO - 'LOGNAME': 'cltbld', 01:49:40 INFO - 'MAIL': '/var/mail/cltbld', 01:49:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:49:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:40 INFO - 'MOZ_NO_REMOTE': '1', 01:49:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:49:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:40 INFO - 'NO_EM_RESTART': '1', 01:49:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:40 INFO - 'PWD': '/builds/slave/test', 01:49:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:49:40 INFO - 'SHELL': '/bin/bash', 01:49:40 INFO - 'SHLVL': '1', 01:49:40 INFO - 'TERM': 'linux', 01:49:40 INFO - 'TMOUT': '86400', 01:49:40 INFO - 'USER': 'cltbld', 01:49:40 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102', 01:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:40 INFO - '_': '/tools/buildbot/bin/python'} 01:49:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 01:49:40 INFO - mozversion application_buildid: 20160901002821 01:49:40 INFO - mozversion application_changeset: 444b8a745f0433a467d41229261751ea5aa7f692 01:49:40 INFO - mozversion application_display_name: Nightly 01:49:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:49:40 INFO - mozversion application_name: Firefox 01:49:40 INFO - mozversion application_remotingname: firefox 01:49:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 01:49:40 INFO - mozversion application_vendor: Mozilla 01:49:40 INFO - mozversion application_version: 51.0a1 01:49:40 INFO - mozversion platform_buildid: 20160901002821 01:49:40 INFO - mozversion platform_changeset: 444b8a745f0433a467d41229261751ea5aa7f692 01:49:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 01:49:40 INFO - mozversion platform_version: 51.0a1 01:49:40 INFO - using testdate: 1472719780 01:49:40 INFO - actual date: 1472719780 01:49:40 INFO - starting webserver on 'localhost:50813' 01:49:40 INFO - SUITE-START | Running 2 tests 01:49:40 INFO - TEST-START | dromaeo_css 01:49:40 INFO - operating with platform_type : linux_ 01:49:40 INFO - Initialising browser for dromaeo_css test... 01:49:40 INFO - TEST-INFO | started process 30462 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Q6vsE/profile http://localhost:50813/getInfo.html) 01:49:44 INFO - PROCESS | 30462 | __metrics Screen width/height:1600/1200 01:49:44 INFO - PROCESS | 30462 | colorDepth:24 01:49:44 INFO - PROCESS | 30462 | Browser inner width/height: 1024/697 01:49:44 INFO - PROCESS | 30462 | __metrics 01:49:45 INFO - PROCESS | 30462 | 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" 01:49:45 INFO - PROCESS | 30462 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:49:45 INFO - PROCESS | 30462 | console.error: PushService: 01:49:45 INFO - PROCESS | 30462 | stateChangeProcessEnqueue: Error transitioning state 01:49:45 INFO - PROCESS | 30462 | UnknownError 01:49:46 INFO - TEST-INFO | 30462: exit 0 01:49:46 INFO - Browser initialized. 01:49:46 INFO - Running cycle 1/1 for dromaeo_css test... 01:49:46 INFO - TEST-INFO | started process 30545 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Q6vsE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:49:58 INFO - PROCESS | 30545 | RSS: Main: 182808576 01:49:58 INFO - PROCESS | 30545 | 01:50:50 INFO - PROCESS | 30545 | 1472719850204 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] 01:53:03 INFO - PROCESS | 30545 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50813/tests/dromaeo/cssquery-ext.html) 01:53:04 INFO - PROCESS | 30545 | RSS: Main: 285200384 01:53:04 INFO - PROCESS | 30545 | 01:56:06 INFO - PROCESS | 30545 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-ext.html (next: http://localhost:50813/tests/dromaeo/cssquery-jquery.html) 01:56:07 INFO - PROCESS | 30545 | RSS: Main: 268795904 01:56:07 INFO - PROCESS | 30545 | 01:59:09 INFO - PROCESS | 30545 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50813/tests/dromaeo/cssquery-mootools.html) 01:59:10 INFO - PROCESS | 30545 | RSS: Main: 218714112 01:59:10 INFO - PROCESS | 30545 | 02:02:12 INFO - PROCESS | 30545 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50813/tests/dromaeo/cssquery-prototype.html) 02:02:13 INFO - PROCESS | 30545 | RSS: Main: 219467776 02:02:13 INFO - PROCESS | 30545 | 02:05:16 INFO - PROCESS | 30545 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50813/tests/dromaeo/cssquery-yui.html) 02:05:16 INFO - PROCESS | 30545 | RSS: Main: 224530432 02:05:16 INFO - PROCESS | 30545 | 02:08:19 INFO - PROCESS | 30545 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-yui.html (next: http://localhost:50813/tests/dromaeo/cssquery-dojo.html) 02:08:19 INFO - PROCESS | 30545 | RSS: Main: 313094144 02:08:19 INFO - PROCESS | 30545 | 02:08:19 INFO - PROCESS | 30545 | __start_tp_report 02:08:19 INFO - PROCESS | 30545 | _x_x_mozilla_page_load 02:08:19 INFO - PROCESS | 30545 | _x_x_mozilla_page_load_details 02:08:19 INFO - PROCESS | 30545 | |i|pagename|runs| 02:08:19 INFO - PROCESS | 30545 | |0;dojo.html;2556.0925871315685;2563.7820785233253;2574.369279526515;2589.1326405654104;2604.3228760522256;4563.178627846986;4634.351190833282;4635.907281854361;4712.5994290485305;4776.068666610013;4596.264597664366;4608.60826829719;4621.260598304272;4649.883752906179;4699.647526435514;4218.198542276973;4224.366345048236;4294;4359.542248063955;4456.175607512604;4381.583749543788;4401.691881568291;4423.911521769563;4429.114177164563;4451.310046942729;4202.91594168118;4241.151769646052;4293.8711838644895;4307.569243075699;4325.4593175853015;4295.828166873322;4295.849645262432;4295.978520107411;4398.318260669601;4403.735775853459;4313.503947046067;4314.072474417983;4320.978395108004;4352.695311328208;4363.498197707273;4520.570545798144;4578.10726908253;4665.393498845128;4709.152352576569;4709.1994360958715;4954.554090131869;4974.4776798436005;4977.7262250576205;4981.551660350549;5040.5463508284065;4964.453910069889;4978.303037574742;4982.6512144149565;5011.974940125312;5047.949520504784;4553.749543775058;4618.792154353062;4629.86110416688;4654.232051711429;4698.318743782201;5124.077666020152;5147.279380886679;5147.613928955343;5196.142636464941;5206.297149884793;4928.728919909439;4938.160512712848;4939.333190019373;4941.308216849644;5002.974985125051;4818.68678535894;4938.481459446706;4988.226824842082;5013.022460620217;5014.749262536859;4954.504549545017;4957.603391728653;4961.4294356148785;4990.850274491771;4999.42506611737;4929.309896614477;4937.432195297515;4942.1104201244125;4948.05986862495;4994.750262486934;4081.3265811140827;4137.731047481904;4220.155968806251;4240.321548552279;4287.614114729627;3671.1372827385544;3691.520102386726;3738.2523495300507;3749.6625303722794;3768.9057773555332;4327.632151267115;4330.545292744217;4336.609705126553;4337.132573485315;4381.627561657231;4798.328234047236;4851.393575803025;5014.247862820534;5032.245163225545;5091.134507133796;4270.081932384553;4288.807003684841;4298.914021719548;4314.784260786948;4369.2790689536505;4501.459824821042;4508.549145085531;4509.12072145935;4525.705829121097;4567.429071366079;4517.322401639781;4699.083678682624;4703.623710103115;4706.411698537751;4746.976265118652;4893.290472881484;4942.20924652054;4954.232094025432;4967.503249675004;5035.144025515598;4612.262038074026;4640.025594625166;4655.464621568563;4656.62746980234;4711.764411779466;4776.283557466408;4909.803607855646;4911.607071434205;4916.680415772963;4976.253561965734;4972.129877271391;5007.824726134713;5014.774335155;5016.698998060128;5088.669236499616;4890.608751299958;4902.852914412573;4906.681065730716;4908.901821963469;4963.751812409438;4727.763611819327;4890.731009794496;4904.5340692634145;4906.6320025997475;4973.975130124326;4700.76496175183;4712.69367491112;4737.123632128068;4740.407449068866;4782;4838.443578988461;4841.225403935354;4844.128056949783;4844.806207751651;4899.681520701143;4616.192166370939;4732.313814496947;4734.19518681818;4736.289556566542;4798.160321943716 02:08:19 INFO - PROCESS | 30545 | |1;ext.html;558.8714595643003;559.6642014791125;566.5071387892534;567.5970061256508;572.9455701708338;27723.168304950832;36619.4507082394;37379;37420.25159496808;41265.17469650605;38092.809535952285;38454.4231836522;38502.229955400944;38566;38594.228115437676;7193.532420392684;7682.6542805573745;7823.374130069581;7832.490888092284;7835;7148.106486689164;7375.114986201664;7382.040334756474;9696.17582505488;9944.055314745088;10083.747906302327;10155.441450720244;10157.441340726256;10167.542460589255;10170.44062576558;10045.648402305884;10095.444750538718;10103.646372376932;10104.898951010542;10112.696619101438;9874.753131171778;9924.900750992541;9942.055504727039;9944.751381215456;9945.701628951143;36531.452028219595;36835.63164368376;36865.815670921475;36891.26217475662;36894.815525922466;2725.0788194322954;2776.1810265971585;2786.70739572346;2803.817751846144;2847.957280640792;27469.587956180672;27757.583636245472;28093;28202;28254.858725706446;3214.1321843102232;3255.1699316674335;3274.099622603779;3372.224388390684;3488.354654388946;244479.66561003198;245838;246254;246715.9999999982;247013;38431.231375372336;38548.8072559641;38575.807120964215;38594.22811543753;38595.421068683994;31152.376952460825;32025.679743202734;32069.67930320667;33891.15272118217;54211.45788542064;12841.229526228457;12866.099373043984;12923.547675831489;12926.676833079247;12930.353482326034;10587.311824731521;10605.787884242272;10621.893781062245;10648.414337211529;10662.413567253876;15456;15903.125328107066;15913.363465461252;15951.282192301374;16380;6494.318096599884;6645.700943457556;6705.329467053353;6772.424343930821;6799.524033317621;754.1628792040831;796.5857753967904;801.2068052627857;843.9240468357755;855.9272461840814;25575;26416.339591510365;26666.200013999613;27058.5941210882;27376;950.7147855643279;954.2842867849113;962.0908241711516;991.861139440479;1022.8568000479938;13515.324233788271;14318;14374.712505749827;14567.198804065667;14634;379710;386171;386360;386483;386858;46426.303605445944;46884;46896.5310346899;46955.591332259406;46978.06043879172;957.0764212534938;1082.4858192358568;1102.6581759654373;1115.7378016166194;1137.9829302560302;24093.17238589432;25165.496690066462;26271.737282626927;26294.211173664822;26354.86822565875;32520.186995325304;32673.509897352033;32686.83656581702;32781.34437311288;32818;137712;137759.31120344333;137790;138436;138558;23871.164509242095;24408.87795561082;24928.626070608592;24957.75042249554;24995.625065624394;34550.82724586361;34960.30079398347;39408.80295598504;40818;40834.79582602068;33408.331833363685;33847.83076084604;33961.83019084889;33990.32019359648;34353.65646343504;34360.14099647529;34523.48214776831;34790;34802.651973479944;35102.297954041285;31469.8426507866;32297.51553726649;33744.49383259302;34905.825470872485;34912.825435872655;28967.841286349154;31044.844775775975;31194.532082018333;31249.531257030707;31465.8426707865;24123;24176.75823241745;24353.878230608734;24743.752562474147;24947 02:08:19 INFO - PROCESS | 30545 | |2;jquery.html;348.8395338144452;358.5356962733259;360.45571187506863;365.22207697604097;380.4692454026633;15948.601284967866;15953.202339883002;16630.08534530602;16878.746818797696;16880.07159606221;15531.84468155318;16252.106134162615;16852.662946741057;16989.57526061849;17014.48956531303;4884.706917584947;5126.102931986906;5127.615428842833;5129.666571672848;5130.871728206787;3653.342398368293;3832.6550610445056;3844.3464611016193;3876.205377897524;5050.873728156789;3886.397608370707;6115.205023346959;6116.724747386356;6130.233720784902;6206.75172993082;5846.619969701956;5973.551983601225;6030.728617212236;6061.727222275008;6123.908141377883;5343.492368225013;5350.063738845686;5351.197320401932;5352.705601191933;5353.38436079848;21007.159713611436;22187.889060554757;22961;23027.078916843308;23493.17773877906;1843.8617103717274;2034.6134234495398;2233.6426171812595;2287.913241210428;2463.3841539615096;13852.93073534636;14102.506412275623;14386.85613143876;14574.562763117123;14575.489857854924;1815.7094864821697;1844.4190080124852;1845.308009496439;1866.8413184879303;1874.6157037807218;77677.61161194215;80953.59523202405;82637.58681206616;83179.58410207971;85851.14148858556;16160.595985100466;16419;16698.248578813982;16739.414120505724;16927.238274277686;28066.43867122646;28123.57814632782;28264.85867570649;28436.857815710788;28505.144845654664;8455.95772021136;8618.784530386665;8666.653333866574;8833.779155521035;8940.061293564082;7188.496805223689;7591.240875912475;7672.65473053714;7894.171112033268;7938.6427610757655;8208.179182081743;8322.334213262924;8379.413441059067;8461.703840365557;8484.533350665773;3048.029403110849;3154.4322022036254;3268.3790079884748;3296.5055241713935;3301.422251106047;827.9172082791794;834.2450082675067;860.0968982568278;869.1265278395173;909.2135302962889;15542.378304867905;16330.75503867443;16668;17043.82956170447;17110.743338849927;771.3443572963049;807.7051876065194;813.6209125532166;826.3182874128747;875.4484674654926;8885.600147993357;8981.730548083568;9007.324450666227;10095.394276343442;10163.18694504438;354893;354962;365924;379933;381687;77106;78131.60934195407;78210;80248;80407;918.5361392496746;920.0845159066771;933.8505839065717;943.061653654618;947.2185447006248;12683.936580317039;12858.935705321413;12918.677033074247;12981.221126732426;13027.869721302666;35154.296914062084;37080.07299817526;37689.05777355588;37690;38928.22143557169;50348.99302013866;52161.7391913038;52379;52456.47543524516;52516.47483525269;7226.602536860523;7528.360089392244;7550.35821955118;7636.274553917369;8073.111957684532;10804.362138695182;11578.421078946187;11885.643430697093;12100.152989290666;12375.133740638068;9869.802603947737;10096.242781791248;10316.845247321146;10376.533056012648;10921.781564368826;4771.6898401603785;9835.573841793042;9934.254930880068;10513.632022879163;10521.736956576147;10265.794684106128;10415.531301091318;10661.786764264516;10826.242163048511;11208.103351732005;7599.772006839804;7616.162222155448;7625.084989801259;7692.538447693157;7694.07671079474;9103.908960910307;10428.478576071318;10665.146788256765;10705.19711021661;10822.188335874998 02:08:19 INFO - PROCESS | 30545 | |3;mootools.html;1183.7159081820366;1308.3654427602612;1336.4320163930329;1373.8420081690601;1632.4939268826656;6580.703868325925;6843.794686159417;6872.725090996367;6875.7593484228;7049.753258635948;7283.235260297672;7618.12391574969;7621.504602200853;7626.50427722199;7626.885596716053;2149.376680762577;2180.7710190430016;2182.312571539968;2193.2323686709683;2193.5503221839485;2087.279888438486;2129.127057906259;2172.2831465616387;2175.912963481459;2176.1948079210715;2099.5276062885805;2116.767155612881;2119.2900378373265;2127.851050426471;2147.7100591420112;2061.0416156487304;2064.4013236161572;2076.501639606499;2084.0205103601284;2093.675480300556;2085.259732794851;2110.767815540289;2110.9366718998454;2116.428564287649;2117.4388786971463;7510.549367037996;7550.924490755132;7564.621768911587;7571.583562904038;7597.506162099446;4230.259704551691;4369.4756629204385;4374.759388233646;4408.977955110236;4410.492793328776;2790.7441651256813;2966.644002719666;3004.3841012592366;3005.6693763685976;3017.4719424100695;982.6609819612294;984.1536278800226;985.4333758089056;996.7807082441851;1003.64872294697;10180.185585153169;10350.223733220038;10356.06795388411;10373.11828494579;10374.377537347784;3995.380715989026;4062.2281766464275;4216.936745948752;4233.470816147981;4258.233517966733;8951.552422378854;8954.328425368123;8954.373193876496;8956.059613740463;8973.416727912665;4445.199864024442;4445.59989600931;4455.220336441174;4455.509893911667;4456;3423.246885685195;3534.2048039190977;3655.7258205634685;4027.637512623878;4051.230266249403;3384.61076976146;3409.3351796399465;3504.421770407905;3509.666581674737;3511.8244087795506;1131.971700707489;1141.9771604567861;1151.205668089016;1153.6308381318065;1156.3928937307812;654.7348323928817;684.9965750171217;732.2238427267114;760.8288135169653;768.5696010234175;5150;5150.072986862401;5272.551833094153;5290.603204759658;5448.101063324507;827.9089300176976;856.5588721808274;881.0924747510074;886.2688282167238;888.6312180445173;3261.2825178460703;3265.5754751882105;3298.5546951161286;3303.570535830375;3333.3333333332944;8960.462372257685;9316.114969077928;9396.95301523488;9398.20115290208;9417.670381536613;9260.907390926139;9261.583228754587;9262.351635385594;9266.027067157851;9267.026962168875;7101.467389945671;7219.025431566672;7270.382017528569;7271.672774725044;7279.67241474135;5023.974880125576;5053.31780209667;5085.694858308365;5163.148080566664;5361.731913404392;8325.83348333042;8600.956995214983;8626.568671566522;8663.436876603;8718.997315308821;7519.624018798929;8654.30765538768;8711.867457230519;8771.824563508822;8786.868196977179;5702.201691763239;5861.824145275648;5890.175375447527;6000.069989151689;6004.519638428827;6983.860322793414;6988.965055174692;7043.577385356895;7050.612216327946;7052.330028647476;6455.419012288917;6456.386643268882;6497.9025314619375;6511.902321465276;6521.836954076186;6258.718357673824;6340.4927605792345;6350.206224221973;6390.5526613136635;6412.871742565216;5938.010766393134;5996.910046349395;6021.698915054142;6036.154938308729;6123.938760612336;4256.59562341577;4384.824607015684;4414.161309351213;4427.756473393994;4546.40896683429;4299.161663475592;4340.804663790074;4428.778561071998;4489.506154323087;4550.8862278441975 02:08:19 INFO - PROCESS | 30545 | |4;prototype.html;287.8416870721104;292.1891750392659;292.20957310475166;297.2968928484135;297.8138663335415;4733.928991065137;4778.259369797678;4778.378810754606;4788.808447662098;4798.4001999750035;4670.855640368115;4678.461976872661;4738.79160813992;4756.90486190276;4759.524047595248;3542.3092496963104;3546.733994950376;3692.8153592320277;3720.441933709938;3725.4225595032813;3706.4069748840193;3715.479832823407;3717.4609681596153;3727;3730.5523337199575;3678.50340204073;3697.0405684987;3701.6488981521684;3703.944440833376;3716.4425336199515;3504.404251277276;3549.3078849624335;3618.294432585647;3662.1577037281572;3685.4840322354894;3263.673632636741;3271.525628783813;3282.5404443377947;3288.2765791525826;3300.966990330114;4529.501754806968;4572.359869618256;4573.954260457419;4573.977130114328;4594.218982772936;3998.140399814025;4181.226473102486;4224.366345048236;4231.809568569391;4241.172971270604;4895.143349913751;4912.459629440758;4917.237828136609;4942.233953737176;4942.728149951751;3611.205534782344;3617.963820361789;3618.583862855779;3620.3664358737115;3620.714646300578;4933.062718083553;5141.768620412053;5164.6384753067305;5172.560332371753;5177.741112944375;3641.672249497505;3649.3066317399607;3668.5597728272246;3674.3018826422895;3681.9447708284397;3717.9442308365396;3726.31063253299;3734;3737.2525494900583;3738.6074462181623;4167.874963751032;4174.686898482626;4185.602367775056;4189.748615083105;4204.579542045771;4779.856604301807;4898.2652602109965;4931.309616653672;4934.67924584908;4941.604671626261;1291.8708129187007;1314.8882345000782;1317.1636011133;1318.5714642740957;1319.129374612751;803.7026300268938;832.8423491347032;835.469476882172;838.6519594368269;842.3345557010016;4234.110836724322;4385.386045953569;4390.253656878338;4390.53899340571;4391.5827996340295;5018.222175562793;5064.468230835783;5067;5072.528966599645;5080.212567052113;4334.41485399467;4350.412694286294;4362.476502819682;4377.649788016951;4382.2112019836095;2511.1336588876807;2671.252049426164;2681.9731802682113;2703.391736859191;2712.5253080710795;4759.50025247344;4767.308740232716;4776.21192503233;4776.593989510861;4777.641676874248;5006.148954677714;5204.687718736888;5207.583393328525;5210.76551555181;5212.374515058217;4671.416072990876;4697.78859951303;4728.196206644879;4734.360861283682;4777.566729981061;5025.145725226647;5029.924551131663;5050.813058931221;5054.9494505055945;5059;4727.3854398927915;4942.555170034714;4969.652124351261;4971.950280497149;4986.7257300848805;4673.532646735299;4699.060187962353;4701.02556926085;4712.316714076371;4718.976405117953;3625.6193099725206;3695.1870588470492;3701;3715.498407714924;3720.1257704439977;4990.126727822689;5037;5044.646874718735;5048.091343558195;5049.091163590442;4632.606228470618;4900.583450406755;4920.434150072788;4926.778294976663;4939.6542242042715;4867.829625963074;4890.217565189695;4892.865528628304;4897.265410188358;4942.357493525818;4777.856664300077;4782;4795.256735206049;4825.734584597881;4865.489123641966;4704.500128349356;4891.633127515522;4896.902061958812;4909.1036288588475;4951.430585482715;4788.688735232199;4809.278608208657;4823.758812059313;4823.975880120577;4829.396325459317 02:08:19 INFO - PROCESS | 30545 | |5;yui.html;580.918671386006;595.2083728640905;630.4404119275738;645.3449748505267;647.8445173158443;11710.414479276034;12146.514139434423;12262.632121036371;12491.937540312309;12585.244885306887;13921.512747053855;14490.420383184686;14530.636734081654;14539.273036348193;14569.490067847628;712.2770388056127;732.1104857598017;734.438154811567;734.9154847192561;735.1582438108363;589.1222079102129;699.5907394174388;718.2673672853687;718.4252597921668;723.3887365176417;749.7938067031555;751.0536723728114;751.3313151295351;751.612919346537;751.6617522115071;742.9665665045086;744.036472767767;744.6723441685641;744.7579536650583;744.9515781474187;448.8653403978826;537.8278950735767;547.5072434808704;552.2682445759344;554.789180111555;12159.817602735966;12171.026317894548;12178.756424871453;12194.573189938399;12200.755984880341;11142.554297828077;11862.28826270418;11874.228175168586;12012.879871201263;12240.571579994747;12127.957705188419;12277.263364198086;12365.629031129081;12479.625611231677;12498.375081245993;4097.242010228118;4118.546959834416;4141.399497072907;4143.555302371616;4159.334506478949;67084.26025445576;67620;68403;68470.65764671194;68895.65552172257;9252.398594091363;9851.75370615726;10225.897741022643;10324.741881452874;10679.78640427187;18030.819691802913;18191.18139683691;18245.543861403305;18271.177796999167;18411.447656570323;6193.1329613853195;6211.3478084801345;6307.968460157762;6368.076628888785;6659.101021362151;3401.2857299967286;3419.093940105874;3435.7251419886347;3477.078574177845;3487.8256087196046;2522.41984343602;2618.834618594707;2621.5281249375294;2621.6722909636296;2623.921282361532;445.2653122348087;449.10851958861633;452.42993827777235;455.30793194345074;457.922153233951;468.68363854398825;481.9903601927942;496.69701482095894;509.8444974282879;510.7676007416616;7303.817404564822;7474.439417043744;7647.502912310682;7683.692652293846;7786.1824508426935;478.44739326078434;481.5714014527074;485.63334682314627;490.4825409193264;491.0694234425743;3628.564572251347;3783.375743002429;3811.3330167220624;3815.5802861685193;3884.786336751449;27554.311142221202;27676.861615691792;105174.4741276304;105429.47285263678;106713;90275.54862225647;94849;95058.52470737603;95529.5223523878;95997;50837.74581127071;80422.59788701136;80950;81854;83183.16816831875;6889.311068893071;6944.826379340557;7108.537945033556;7229.7469588564145;7303.269673032867;46811.53188468072;47150.76424617992;47864.5213547874;49027.509724902295;49127.75436122796;47395.52604474049;66717.66641166763;67005;67100.3289967094;69786;8723.65105395777;8771.429857059271;8932.46405215689;9155.771105722144;9352.53237338144;9944.1547468466;10164.33931794431;10200.438975856103;10263.12763415118;10396.012378824;9507.191888689262;9564.808703826024;9651.662191823252;9968.601255949681;10128.493575321058;9768;9772.12050915421;9780.657676981271;9862.21102311799;10027.398356098658;9269.814603708022;9280.489573073546;9376.859347109663;9470.431774093577;9790.167835733757;7663.8850417242675;7767.384712946623;7827.726029588937;8213.83572328562;8227.629756660845;7589.1651918290045;7788.065432148179;7907.209279072047;7941.086775020715;8225.259726624632 02:08:19 INFO - PROCESS | 30545 | __end_tp_report 02:08:19 INFO - PROCESS | 30545 | __start_cc_report 02:08:19 INFO - PROCESS | 30545 | _x_x_mozilla_cycle_collect,1027 02:08:19 INFO - PROCESS | 30545 | __end_cc_report 02:08:19 INFO - PROCESS | 30545 | __startTimestamp1472720899753__endTimestamp 02:08:19 INFO - PROCESS | 30545 | 02:08:19 INFO - PROCESS | 30545 | ------- Summary: start ------- 02:08:19 INFO - PROCESS | 30545 | Number of tests: 6 02:08:19 INFO - PROCESS | 30545 | 02:08:19 INFO - PROCESS | 30545 | [#0] dojo.html Cycles:165 Average:4610.74 Median:4709.20 stddev:480.59 (10.2%) stddev-sans-first:454.23 02:08:19 INFO - PROCESS | 30545 | Values: 2556.1 2563.8 2574.4 2589.1 2604.3 4563.2 4634.4 4635.9 4712.6 4776.1 4596.3 4608.6 4621.3 4649.9 4699.6 4218.2 4224.4 4294.0 4359.5 4456.2 4381.6 4401.7 4423.9 4429.1 4451.3 4202.9 4241.2 4293.9 4307.6 4325.5 4295.8 4295.8 4296.0 4398.3 4403.7 4313.5 4314.1 4321.0 4352.7 4363.5 4520.6 4578.1 4665.4 4709.2 4709.2 4954.6 4974.5 4977.7 4981.6 5040.5 4964.5 4978.3 4982.7 5012.0 5047.9 4553.7 4618.8 4629.9 4654.2 4698.3 5124.1 5147.3 5147.6 5196.1 5206.3 4928.7 4938.2 4939.3 4941.3 5003.0 4818.7 4938.5 4988.2 5013.0 5014.7 4954.5 4957.6 4961.4 4990.9 4999.4 4929.3 4937.4 4942.1 4948.1 4994.8 4081.3 4137.7 4220.2 4240.3 4287.6 3671.1 3691.5 3738.3 3749.7 3768.9 4327.6 4330.5 4336.6 4337.1 4381.6 4798.3 4851.4 5014.2 5032.2 5091.1 4270.1 4288.8 4298.9 4314.8 4369.3 4501.5 4508.5 4509.1 4525.7 4567.4 4517.3 4699.1 4703.6 4706.4 4747.0 4893.3 4942.2 4954.2 4967.5 5035.1 4612.3 4640.0 4655.5 4656.6 4711.8 4776.3 4909.8 4911.6 4916.7 4976.3 4972.1 5007.8 5014.8 5016.7 5088.7 4890.6 4902.9 4906.7 4908.9 4963.8 4727.8 4890.7 4904.5 4906.6 4974.0 4700.8 4712.7 4737.1 4740.4 4782.0 4838.4 4841.2 4844.1 4844.8 4899.7 4616.2 4732.3 4734.2 4736.3 4798.2 02:08:19 INFO - PROCESS | 30545 | 02:08:19 INFO - PROCESS | 30545 | [#1] ext.html Cycles:180 Average:40324.98 Median:17980.47 stddev:72887.11 (405.4%) stddev-sans-first:73030.43 02:08:19 INFO - PROCESS | 30545 | Values: 558.9 559.7 566.5 567.6 572.9 27723.2 36619.5 37379.0 37420.3 41265.2 38092.8 38454.4 38502.2 38566.0 38594.2 7193.5 7682.7 7823.4 7832.5 7835.0 7148.1 7375.1 7382.0 9696.2 9944.1 10083.7 10155.4 10157.4 10167.5 10170.4 10045.6 10095.4 10103.6 10104.9 10112.7 9874.8 9924.9 9942.1 9944.8 9945.7 36531.5 36835.6 36865.8 36891.3 36894.8 2725.1 2776.2 2786.7 2803.8 2848.0 27469.6 27757.6 28093.0 28202.0 28254.9 3214.1 3255.2 3274.1 3372.2 3488.4 244479.7 245838.0 246254.0 246716.0 247013.0 38431.2 38548.8 38575.8 38594.2 38595.4 31152.4 32025.7 32069.7 33891.2 54211.5 12841.2 12866.1 12923.5 12926.7 12930.4 10587.3 10605.8 10621.9 10648.4 10662.4 15456.0 15903.1 15913.4 15951.3 16380.0 6494.3 6645.7 6705.3 6772.4 6799.5 754.2 796.6 801.2 843.9 855.9 25575.0 26416.3 26666.2 27058.6 27376.0 950.7 954.3 962.1 991.9 1022.9 13515.3 14318.0 14374.7 14567.2 14634.0 379710.0 386171.0 386360.0 386483.0 386858.0 46426.3 46884.0 46896.5 46955.6 46978.1 957.1 1082.5 1102.7 1115.7 1138.0 24093.2 25165.5 26271.7 26294.2 26354.9 32520.2 32673.5 32686.8 32781.3 32818.0 137712.0 137759.3 137790.0 138436.0 138558.0 23871.2 24408.9 24928.6 24957.8 24995.6 34550.8 34960.3 39408.8 40818.0 40834.8 33408.3 33847.8 33961.8 33990.3 34353.7 34360.1 34523.5 34790.0 34802.7 35102.3 31469.8 32297.5 33744.5 34905.8 34912.8 28967.8 31044.8 31194.5 31249.5 31465.8 24123.0 24176.8 24353.9 24743.8 24947.0 02:08:19 INFO - PROCESS | 30545 | 02:08:19 INFO - PROCESS | 30545 | [#2] jquery.html Cycles:180 Average:24993.33 Median:191033.73 stddev:61097.40 (32.0%) stddev-sans-first:61240.77 02:08:19 INFO - PROCESS | 30545 | Values: 348.8 358.5 360.5 365.2 380.5 15948.6 15953.2 16630.1 16878.7 16880.1 15531.8 16252.1 16852.7 16989.6 17014.5 4884.7 5126.1 5127.6 5129.7 5130.9 3653.3 3832.7 3844.3 3876.2 5050.9 3886.4 6115.2 6116.7 6130.2 6206.8 5846.6 5973.6 6030.7 6061.7 6123.9 5343.5 5350.1 5351.2 5352.7 5353.4 21007.2 22187.9 22961.0 23027.1 23493.2 1843.9 2034.6 2233.6 2287.9 2463.4 13852.9 14102.5 14386.9 14574.6 14575.5 1815.7 1844.4 1845.3 1866.8 1874.6 77677.6 80953.6 82637.6 83179.6 85851.1 16160.6 16419.0 16698.2 16739.4 16927.2 28066.4 28123.6 28264.9 28436.9 28505.1 8456.0 8618.8 8666.7 8833.8 8940.1 7188.5 7591.2 7672.7 7894.2 7938.6 8208.2 8322.3 8379.4 8461.7 8484.5 3048.0 3154.4 3268.4 3296.5 3301.4 827.9 834.2 860.1 869.1 909.2 15542.4 16330.8 16668.0 17043.8 17110.7 771.3 807.7 813.6 826.3 875.4 8885.6 8981.7 9007.3 10095.4 10163.2 354893.0 354962.0 365924.0 379933.0 381687.0 77106.0 78131.6 78210.0 80248.0 80407.0 918.5 920.1 933.9 943.1 947.2 12683.9 12858.9 12918.7 12981.2 13027.9 35154.3 37080.1 37689.1 37690.0 38928.2 50349.0 52161.7 52379.0 52456.5 52516.5 7226.6 7528.4 7550.4 7636.3 8073.1 10804.4 11578.4 11885.6 12100.2 12375.1 9869.8 10096.2 10316.8 10376.5 10921.8 4771.7 9835.6 9934.3 10513.6 10521.7 10265.8 10415.5 10661.8 10826.2 11208.1 7599.8 7616.2 7625.1 7692.5 7694.1 9103.9 10428.5 10665.1 10705.2 10822.2 02:08:19 INFO - PROCESS | 30545 | 02:08:19 INFO - PROCESS | 30545 | [#3] mootools.html Cycles:180 Average:4923.83 Median:5156.61 stddev:2753.87 (53.4%) stddev-sans-first:2747.25 02:08:19 INFO - PROCESS | 30545 | Values: 1183.7 1308.4 1336.4 1373.8 1632.5 6580.7 6843.8 6872.7 6875.8 7049.8 7283.2 7618.1 7621.5 7626.5 7626.9 2149.4 2180.8 2182.3 2193.2 2193.6 2087.3 2129.1 2172.3 2175.9 2176.2 2099.5 2116.8 2119.3 2127.9 2147.7 2061.0 2064.4 2076.5 2084.0 2093.7 2085.3 2110.8 2110.9 2116.4 2117.4 7510.5 7550.9 7564.6 7571.6 7597.5 4230.3 4369.5 4374.8 4409.0 4410.5 2790.7 2966.6 3004.4 3005.7 3017.5 982.7 984.2 985.4 996.8 1003.6 10180.2 10350.2 10356.1 10373.1 10374.4 3995.4 4062.2 4216.9 4233.5 4258.2 8951.6 8954.3 8954.4 8956.1 8973.4 4445.2 4445.6 4455.2 4455.5 4456.0 3423.2 3534.2 3655.7 4027.6 4051.2 3384.6 3409.3 3504.4 3509.7 3511.8 1132.0 1142.0 1151.2 1153.6 1156.4 654.7 685.0 732.2 760.8 768.6 5150.0 5150.1 5272.6 5290.6 5448.1 827.9 856.6 881.1 886.3 888.6 3261.3 3265.6 3298.6 3303.6 3333.3 8960.5 9316.1 9397.0 9398.2 9417.7 9260.9 9261.6 9262.4 9266.0 9267.0 7101.5 7219.0 7270.4 7271.7 7279.7 5024.0 5053.3 5085.7 5163.1 5361.7 8325.8 8601.0 8626.6 8663.4 8719.0 7519.6 8654.3 8711.9 8771.8 8786.9 5702.2 5861.8 5890.2 6000.1 6004.5 6983.9 6989.0 7043.6 7050.6 7052.3 6455.4 6456.4 6497.9 6511.9 6521.8 6258.7 6340.5 6350.2 6390.6 6412.9 5938.0 5996.9 6021.7 6036.2 6123.9 4256.6 4384.8 4414.2 4427.8 4546.4 4299.2 4340.8 4428.8 4489.5 4550.9 02:08:19 INFO - PROCESS | 30545 | 02:08:19 INFO - PROCESS | 30545 | [#4] prototype.html Cycles:180 Average:4097.36 Median:4698.42 stddev:1174.22 (25.0%) stddev-sans-first:1142.17 02:08:19 INFO - PROCESS | 30545 | Values: 287.8 292.2 292.2 297.3 297.8 4733.9 4778.3 4778.4 4788.8 4798.4 4670.9 4678.5 4738.8 4756.9 4759.5 3542.3 3546.7 3692.8 3720.4 3725.4 3706.4 3715.5 3717.5 3727.0 3730.6 3678.5 3697.0 3701.6 3703.9 3716.4 3504.4 3549.3 3618.3 3662.2 3685.5 3263.7 3271.5 3282.5 3288.3 3301.0 4529.5 4572.4 4574.0 4574.0 4594.2 3998.1 4181.2 4224.4 4231.8 4241.2 4895.1 4912.5 4917.2 4942.2 4942.7 3611.2 3618.0 3618.6 3620.4 3620.7 4933.1 5141.8 5164.6 5172.6 5177.7 3641.7 3649.3 3668.6 3674.3 3681.9 3717.9 3726.3 3734.0 3737.3 3738.6 4167.9 4174.7 4185.6 4189.7 4204.6 4779.9 4898.3 4931.3 4934.7 4941.6 1291.9 1314.9 1317.2 1318.6 1319.1 803.7 832.8 835.5 838.7 842.3 4234.1 4385.4 4390.3 4390.5 4391.6 5018.2 5064.5 5067.0 5072.5 5080.2 4334.4 4350.4 4362.5 4377.6 4382.2 2511.1 2671.3 2682.0 2703.4 2712.5 4759.5 4767.3 4776.2 4776.6 4777.6 5006.1 5204.7 5207.6 5210.8 5212.4 4671.4 4697.8 4728.2 4734.4 4777.6 5025.1 5029.9 5050.8 5054.9 5059.0 4727.4 4942.6 4969.7 4972.0 4986.7 4673.5 4699.1 4701.0 4712.3 4719.0 3625.6 3695.2 3701.0 3715.5 3720.1 4990.1 5037.0 5044.6 5048.1 5049.1 4632.6 4900.6 4920.4 4926.8 4939.7 4867.8 4890.2 4892.9 4897.3 4942.4 4777.9 4782.0 4795.3 4825.7 4865.5 4704.5 4891.6 4896.9 4909.1 4951.4 4788.7 4809.3 4823.8 4824.0 4829.4 02:08:19 INFO - PROCESS | 30545 | 02:08:19 INFO - PROCESS | 30545 | [#5] yui.html Cycles:180 Average:17217.52 Median:567.85 stddev:25986.23 (4576.2%) stddev-sans-first:26029.10 02:08:19 INFO - PROCESS | 30545 | Values: 580.9 595.2 630.4 645.3 647.8 11710.4 12146.5 12262.6 12491.9 12585.2 13921.5 14490.4 14530.6 14539.3 14569.5 712.3 732.1 734.4 734.9 735.2 589.1 699.6 718.3 718.4 723.4 749.8 751.1 751.3 751.6 751.7 743.0 744.0 744.7 744.8 745.0 448.9 537.8 547.5 552.3 554.8 12159.8 12171.0 12178.8 12194.6 12200.8 11142.6 11862.3 11874.2 12012.9 12240.6 12128.0 12277.3 12365.6 12479.6 12498.4 4097.2 4118.5 4141.4 4143.6 4159.3 67084.3 67620.0 68403.0 68470.7 68895.7 9252.4 9851.8 10225.9 10324.7 10679.8 18030.8 18191.2 18245.5 18271.2 18411.4 6193.1 6211.3 6308.0 6368.1 6659.1 3401.3 3419.1 3435.7 3477.1 3487.8 2522.4 2618.8 2621.5 2621.7 2623.9 445.3 449.1 452.4 455.3 457.9 468.7 482.0 496.7 509.8 510.8 7303.8 7474.4 7647.5 7683.7 7786.2 478.4 481.6 485.6 490.5 491.1 3628.6 3783.4 3811.3 3815.6 3884.8 27554.3 27676.9 105174.5 105429.5 106713.0 90275.5 94849.0 95058.5 95529.5 95997.0 50837.7 80422.6 80950.0 81854.0 83183.2 6889.3 6944.8 7108.5 7229.7 7303.3 46811.5 47150.8 47864.5 49027.5 49127.8 47395.5 66717.7 67005.0 67100.3 69786.0 8723.7 8771.4 8932.5 9155.8 9352.5 9944.2 10164.3 10200.4 10263.1 10396.0 9507.2 9564.8 9651.7 9968.6 10128.5 9768.0 9772.1 9780.7 9862.2 10027.4 9269.8 9280.5 9376.9 9470.4 9790.2 7663.9 7767.4 7827.7 8213.8 8227.6 7589.2 7788.1 7907.2 7941.1 8225.3 02:08:19 INFO - PROCESS | 30545 | -------- Summary: end -------- 02:08:19 INFO - PROCESS | 30545 | 02:08:20 INFO - TEST-INFO | 30545: exit 0 02:08:20 INFO - TEST-OK | dromaeo_css | took 1120509ms 02:08:20 INFO - TEST-START | kraken 02:08:20 INFO - operating with platform_type : linux_ 02:08:20 INFO - Initialising browser for kraken test... 02:08:20 INFO - TEST-INFO | started process 30846 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbrt94G/profile http://localhost:50813/getInfo.html) 02:08:25 INFO - PROCESS | 30846 | __metrics Screen width/height:1600/1200 02:08:25 INFO - PROCESS | 30846 | colorDepth:24 02:08:25 INFO - PROCESS | 30846 | Browser inner width/height: 1024/697 02:08:25 INFO - PROCESS | 30846 | __metrics 02:08:25 INFO - PROCESS | 30846 | 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" 02:08:25 INFO - PROCESS | 30846 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:08:26 INFO - PROCESS | 30846 | console.error: PushService: 02:08:26 INFO - PROCESS | 30846 | stateChangeProcessEnqueue: Error transitioning state 02:08:26 INFO - PROCESS | 30846 | UnknownError 02:08:27 INFO - TEST-INFO | 30846: exit 0 02:08:27 INFO - Browser initialized. 02:08:27 INFO - Running cycle 1/1 for kraken test... 02:08:27 INFO - TEST-INFO | started process 30929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbrt94G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:08:39 INFO - PROCESS | 30929 | RSS: Main: 182349824 02:08:39 INFO - PROCESS | 30929 | 02:08:42 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50813/tests/kraken/driver.html?testName=audio-beat-detection) 02:08:43 INFO - PROCESS | 30929 | RSS: Main: 235712512 02:08:43 INFO - PROCESS | 30929 | 02:08:45 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50813/tests/kraken/driver.html?testName=audio-dft) 02:08:46 INFO - PROCESS | 30929 | RSS: Main: 222425088 02:08:46 INFO - PROCESS | 30929 | 02:08:49 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50813/tests/kraken/driver.html?testName=audio-fft) 02:08:50 INFO - PROCESS | 30929 | RSS: Main: 304472064 02:08:50 INFO - PROCESS | 30929 | 02:08:52 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50813/tests/kraken/driver.html?testName=audio-oscillator) 02:08:52 INFO - PROCESS | 30929 | RSS: Main: 213819392 02:08:52 INFO - PROCESS | 30929 | 02:08:55 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50813/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:08:55 INFO - PROCESS | 30929 | RSS: Main: 215023616 02:08:55 INFO - PROCESS | 30929 | 02:08:58 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50813/tests/kraken/driver.html?testName=imaging-darkroom) 02:08:59 INFO - PROCESS | 30929 | RSS: Main: 253083648 02:08:59 INFO - PROCESS | 30929 | 02:09:03 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50813/tests/kraken/driver.html?testName=imaging-desaturate) 02:09:03 INFO - PROCESS | 30929 | RSS: Main: 247558144 02:09:03 INFO - PROCESS | 30929 | 02:09:06 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50813/tests/kraken/driver.html?testName=json-parse-financial) 02:09:07 INFO - PROCESS | 30929 | RSS: Main: 250490880 02:09:07 INFO - PROCESS | 30929 | 02:09:08 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50813/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:09:09 INFO - PROCESS | 30929 | RSS: Main: 214114304 02:09:09 INFO - PROCESS | 30929 | 02:09:11 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:09:12 INFO - PROCESS | 30929 | RSS: Main: 222203904 02:09:12 INFO - PROCESS | 30929 | 02:09:14 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:09:14 INFO - PROCESS | 30929 | RSS: Main: 222461952 02:09:14 INFO - PROCESS | 30929 | 02:09:17 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:09:18 INFO - PROCESS | 30929 | RSS: Main: 224141312 02:09:18 INFO - PROCESS | 30929 | 02:09:21 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:09:22 INFO - PROCESS | 30929 | RSS: Main: 221458432 02:09:22 INFO - PROCESS | 30929 | 02:09:23 INFO - PROCESS | 30929 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50813/tests/kraken/driver.html?testName=ai-astar) 02:09:24 INFO - PROCESS | 30929 | RSS: Main: 272310272 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | __start_tp_report 02:09:24 INFO - PROCESS | 30929 | _x_x_mozilla_page_load 02:09:24 INFO - PROCESS | 30929 | _x_x_mozilla_page_load_details 02:09:24 INFO - PROCESS | 30929 | |i|pagename|runs| 02:09:24 INFO - PROCESS | 30929 | |0;ai-astar;115;114;135;115;114;114;114;113;114;114 02:09:24 INFO - PROCESS | 30929 | |1;audio-beat-detection;151;124;148;191;125;149;154;128;183;130 02:09:24 INFO - PROCESS | 30929 | |2;audio-dft;163;162;162;161;161;161;163;163;160;161 02:09:24 INFO - PROCESS | 30929 | |3;audio-fft;77;79;76;168;76;76;76;78;166;75 02:09:24 INFO - PROCESS | 30929 | |4;audio-oscillator;83;83;159;83;83;85;135;85;85;145 02:09:24 INFO - PROCESS | 30929 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;116;117;116 02:09:24 INFO - PROCESS | 30929 | |6;imaging-darkroom;147;146;146;147;147;146;146;149;146;146 02:09:24 INFO - PROCESS | 30929 | |7;imaging-desaturate;95;95;95;96;95;95;95;95;94;94 02:09:24 INFO - PROCESS | 30929 | |8;json-parse-financial;65;66;66;67;65;75;67;65;66;66 02:09:24 INFO - PROCESS | 30929 | |9;json-stringify-tinderbox;66;67;66;67;68;66;67;66;67;69 02:09:24 INFO - PROCESS | 30929 | |10;stanford-crypto-aes;83;87;82;85;82;80;81;83;81;81 02:09:24 INFO - PROCESS | 30929 | |11;stanford-crypto-ccm;144;131;134;142;149;135;134;134;182;142 02:09:24 INFO - PROCESS | 30929 | |12;stanford-crypto-pbkdf2;173;180;174;176;180;173;173;169;170;170 02:09:24 INFO - PROCESS | 30929 | |13;stanford-crypto-sha256-iterative;69;69;73;68;68;71;69;68;71;68 02:09:24 INFO - PROCESS | 30929 | __end_tp_report 02:09:24 INFO - PROCESS | 30929 | __start_cc_report 02:09:24 INFO - PROCESS | 30929 | _x_x_mozilla_cycle_collect,2002 02:09:24 INFO - PROCESS | 30929 | __end_cc_report 02:09:24 INFO - PROCESS | 30929 | __startTimestamp1472720964046__endTimestamp 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | ------- Summary: start ------- 02:09:24 INFO - PROCESS | 30929 | Number of tests: 14 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#0] ai-astar Cycles:10 Average:116.20 Median:114.00 stddev:6.63 (5.8%) stddev-sans-first:7.02 02:09:24 INFO - PROCESS | 30929 | Values: 115.0 114.0 135.0 115.0 114.0 114.0 114.0 113.0 114.0 114.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#1] audio-beat-detection Cycles:10 Average:148.30 Median:150.00 stddev:23.45 (15.6%) stddev-sans-first:24.85 02:09:24 INFO - PROCESS | 30929 | Values: 151.0 124.0 148.0 191.0 125.0 149.0 154.0 128.0 183.0 130.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.06 (0.7%) stddev-sans-first:1.01 02:09:24 INFO - PROCESS | 30929 | Values: 163.0 162.0 162.0 161.0 161.0 161.0 163.0 163.0 160.0 161.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#3] audio-fft Cycles:10 Average:94.70 Median:76.00 stddev:38.13 (50.2%) stddev-sans-first:39.90 02:09:24 INFO - PROCESS | 30929 | Values: 77.0 79.0 76.0 168.0 76.0 76.0 76.0 78.0 166.0 75.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#4] audio-oscillator Cycles:10 Average:102.60 Median:83.00 stddev:30.72 (37.0%) stddev-sans-first:31.76 02:09:24 INFO - PROCESS | 30929 | Values: 83.0 83.0 159.0 83.0 83.0 85.0 135.0 85.0 85.0 145.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:09:24 INFO - PROCESS | 30929 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:146.50 stddev:0.97 (0.7%) stddev-sans-first:1.01 02:09:24 INFO - PROCESS | 30929 | Values: 147.0 146.0 146.0 147.0 147.0 146.0 146.0 149.0 146.0 146.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 02:09:24 INFO - PROCESS | 30929 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 94.0 94.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:66.00 stddev:2.97 (4.5%) stddev-sans-first:3.08 02:09:24 INFO - PROCESS | 30929 | Values: 65.0 66.0 66.0 67.0 65.0 75.0 67.0 65.0 66.0 66.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 02:09:24 INFO - PROCESS | 30929 | Values: 66.0 67.0 66.0 67.0 68.0 66.0 67.0 66.0 67.0 69.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:82.50 stddev:2.12 (2.6%) stddev-sans-first:2.24 02:09:24 INFO - PROCESS | 30929 | Values: 83.0 87.0 82.0 85.0 82.0 80.0 81.0 83.0 81.0 81.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:142.00 stddev:14.94 (10.5%) stddev-sans-first:15.84 02:09:24 INFO - PROCESS | 30929 | Values: 144.0 131.0 134.0 142.0 149.0 135.0 134.0 134.0 182.0 142.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:173.50 stddev:3.88 (2.2%) stddev-sans-first:4.11 02:09:24 INFO - PROCESS | 30929 | Values: 173.0 180.0 174.0 176.0 180.0 173.0 173.0 169.0 170.0 170.0 02:09:24 INFO - PROCESS | 30929 | 02:09:24 INFO - PROCESS | 30929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.00 stddev:1.71 (2.5%) stddev-sans-first:1.81 02:09:24 INFO - PROCESS | 30929 | Values: 69.0 69.0 73.0 68.0 68.0 71.0 69.0 68.0 71.0 68.0 02:09:24 INFO - PROCESS | 30929 | -------- Summary: end -------- 02:09:24 INFO - PROCESS | 30929 | 02:09:25 INFO - TEST-INFO | 30929: exit 0 02:09:25 INFO - TEST-OK | kraken | took 64705ms 02:09:25 INFO - SUITE-END | took 1185s 02:09:25 INFO - Completed test suite (00:19:45) 02:09:25 INFO - javascript benchmark 02:09:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2556.0925871315685, 2563.7820785233253, 2574.369279526515, 2589.1326405654104, 2604.3228760522256, 4563.178627846986, 4634.351190833282, 4635.907281854361, 4712.5994290485305, 4776.068666610013, 4596.264597664366, 4608.60826829719, 4621.260598304272, 4649.883752906179, 4699.647526435514, 4218.198542276973, 4224.366345048236, 4294.0, 4359.542248063955, 4456.175607512604, 4381.583749543788, 4401.691881568291, 4423.911521769563, 4429.114177164563, 4451.310046942729, 4202.91594168118, 4241.151769646052, 4293.8711838644895, 4307.569243075699, 4325.4593175853015, 4295.828166873322, 4295.849645262432, 4295.978520107411, 4398.318260669601, 4403.735775853459, 4313.503947046067, 4314.072474417983, 4320.978395108004, 4352.695311328208, 4363.498197707273, 4520.570545798144, 4578.10726908253, 4665.393498845128, 4709.152352576569, 4709.1994360958715, 4954.554090131869, 4974.4776798436005, 4977.7262250576205, 4981.551660350549, 5040.5463508284065, 4964.453910069889, 4978.303037574742, 4982.6512144149565, 5011.974940125312, 5047.949520504784, 4553.749543775058, 4618.792154353062, 4629.86110416688, 4654.232051711429, 4698.318743782201, 5124.077666020152, 5147.279380886679, 5147.613928955343, 5196.142636464941, 5206.297149884793, 4928.728919909439, 4938.160512712848, 4939.333190019373, 4941.308216849644, 5002.974985125051, 4818.68678535894, 4938.481459446706, 4988.226824842082, 5013.022460620217, 5014.749262536859, 4954.504549545017, 4957.603391728653, 4961.4294356148785, 4990.850274491771, 4999.42506611737, 4929.309896614477, 4937.432195297515, 4942.1104201244125, 4948.05986862495, 4994.750262486934, 4081.3265811140827, 4137.731047481904, 4220.155968806251, 4240.321548552279, 4287.614114729627, 3671.1372827385544, 3691.520102386726, 3738.2523495300507, 3749.6625303722794, 3768.9057773555332, 4327.632151267115, 4330.545292744217, 4336.609705126553, 4337.132573485315, 4381.627561657231, 4798.328234047236, 4851.393575803025, 5014.247862820534, 5032.245163225545, 5091.134507133796, 4270.081932384553, 4288.807003684841, 4298.914021719548, 4314.784260786948, 4369.2790689536505, 4501.459824821042, 4508.549145085531, 4509.12072145935, 4525.705829121097, 4567.429071366079, 4517.322401639781, 4699.083678682624, 4703.623710103115, 4706.411698537751, 4746.976265118652, 4893.290472881484, 4942.20924652054, 4954.232094025432, 4967.503249675004, 5035.144025515598, 4612.262038074026, 4640.025594625166, 4655.464621568563, 4656.62746980234, 4711.764411779466, 4776.283557466408, 4909.803607855646, 4911.607071434205, 4916.680415772963, 4976.253561965734, 4972.129877271391, 5007.824726134713, 5014.774335155, 5016.698998060128, 5088.669236499616, 4890.608751299958, 4902.852914412573, 4906.681065730716, 4908.901821963469, 4963.751812409438, 4727.763611819327, 4890.731009794496, 4904.5340692634145, 4906.6320025997475, 4973.975130124326, 4700.76496175183, 4712.69367491112, 4737.123632128068, 4740.407449068866, 4782.0, 4838.443578988461, 4841.225403935354, 4844.128056949783, 4844.806207751651, 4899.681520701143, 4616.192166370939, 4732.313814496947, 4734.19518681818, 4736.289556566542, 4798.160321943716], "value": 4579.988463495234, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.8714595643003, 559.6642014791125, 566.5071387892534, 567.5970061256508, 572.9455701708338, 27723.168304950832, 36619.4507082394, 37379.0, 37420.25159496808, 41265.17469650605, 38092.809535952285, 38454.4231836522, 38502.229955400944, 38566.0, 38594.228115437676, 7193.532420392684, 7682.6542805573745, 7823.374130069581, 7832.490888092284, 7835.0, 7148.106486689164, 7375.114986201664, 7382.040334756474, 9696.17582505488, 9944.055314745088, 10083.747906302327, 10155.441450720244, 10157.441340726256, 10167.542460589255, 10170.44062576558, 10045.648402305884, 10095.444750538718, 10103.646372376932, 10104.898951010542, 10112.696619101438, 9874.753131171778, 9924.900750992541, 9942.055504727039, 9944.751381215456, 9945.701628951143, 36531.452028219595, 36835.63164368376, 36865.815670921475, 36891.26217475662, 36894.815525922466, 2725.0788194322954, 2776.1810265971585, 2786.70739572346, 2803.817751846144, 2847.957280640792, 27469.587956180672, 27757.583636245472, 28093.0, 28202.0, 28254.858725706446, 3214.1321843102232, 3255.1699316674335, 3274.099622603779, 3372.224388390684, 3488.354654388946, 244479.66561003198, 245838.0, 246254.0, 246715.9999999982, 247013.0, 38431.231375372336, 38548.8072559641, 38575.807120964215, 38594.22811543753, 38595.421068683994, 31152.376952460825, 32025.679743202734, 32069.67930320667, 33891.15272118217, 54211.45788542064, 12841.229526228457, 12866.099373043984, 12923.547675831489, 12926.676833079247, 12930.353482326034, 10587.311824731521, 10605.787884242272, 10621.893781062245, 10648.414337211529, 10662.413567253876, 15456.0, 15903.125328107066, 15913.363465461252, 15951.282192301374, 16380.0, 6494.318096599884, 6645.700943457556, 6705.329467053353, 6772.424343930821, 6799.524033317621, 754.1628792040831, 796.5857753967904, 801.2068052627857, 843.9240468357755, 855.9272461840814, 25575.0, 26416.339591510365, 26666.200013999613, 27058.5941210882, 27376.0, 950.7147855643279, 954.2842867849113, 962.0908241711516, 991.861139440479, 1022.8568000479938, 13515.324233788271, 14318.0, 14374.712505749827, 14567.198804065667, 14634.0, 379710.0, 386171.0, 386360.0, 386483.0, 386858.0, 46426.303605445944, 46884.0, 46896.5310346899, 46955.591332259406, 46978.06043879172, 957.0764212534938, 1082.4858192358568, 1102.6581759654373, 1115.7378016166194, 1137.9829302560302, 24093.17238589432, 25165.496690066462, 26271.737282626927, 26294.211173664822, 26354.86822565875, 32520.186995325304, 32673.509897352033, 32686.83656581702, 32781.34437311288, 32818.0, 137712.0, 137759.31120344333, 137790.0, 138436.0, 138558.0, 23871.164509242095, 24408.87795561082, 24928.626070608592, 24957.75042249554, 24995.625065624394, 34550.82724586361, 34960.30079398347, 39408.80295598504, 40818.0, 40834.79582602068, 33408.331833363685, 33847.83076084604, 33961.83019084889, 33990.32019359648, 34353.65646343504, 34360.14099647529, 34523.48214776831, 34790.0, 34802.651973479944, 35102.297954041285, 31469.8426507866, 32297.51553726649, 33744.49383259302, 34905.825470872485, 34912.825435872655, 28967.841286349154, 31044.844775775975, 31194.532082018333, 31249.531257030707, 31465.8426707865, 24123.0, 24176.75823241745, 24353.878230608734, 24743.752562474147, 24947.0], "value": 16330.492206268063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.8395338144452, 358.5356962733259, 360.45571187506863, 365.22207697604097, 380.4692454026633, 15948.601284967866, 15953.202339883002, 16630.08534530602, 16878.746818797696, 16880.07159606221, 15531.84468155318, 16252.106134162615, 16852.662946741057, 16989.57526061849, 17014.48956531303, 4884.706917584947, 5126.102931986906, 5127.615428842833, 5129.666571672848, 5130.871728206787, 3653.342398368293, 3832.6550610445056, 3844.3464611016193, 3876.205377897524, 5050.873728156789, 3886.397608370707, 6115.205023346959, 6116.724747386356, 6130.233720784902, 6206.75172993082, 5846.619969701956, 5973.551983601225, 6030.728617212236, 6061.727222275008, 6123.908141377883, 5343.492368225013, 5350.063738845686, 5351.197320401932, 5352.705601191933, 5353.38436079848, 21007.159713611436, 22187.889060554757, 22961.0, 23027.078916843308, 23493.17773877906, 1843.8617103717274, 2034.6134234495398, 2233.6426171812595, 2287.913241210428, 2463.3841539615096, 13852.93073534636, 14102.506412275623, 14386.85613143876, 14574.562763117123, 14575.489857854924, 1815.7094864821697, 1844.4190080124852, 1845.308009496439, 1866.8413184879303, 1874.6157037807218, 77677.61161194215, 80953.59523202405, 82637.58681206616, 83179.58410207971, 85851.14148858556, 16160.595985100466, 16419.0, 16698.248578813982, 16739.414120505724, 16927.238274277686, 28066.43867122646, 28123.57814632782, 28264.85867570649, 28436.857815710788, 28505.144845654664, 8455.95772021136, 8618.784530386665, 8666.653333866574, 8833.779155521035, 8940.061293564082, 7188.496805223689, 7591.240875912475, 7672.65473053714, 7894.171112033268, 7938.6427610757655, 8208.179182081743, 8322.334213262924, 8379.413441059067, 8461.703840365557, 8484.533350665773, 3048.029403110849, 3154.4322022036254, 3268.3790079884748, 3296.5055241713935, 3301.422251106047, 827.9172082791794, 834.2450082675067, 860.0968982568278, 869.1265278395173, 909.2135302962889, 15542.378304867905, 16330.75503867443, 16668.0, 17043.82956170447, 17110.743338849927, 771.3443572963049, 807.7051876065194, 813.6209125532166, 826.3182874128747, 875.4484674654926, 8885.600147993357, 8981.730548083568, 9007.324450666227, 10095.394276343442, 10163.18694504438, 354893.0, 354962.0, 365924.0, 379933.0, 381687.0, 77106.0, 78131.60934195407, 78210.0, 80248.0, 80407.0, 918.5361392496746, 920.0845159066771, 933.8505839065717, 943.061653654618, 947.2185447006248, 12683.936580317039, 12858.935705321413, 12918.677033074247, 12981.221126732426, 13027.869721302666, 35154.296914062084, 37080.07299817526, 37689.05777355588, 37690.0, 38928.22143557169, 50348.99302013866, 52161.7391913038, 52379.0, 52456.47543524516, 52516.47483525269, 7226.602536860523, 7528.360089392244, 7550.35821955118, 7636.274553917369, 8073.111957684532, 10804.362138695182, 11578.421078946187, 11885.643430697093, 12100.152989290666, 12375.133740638068, 9869.802603947737, 10096.242781791248, 10316.845247321146, 10376.533056012648, 10921.781564368826, 4771.6898401603785, 9835.573841793042, 9934.254930880068, 10513.632022879163, 10521.736956576147, 10265.794684106128, 10415.531301091318, 10661.786764264516, 10826.242163048511, 11208.103351732005, 7599.772006839804, 7616.162222155448, 7625.084989801259, 7692.538447693157, 7694.07671079474, 9103.908960910307, 10428.478576071318, 10665.146788256765, 10705.19711021661, 10822.188335874998], "value": 8937.999871947122, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1183.7159081820366, 1308.3654427602612, 1336.4320163930329, 1373.8420081690601, 1632.4939268826656, 6580.703868325925, 6843.794686159417, 6872.725090996367, 6875.7593484228, 7049.753258635948, 7283.235260297672, 7618.12391574969, 7621.504602200853, 7626.50427722199, 7626.885596716053, 2149.376680762577, 2180.7710190430016, 2182.312571539968, 2193.2323686709683, 2193.5503221839485, 2087.279888438486, 2129.127057906259, 2172.2831465616387, 2175.912963481459, 2176.1948079210715, 2099.5276062885805, 2116.767155612881, 2119.2900378373265, 2127.851050426471, 2147.7100591420112, 2061.0416156487304, 2064.4013236161572, 2076.501639606499, 2084.0205103601284, 2093.675480300556, 2085.259732794851, 2110.767815540289, 2110.9366718998454, 2116.428564287649, 2117.4388786971463, 7510.549367037996, 7550.924490755132, 7564.621768911587, 7571.583562904038, 7597.506162099446, 4230.259704551691, 4369.4756629204385, 4374.759388233646, 4408.977955110236, 4410.492793328776, 2790.7441651256813, 2966.644002719666, 3004.3841012592366, 3005.6693763685976, 3017.4719424100695, 982.6609819612294, 984.1536278800226, 985.4333758089056, 996.7807082441851, 1003.64872294697, 10180.185585153169, 10350.223733220038, 10356.06795388411, 10373.11828494579, 10374.377537347784, 3995.380715989026, 4062.2281766464275, 4216.936745948752, 4233.470816147981, 4258.233517966733, 8951.552422378854, 8954.328425368123, 8954.373193876496, 8956.059613740463, 8973.416727912665, 4445.199864024442, 4445.59989600931, 4455.220336441174, 4455.509893911667, 4456.0, 3423.246885685195, 3534.2048039190977, 3655.7258205634685, 4027.637512623878, 4051.230266249403, 3384.61076976146, 3409.3351796399465, 3504.421770407905, 3509.666581674737, 3511.8244087795506, 1131.971700707489, 1141.9771604567861, 1151.205668089016, 1153.6308381318065, 1156.3928937307812, 654.7348323928817, 684.9965750171217, 732.2238427267114, 760.8288135169653, 768.5696010234175, 5150.0, 5150.072986862401, 5272.551833094153, 5290.603204759658, 5448.101063324507, 827.9089300176976, 856.5588721808274, 881.0924747510074, 886.2688282167238, 888.6312180445173, 3261.2825178460703, 3265.5754751882105, 3298.5546951161286, 3303.570535830375, 3333.3333333332944, 8960.462372257685, 9316.114969077928, 9396.95301523488, 9398.20115290208, 9417.670381536613, 9260.907390926139, 9261.583228754587, 9262.351635385594, 9266.027067157851, 9267.026962168875, 7101.467389945671, 7219.025431566672, 7270.382017528569, 7271.672774725044, 7279.67241474135, 5023.974880125576, 5053.31780209667, 5085.694858308365, 5163.148080566664, 5361.731913404392, 8325.83348333042, 8600.956995214983, 8626.568671566522, 8663.436876603, 8718.997315308821, 7519.624018798929, 8654.30765538768, 8711.867457230519, 8771.824563508822, 8786.868196977179, 5702.201691763239, 5861.824145275648, 5890.175375447527, 6000.069989151689, 6004.519638428827, 6983.860322793414, 6988.965055174692, 7043.577385356895, 7050.612216327946, 7052.330028647476, 6455.419012288917, 6456.386643268882, 6497.9025314619375, 6511.902321465276, 6521.836954076186, 6258.718357673824, 6340.4927605792345, 6350.206224221973, 6390.5526613136635, 6412.871742565216, 5938.010766393134, 5996.910046349395, 6021.698915054142, 6036.154938308729, 6123.938760612336, 4256.59562341577, 4384.824607015684, 4414.161309351213, 4427.756473393994, 4546.40896683429, 4299.161663475592, 4340.804663790074, 4428.778561071998, 4489.506154323087, 4550.8862278441975], "value": 3976.563376854099, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.8416870721104, 292.1891750392659, 292.20957310475166, 297.2968928484135, 297.8138663335415, 4733.928991065137, 4778.259369797678, 4778.378810754606, 4788.808447662098, 4798.4001999750035, 4670.855640368115, 4678.461976872661, 4738.79160813992, 4756.90486190276, 4759.524047595248, 3542.3092496963104, 3546.733994950376, 3692.8153592320277, 3720.441933709938, 3725.4225595032813, 3706.4069748840193, 3715.479832823407, 3717.4609681596153, 3727.0, 3730.5523337199575, 3678.50340204073, 3697.0405684987, 3701.6488981521684, 3703.944440833376, 3716.4425336199515, 3504.404251277276, 3549.3078849624335, 3618.294432585647, 3662.1577037281572, 3685.4840322354894, 3263.673632636741, 3271.525628783813, 3282.5404443377947, 3288.2765791525826, 3300.966990330114, 4529.501754806968, 4572.359869618256, 4573.954260457419, 4573.977130114328, 4594.218982772936, 3998.140399814025, 4181.226473102486, 4224.366345048236, 4231.809568569391, 4241.172971270604, 4895.143349913751, 4912.459629440758, 4917.237828136609, 4942.233953737176, 4942.728149951751, 3611.205534782344, 3617.963820361789, 3618.583862855779, 3620.3664358737115, 3620.714646300578, 4933.062718083553, 5141.768620412053, 5164.6384753067305, 5172.560332371753, 5177.741112944375, 3641.672249497505, 3649.3066317399607, 3668.5597728272246, 3674.3018826422895, 3681.9447708284397, 3717.9442308365396, 3726.31063253299, 3734.0, 3737.2525494900583, 3738.6074462181623, 4167.874963751032, 4174.686898482626, 4185.602367775056, 4189.748615083105, 4204.579542045771, 4779.856604301807, 4898.2652602109965, 4931.309616653672, 4934.67924584908, 4941.604671626261, 1291.8708129187007, 1314.8882345000782, 1317.1636011133, 1318.5714642740957, 1319.129374612751, 803.7026300268938, 832.8423491347032, 835.469476882172, 838.6519594368269, 842.3345557010016, 4234.110836724322, 4385.386045953569, 4390.253656878338, 4390.53899340571, 4391.5827996340295, 5018.222175562793, 5064.468230835783, 5067.0, 5072.528966599645, 5080.212567052113, 4334.41485399467, 4350.412694286294, 4362.476502819682, 4377.649788016951, 4382.2112019836095, 2511.1336588876807, 2671.252049426164, 2681.9731802682113, 2703.391736859191, 2712.5253080710795, 4759.50025247344, 4767.308740232716, 4776.21192503233, 4776.593989510861, 4777.641676874248, 5006.148954677714, 5204.687718736888, 5207.583393328525, 5210.76551555181, 5212.374515058217, 4671.416072990876, 4697.78859951303, 4728.196206644879, 4734.360861283682, 4777.566729981061, 5025.145725226647, 5029.924551131663, 5050.813058931221, 5054.9494505055945, 5059.0, 4727.3854398927915, 4942.555170034714, 4969.652124351261, 4971.950280497149, 4986.7257300848805, 4673.532646735299, 4699.060187962353, 4701.02556926085, 4712.316714076371, 4718.976405117953, 3625.6193099725206, 3695.1870588470492, 3701.0, 3715.498407714924, 3720.1257704439977, 4990.126727822689, 5037.0, 5044.646874718735, 5048.091343558195, 5049.091163590442, 4632.606228470618, 4900.583450406755, 4920.434150072788, 4926.778294976663, 4939.6542242042715, 4867.829625963074, 4890.217565189695, 4892.865528628304, 4897.265410188358, 4942.357493525818, 4777.856664300077, 4782.0, 4795.256735206049, 4825.734584597881, 4865.489123641966, 4704.500128349356, 4891.633127515522, 4896.902061958812, 4909.1036288588475, 4951.430585482715, 4788.688735232199, 4809.278608208657, 4823.758812059313, 4823.975880120577, 4829.396325459317], "value": 3724.6154289376286, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.918671386006, 595.2083728640905, 630.4404119275738, 645.3449748505267, 647.8445173158443, 11710.414479276034, 12146.514139434423, 12262.632121036371, 12491.937540312309, 12585.244885306887, 13921.512747053855, 14490.420383184686, 14530.636734081654, 14539.273036348193, 14569.490067847628, 712.2770388056127, 732.1104857598017, 734.438154811567, 734.9154847192561, 735.1582438108363, 589.1222079102129, 699.5907394174388, 718.2673672853687, 718.4252597921668, 723.3887365176417, 749.7938067031555, 751.0536723728114, 751.3313151295351, 751.612919346537, 751.6617522115071, 742.9665665045086, 744.036472767767, 744.6723441685641, 744.7579536650583, 744.9515781474187, 448.8653403978826, 537.8278950735767, 547.5072434808704, 552.2682445759344, 554.789180111555, 12159.817602735966, 12171.026317894548, 12178.756424871453, 12194.573189938399, 12200.755984880341, 11142.554297828077, 11862.28826270418, 11874.228175168586, 12012.879871201263, 12240.571579994747, 12127.957705188419, 12277.263364198086, 12365.629031129081, 12479.625611231677, 12498.375081245993, 4097.242010228118, 4118.546959834416, 4141.399497072907, 4143.555302371616, 4159.334506478949, 67084.26025445576, 67620.0, 68403.0, 68470.65764671194, 68895.65552172257, 9252.398594091363, 9851.75370615726, 10225.897741022643, 10324.741881452874, 10679.78640427187, 18030.819691802913, 18191.18139683691, 18245.543861403305, 18271.177796999167, 18411.447656570323, 6193.1329613853195, 6211.3478084801345, 6307.968460157762, 6368.076628888785, 6659.101021362151, 3401.2857299967286, 3419.093940105874, 3435.7251419886347, 3477.078574177845, 3487.8256087196046, 2522.41984343602, 2618.834618594707, 2621.5281249375294, 2621.6722909636296, 2623.921282361532, 445.2653122348087, 449.10851958861633, 452.42993827777235, 455.30793194345074, 457.922153233951, 468.68363854398825, 481.9903601927942, 496.69701482095894, 509.8444974282879, 510.7676007416616, 7303.817404564822, 7474.439417043744, 7647.502912310682, 7683.692652293846, 7786.1824508426935, 478.44739326078434, 481.5714014527074, 485.63334682314627, 490.4825409193264, 491.0694234425743, 3628.564572251347, 3783.375743002429, 3811.3330167220624, 3815.5802861685193, 3884.786336751449, 27554.311142221202, 27676.861615691792, 105174.4741276304, 105429.47285263678, 106713.0, 90275.54862225647, 94849.0, 95058.52470737603, 95529.5223523878, 95997.0, 50837.74581127071, 80422.59788701136, 80950.0, 81854.0, 83183.16816831875, 6889.311068893071, 6944.826379340557, 7108.537945033556, 7229.7469588564145, 7303.269673032867, 46811.53188468072, 47150.76424617992, 47864.5213547874, 49027.509724902295, 49127.75436122796, 47395.52604474049, 66717.66641166763, 67005.0, 67100.3289967094, 69786.0, 8723.65105395777, 8771.429857059271, 8932.46405215689, 9155.771105722144, 9352.53237338144, 9944.1547468466, 10164.33931794431, 10200.438975856103, 10263.12763415118, 10396.012378824, 9507.191888689262, 9564.808703826024, 9651.662191823252, 9968.601255949681, 10128.493575321058, 9768.0, 9772.12050915421, 9780.657676981271, 9862.21102311799, 10027.398356098658, 9269.814603708022, 9280.489573073546, 9376.859347109663, 9470.431774093577, 9790.167835733757, 7663.8850417242675, 7767.384712946623, 7827.726029588937, 8213.83572328562, 8227.629756660845, 7589.1651918290045, 7788.065432148179, 7907.209279072047, 7941.086775020715, 8225.259726624632], "value": 6073.346510719133, "unit": "score"}], "name": "dromaeo_css", "value": 6259.342807858033, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 135.0, 115.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 148.0, 191.0, 125.0, 149.0, 154.0, 128.0, 183.0, 130.0], "value": 148.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 161.0, 161.0, 161.0, 163.0, 163.0, 160.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 76.0, 168.0, 76.0, 76.0, 76.0, 78.0, 166.0, 75.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 159.0, 83.0, 83.0, 85.0, 135.0, 85.0, 85.0, 145.0], "value": 102.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 149.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 65.0, 75.0, 67.0, 65.0, 66.0, 66.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 68.0, 66.0, 67.0, 66.0, 67.0, 69.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 82.0, 85.0, 82.0, 80.0, 81.0, 83.0, 81.0, 81.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 131.0, 134.0, 142.0, 149.0, 135.0, 134.0, 134.0, 182.0, 142.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 180.0, 174.0, 176.0, 180.0, 173.0, 173.0, 169.0, 170.0, 170.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 73.0, 68.0, 68.0, 71.0, 69.0, 68.0, 71.0, 68.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1583.9, "alertThreshold": 2.0}]} 02:09:25 INFO - Return code: 0 02:09:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:09:25 INFO - # TBPL SUCCESS # 02:09:25 INFO - Running post-action listener: _resource_record_post_action 02:09:25 INFO - [mozharness: 2016-09-01 09:09:25.837712Z] Finished run-tests step (success) 02:09:25 INFO - Running post-run listener: _resource_record_post_run 02:09:25 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 407134208; Read time: 8; Write time: 286264 02:09:25 INFO - TinderboxPrint: CPU usage
13.0% 02:09:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 02:09:25 INFO - TinderboxPrint: I/O write bytes / time
407,134,208 / 286,264 02:09:25 INFO - TinderboxPrint: CPU idle
8,340.9 (87.0%) 02:09:25 INFO - TinderboxPrint: CPU user
1,199.2 (12.5%) 02:09:25 INFO - TinderboxPrint: Swap in / out
0 / 0 02:09:25 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114307072; Read time: 0; Write time: 100704 02:09:25 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 292827136; Read time: 0; Write time: 185560 02:09:26 INFO - Running post-run listener: _upload_blobber_files 02:09:26 INFO - Blob upload gear active. 02:09:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:09:26 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 02:09:26 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:09:26 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:09:26 INFO - (blobuploader) - INFO - Open directory for files ... 02:09:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:09:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:09:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:09:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:09:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:09:27 INFO - (blobuploader) - INFO - Done attempting. 02:09:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:09:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:09:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:09:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:09:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:09:28 INFO - (blobuploader) - INFO - Done attempting. 02:09:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:09:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:09:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:09:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:09:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:09:35 INFO - (blobuploader) - INFO - Done attempting. 02:09:35 INFO - (blobuploader) - INFO - Iteration through files over. 02:09:35 INFO - Return code: 0 02:09:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:09:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:09:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/07df7de21122e743d755249e0b0cc1887741bd01546f614061710cc0c053cdae185ed6c60288d752d233d52f5d850d32884b96bd445ed59f3d8f629c82434017", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/53ca00111dc4fe8f14be851fde45c569bdb0dbe9c4ef57a91db49170ade0604feafc2828e5b3b59b6ff7d86e3dd013031d8f7fd74a5cbd6b3d1b95b04c14ccab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/989c76e2df487808b94c70b723e919b222dc299984d50198c430106994b9afff4acff065bc645ca07b3c5320d9f3c8052e3df9ccfc087e968e957b2dfab96752"} 02:09:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:09:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:09:35 INFO - Contents: 02:09:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/07df7de21122e743d755249e0b0cc1887741bd01546f614061710cc0c053cdae185ed6c60288d752d233d52f5d850d32884b96bd445ed59f3d8f629c82434017", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/53ca00111dc4fe8f14be851fde45c569bdb0dbe9c4ef57a91db49170ade0604feafc2828e5b3b59b6ff7d86e3dd013031d8f7fd74a5cbd6b3d1b95b04c14ccab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/989c76e2df487808b94c70b723e919b222dc299984d50198c430106994b9afff4acff065bc645ca07b3c5320d9f3c8052e3df9ccfc087e968e957b2dfab96752"} 02:09:35 INFO - Running post-run listener: copy_logs_to_upload_dir 02:09:35 INFO - Copying logs to upload dir... 02:09:35 INFO - mkdir: /builds/slave/test/build/upload/logs 02:09:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.575211 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-09-01 02:09:34.837888) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 02:09:34.841421) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/07df7de21122e743d755249e0b0cc1887741bd01546f614061710cc0c053cdae185ed6c60288d752d233d52f5d850d32884b96bd445ed59f3d8f629c82434017", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/53ca00111dc4fe8f14be851fde45c569bdb0dbe9c4ef57a91db49170ade0604feafc2828e5b3b59b6ff7d86e3dd013031d8f7fd74a5cbd6b3d1b95b04c14ccab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/989c76e2df487808b94c70b723e919b222dc299984d50198c430106994b9afff4acff065bc645ca07b3c5320d9f3c8052e3df9ccfc087e968e957b2dfab96752"} build_url:https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/f1f0TH2sTceEkWrDnSh63g/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/Autoland-Non-PGO/sha512/07df7de21122e743d755249e0b0cc1887741bd01546f614061710cc0c053cdae185ed6c60288d752d233d52f5d850d32884b96bd445ed59f3d8f629c82434017", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/53ca00111dc4fe8f14be851fde45c569bdb0dbe9c4ef57a91db49170ade0604feafc2828e5b3b59b6ff7d86e3dd013031d8f7fd74a5cbd6b3d1b95b04c14ccab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/989c76e2df487808b94c70b723e919b222dc299984d50198c430106994b9afff4acff065bc645ca07b3c5320d9f3c8052e3df9ccfc087e968e957b2dfab96752"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 02:09:34.866081) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 02:09:34.866387) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1472719052.713752-217159102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 02:09:34.952099) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 02:09:34.952410) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 02:09:34.952733) ========= ========= Total master_lag: 0.16 =========