builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-033 starttime: 1471335691.72 results: success (0) buildid: 20160815234825 builduid: 37c6dc186a564643b5b4d6bb1350f1cb revision: cc8350ff1a9ba2e9754d9d2d98063e864f38c655 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:31.720488) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:31.720980) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:31.721261) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:31.785615) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:31.785892) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 01:21:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.6M=0s 2016-08-16 01:21:32 (34.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123362 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:31.923209) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:31.926258) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014631 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:31.976899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:31.977213) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev cc8350ff1a9ba2e9754d9d2d98063e864f38c655 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev cc8350ff1a9ba2e9754d9d2d98063e864f38c655 --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=edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 01:21:32,128 truncating revision to first 12 chars 2016-08-16 01:21:32,128 Setting DEBUG logging. 2016-08-16 01:21:32,129 attempt 1/10 2016-08-16 01:21:32,129 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cc8350ff1a9b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 01:21:32,494 unpacking tar archive at: fx-team-cc8350ff1a9b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.797978 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:32.793003) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:32.793300) ========= script_repo_revision: cc8350ff1a9ba2e9754d9d2d98063e864f38c655 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:32.793706) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:32.794095) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 01:21:32.806293) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-16 01:21:32.806737) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:21:33 INFO - MultiFileLogger online at 20160816 01:21:33 in /builds/slave/test 01:21:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 01:21:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:21:33 INFO - {'append_to_log': False, 01:21:33 INFO - 'base_work_dir': '/builds/slave/test', 01:21:33 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 01:21:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:21:33 INFO - 'branch': 'Fx-Team-Non-PGO', 01:21:33 INFO - 'buildbot_json_path': 'buildprops.json', 01:21:33 INFO - 'config_files': ('talos/linux_config.py',), 01:21:33 INFO - 'default_actions': ('clobber', 01:21:33 INFO - 'read-buildbot-config', 01:21:33 INFO - 'download-and-extract', 01:21:33 INFO - 'populate-webroot', 01:21:33 INFO - 'create-virtualenv', 01:21:33 INFO - 'install', 01:21:33 INFO - 'run-tests'), 01:21:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:21:33 INFO - 'download_minidump_stackwalk': True, 01:21:33 INFO - 'download_symbols': 'ondemand', 01:21:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:21:33 INFO - 'tooltool.py': '/tools/tooltool.py', 01:21:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:21:33 INFO - '/tools/misc-python/virtualenv.py')}, 01:21:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:21:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:21:33 INFO - 'installer_path': 'installer.exe', 01:21:33 INFO - 'log_level': 'info', 01:21:33 INFO - 'log_name': 'talos', 01:21:33 INFO - 'log_to_console': True, 01:21:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:21:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:21:33 INFO - 'opt_config_files': (), 01:21:33 INFO - 'pip_index': False, 01:21:33 INFO - 'sps_profile': False, 01:21:33 INFO - 'sps_profile_interval': 0, 01:21:33 INFO - 'suite': 'dromaeojs', 01:21:33 INFO - 'system_bits': '32', 01:21:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:21:33 INFO - 'title': 'talos-linux64-ix-033', 01:21:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:21:33 INFO - 'use_talos_json': True, 01:21:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:21:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:21:33 INFO - 'work_dir': 'build'} 01:21:33 INFO - [mozharness: 2016-08-16 08:21:33.054035Z] Running clobber step. 01:21:33 INFO - Running pre-action listener: _resource_record_pre_action 01:21:33 INFO - Running main action method: clobber 01:21:33 INFO - rmtree: /builds/slave/test/build 01:21:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:21:33 INFO - Running post-action listener: _resource_record_post_action 01:21:33 INFO - [mozharness: 2016-08-16 08:21:33.509157Z] Finished clobber step (success) 01:21:33 INFO - [mozharness: 2016-08-16 08:21:33.509470Z] Running read-buildbot-config step. 01:21:33 INFO - Running pre-action listener: _resource_record_pre_action 01:21:33 INFO - Running main action method: read_buildbot_config 01:21:33 INFO - Using buildbot properties: 01:21:33 INFO - { 01:21:33 INFO - "project": "", 01:21:33 INFO - "product": "firefox", 01:21:33 INFO - "script_repo_revision": "production", 01:21:33 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 01:21:33 INFO - "repository": "", 01:21:33 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 01:21:33 INFO - "buildid": "20160815234825", 01:21:33 INFO - "pgo_build": "False", 01:21:33 INFO - "basedir": "/builds/slave/test", 01:21:33 INFO - "buildnumber": 3234, 01:21:33 INFO - "slavename": "talos-linux64-ix-033", 01:21:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:21:33 INFO - "platform": "ubuntu64_hw", 01:21:33 INFO - "scheduler": "tests-fx-team-linux64-talos-7-3600", 01:21:33 INFO - "branch": "fx-team", 01:21:33 INFO - "repo_path": "integration/fx-team", 01:21:33 INFO - "revision": "cc8350ff1a9ba2e9754d9d2d98063e864f38c655", 01:21:33 INFO - "stage_platform": "linux64", 01:21:33 INFO - "builduid": "37c6dc186a564643b5b4d6bb1350f1cb", 01:21:33 INFO - "slavebuilddir": "test" 01:21:33 INFO - } 01:21:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 01:21:33 INFO - Running post-action listener: _resource_record_post_action 01:21:33 INFO - [mozharness: 2016-08-16 08:21:33.511885Z] Finished read-buildbot-config step (success) 01:21:33 INFO - [mozharness: 2016-08-16 08:21:33.511972Z] Running download-and-extract step. 01:21:33 INFO - Running pre-action listener: _resource_record_pre_action 01:21:33 INFO - Running main action method: download_and_extract 01:21:33 INFO - mkdir: /builds/slave/test/build/tests 01:21:33 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:21:33 INFO - trying https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:21:33 INFO - Downloading https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/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:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/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:21:34 INFO - Downloaded 1361 bytes. 01:21:34 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:21:34 INFO - Using the following test package requirements: 01:21:34 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:21:34 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:21:34 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:21:34 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:34 INFO - u'jsshell-linux-x86_64.zip'], 01:21:34 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:21:34 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:21:34 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:21:34 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:21:34 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:21:34 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:34 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:21:34 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:21:34 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:21:34 INFO - trying https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:21:34 INFO - Downloading https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/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:21:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/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:21:35 INFO - Downloaded 20265717 bytes. 01:21:35 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:21:39 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:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:39 INFO - trying https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:21:39 INFO - Downloading https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/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:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/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:21:40 INFO - Downloaded 20265717 bytes. 01:21:40 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:21: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:21:45 INFO - trying https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 01:21:45 INFO - Downloading https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/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:21:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/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:21:46 INFO - Downloaded 14413719 bytes. 01:21:46 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:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:47 INFO - trying https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:21:47 INFO - Downloading https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/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:21:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/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:21:49 INFO - Downloaded 55627490 bytes. 01:21:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:21:49 INFO - mkdir: /builds/slave/test/properties 01:21:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:21:49 INFO - Writing to file /builds/slave/test/properties/build_url 01:21:49 INFO - Contents: 01:21:49 INFO - build_url:https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:21:50 INFO - Running post-action listener: _resource_record_post_action 01:21:50 INFO - Running post-action listener: set_extra_try_arguments 01:21:50 INFO - [mozharness: 2016-08-16 08:21:50.221572Z] Finished download-and-extract step (success) 01:21:50 INFO - [mozharness: 2016-08-16 08:21:50.221795Z] Running populate-webroot step. 01:21:50 INFO - Running pre-action listener: _resource_record_pre_action 01:21:50 INFO - Running main action method: populate_webroot 01:21:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:21:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:21:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:21:50 INFO - '--tppagecycles', 01:21:50 INFO - '7'], 01:21:50 INFO - 'tests': ['tsvgm']}}, 01:21:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:21:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:21:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:21:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:21:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:21:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:21:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['damp', 'tps']}, 01:21:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'tests': ['damp', 'tps']}, 01:21:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['dromaeo_dom']}, 01:21:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:21:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['basic_compositor_video']}, 01:21:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:21:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'other-e10s': {'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['tsvgx', 01:21:50 INFO - 'tsvgr_opacity', 01:21:50 INFO - 'tart', 01:21:50 INFO - 'tscrollx', 01:21:50 INFO - 'cart']}, 01:21:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:21:50 INFO - 'tsvgr_opacity', 01:21:50 INFO - 'tart', 01:21:50 INFO - 'tscrollx', 01:21:50 INFO - 'cart']}, 01:21:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['tp5o']}, 01:21:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'tests': ['tp5o']}, 01:21:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'talos_options': ['--disable-e10s', 01:21:50 INFO - '--xperf_path', 01:21:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:21:50 INFO - 'tests': ['tp5n']}, 01:21:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'talos_options': ['--xperf_path', 01:21:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:21:50 INFO - 'tests': ['tp5n']}}, 01:21:50 INFO - 'talos.zip': {'path': '', 01:21:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:21:50 INFO - Running post-action listener: _resource_record_post_action 01:21:50 INFO - [mozharness: 2016-08-16 08:21:50.237236Z] Finished populate-webroot step (success) 01:21:50 INFO - [mozharness: 2016-08-16 08:21:50.237343Z] Running create-virtualenv step. 01:21:50 INFO - Running pre-action listener: _resource_record_pre_action 01:21:50 INFO - Running main action method: create_virtualenv 01:21:50 INFO - Creating virtualenv /builds/slave/test/build/venv 01:21:50 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:21:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:21:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:21:50 INFO - Using real prefix '/usr' 01:21:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:21:50 INFO - Installing distribute.............................................................................................................................................................................................done. 01:21:52 INFO - Installing pip.................done. 01:21:52 INFO - Return code: 0 01:21:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:21:52 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:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:52 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:21:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:52 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 0x7ff0f24c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', '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:21:52 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:21:52 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:21:52 INFO - Using env: {'DISPLAY': ':0', 01:21:52 INFO - 'HOME': '/home/cltbld', 01:21:52 INFO - 'LANG': 'en_US.UTF-8', 01:21:52 INFO - 'LANGUAGE': 'en_US:en', 01:21:52 INFO - 'LOGNAME': 'cltbld', 01:21:52 INFO - 'MAIL': '/var/mail/cltbld', 01:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:52 INFO - 'MOZ_NO_REMOTE': '1', 01:21:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:52 INFO - 'NO_EM_RESTART': '1', 01:21:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:52 INFO - 'PWD': '/builds/slave/test', 01:21:52 INFO - 'SHELL': '/bin/bash', 01:21:52 INFO - 'SHLVL': '1', 01:21:52 INFO - 'TERM': 'linux', 01:21:52 INFO - 'TMOUT': '86400', 01:21:52 INFO - 'USER': 'cltbld', 01:21:52 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:52 INFO - '_': '/tools/buildbot/bin/python'} 01:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:53 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:21:53 INFO - Cleaning up... 01:21:53 INFO - Return code: 0 01:21:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff0f24c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', '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:21:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:21:53 INFO - Using env: {'DISPLAY': ':0', 01:21:53 INFO - 'HOME': '/home/cltbld', 01:21:53 INFO - 'LANG': 'en_US.UTF-8', 01:21:53 INFO - 'LANGUAGE': 'en_US:en', 01:21:53 INFO - 'LOGNAME': 'cltbld', 01:21:53 INFO - 'MAIL': '/var/mail/cltbld', 01:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:53 INFO - 'MOZ_NO_REMOTE': '1', 01:21:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:53 INFO - 'NO_EM_RESTART': '1', 01:21:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:53 INFO - 'PWD': '/builds/slave/test', 01:21:53 INFO - 'SHELL': '/bin/bash', 01:21:53 INFO - 'SHLVL': '1', 01:21:53 INFO - 'TERM': 'linux', 01:21:53 INFO - 'TMOUT': '86400', 01:21:53 INFO - 'USER': 'cltbld', 01:21:53 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:53 INFO - '_': '/tools/buildbot/bin/python'} 01:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:53 INFO - Downloading/unpacking psutil>=3.1.1 01:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:21:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:21:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:21:55 INFO - Installing collected packages: psutil 01:21:55 INFO - Running setup.py install for psutil 01:21:55 INFO - building 'psutil._psutil_linux' extension 01:21:55 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:21:55 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:21:55 INFO - building 'psutil._psutil_posix' extension 01:21:55 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:21:55 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:21:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:21:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:21:55 INFO - Successfully installed psutil 01:21:55 INFO - Cleaning up... 01:21:55 INFO - Return code: 0 01:21:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:55 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 0x7ff0f24c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', '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:21:55 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:21:55 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:21:55 INFO - Using env: {'DISPLAY': ':0', 01:21:55 INFO - 'HOME': '/home/cltbld', 01:21:55 INFO - 'LANG': 'en_US.UTF-8', 01:21:55 INFO - 'LANGUAGE': 'en_US:en', 01:21:55 INFO - 'LOGNAME': 'cltbld', 01:21:55 INFO - 'MAIL': '/var/mail/cltbld', 01:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:55 INFO - 'MOZ_NO_REMOTE': '1', 01:21:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:55 INFO - 'NO_EM_RESTART': '1', 01:21:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:55 INFO - 'PWD': '/builds/slave/test', 01:21:55 INFO - 'SHELL': '/bin/bash', 01:21:55 INFO - 'SHLVL': '1', 01:21:55 INFO - 'TERM': 'linux', 01:21:55 INFO - 'TMOUT': '86400', 01:21:55 INFO - 'USER': 'cltbld', 01:21:55 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:55 INFO - '_': '/tools/buildbot/bin/python'} 01:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:21:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:21:57 INFO - Installing collected packages: mozsystemmonitor 01:21:57 INFO - Running setup.py install for mozsystemmonitor 01:21:58 INFO - Successfully installed mozsystemmonitor 01:21:58 INFO - Cleaning up... 01:21:58 INFO - Return code: 0 01:21:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:21:58 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:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:58 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:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:58 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 0x7ff0f24c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', '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:21:58 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:21:58 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:21:58 INFO - Using env: {'DISPLAY': ':0', 01:21:58 INFO - 'HOME': '/home/cltbld', 01:21:58 INFO - 'LANG': 'en_US.UTF-8', 01:21:58 INFO - 'LANGUAGE': 'en_US:en', 01:21:58 INFO - 'LOGNAME': 'cltbld', 01:21:58 INFO - 'MAIL': '/var/mail/cltbld', 01:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:58 INFO - 'MOZ_NO_REMOTE': '1', 01:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:58 INFO - 'NO_EM_RESTART': '1', 01:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:58 INFO - 'PWD': '/builds/slave/test', 01:21:58 INFO - 'SHELL': '/bin/bash', 01:21:58 INFO - 'SHLVL': '1', 01:21:58 INFO - 'TERM': 'linux', 01:21:58 INFO - 'TMOUT': '86400', 01:21:58 INFO - 'USER': 'cltbld', 01:21:58 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:58 INFO - '_': '/tools/buildbot/bin/python'} 01:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:58 INFO - Downloading/unpacking jsonschema==2.5.1 01:21:58 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:21:58 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:21:58 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:21:58 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:21:58 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:21:58 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:22:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:22:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:22:00 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:22:00 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:22:00 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:22:00 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:22:00 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:22:00 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:22:00 INFO - Downloading functools32-3.2.3-2.tar.gz 01:22:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:22:00 INFO - warning: no files found matching '*.txt' 01:22:00 INFO - no previously-included directories found matching 'build' 01:22:00 INFO - no previously-included directories found matching 'dist' 01:22:00 INFO - no previously-included directories found matching '.git*' 01:22:00 INFO - Installing collected packages: jsonschema, functools32 01:22:00 INFO - Running setup.py install for functools32 01:22:00 INFO - warning: no files found matching '*.txt' 01:22:00 INFO - no previously-included directories found matching 'build' 01:22:00 INFO - no previously-included directories found matching 'dist' 01:22:00 INFO - no previously-included directories found matching '.git*' 01:22:00 INFO - Successfully installed jsonschema functools32 01:22:00 INFO - Cleaning up... 01:22:00 INFO - Return code: 0 01:22:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:22:00 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:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:00 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:22:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:00 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 0x7ff0f24c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', '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:22:00 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:22:00 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:22:00 INFO - Using env: {'DISPLAY': ':0', 01:22:00 INFO - 'HOME': '/home/cltbld', 01:22:00 INFO - 'LANG': 'en_US.UTF-8', 01:22:00 INFO - 'LANGUAGE': 'en_US:en', 01:22:00 INFO - 'LOGNAME': 'cltbld', 01:22:00 INFO - 'MAIL': '/var/mail/cltbld', 01:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:00 INFO - 'MOZ_NO_REMOTE': '1', 01:22:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:00 INFO - 'NO_EM_RESTART': '1', 01:22:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:00 INFO - 'PWD': '/builds/slave/test', 01:22:00 INFO - 'SHELL': '/bin/bash', 01:22:00 INFO - 'SHLVL': '1', 01:22:00 INFO - 'TERM': 'linux', 01:22:00 INFO - 'TMOUT': '86400', 01:22:00 INFO - 'USER': 'cltbld', 01:22:00 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:22:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:00 INFO - '_': '/tools/buildbot/bin/python'} 01:22:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:22:00 INFO - Cleaning up... 01:22:00 INFO - Return code: 0 01:22:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:22:00 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:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:00 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:22:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:00 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 0x7ff0f24c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', '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:22:00 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:22:00 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:22:00 INFO - Using env: {'DISPLAY': ':0', 01:22:00 INFO - 'HOME': '/home/cltbld', 01:22:00 INFO - 'LANG': 'en_US.UTF-8', 01:22:00 INFO - 'LANGUAGE': 'en_US:en', 01:22:00 INFO - 'LOGNAME': 'cltbld', 01:22:00 INFO - 'MAIL': '/var/mail/cltbld', 01:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:00 INFO - 'MOZ_NO_REMOTE': '1', 01:22:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:00 INFO - 'NO_EM_RESTART': '1', 01:22:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:00 INFO - 'PWD': '/builds/slave/test', 01:22:00 INFO - 'SHELL': '/bin/bash', 01:22:00 INFO - 'SHLVL': '1', 01:22:00 INFO - 'TERM': 'linux', 01:22:00 INFO - 'TMOUT': '86400', 01:22:00 INFO - 'USER': 'cltbld', 01:22:00 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:22:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:00 INFO - '_': '/tools/buildbot/bin/python'} 01:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:01 INFO - Downloading/unpacking blobuploader==1.2.4 01:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:03 INFO - Downloading blobuploader-1.2.4.tar.gz 01:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:22:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:22:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:03 INFO - Downloading docopt-0.6.1.tar.gz 01:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:22:03 INFO - Installing collected packages: blobuploader, requests, docopt 01:22:03 INFO - Running setup.py install for blobuploader 01:22:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:22:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:22:03 INFO - Running setup.py install for requests 01:22:04 INFO - Running setup.py install for docopt 01:22:04 INFO - Successfully installed blobuploader requests docopt 01:22:04 INFO - Cleaning up... 01:22:04 INFO - Return code: 0 01:22:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22: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:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22: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:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:04 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 0x7ff0f24c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', '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:22:04 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:22:04 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:22:04 INFO - Using env: {'DISPLAY': ':0', 01:22:04 INFO - 'HOME': '/home/cltbld', 01:22:04 INFO - 'LANG': 'en_US.UTF-8', 01:22:04 INFO - 'LANGUAGE': 'en_US:en', 01:22:04 INFO - 'LOGNAME': 'cltbld', 01:22:04 INFO - 'MAIL': '/var/mail/cltbld', 01:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:04 INFO - 'MOZ_NO_REMOTE': '1', 01:22:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:04 INFO - 'NO_EM_RESTART': '1', 01:22:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:04 INFO - 'PWD': '/builds/slave/test', 01:22:04 INFO - 'SHELL': '/bin/bash', 01:22:04 INFO - 'SHLVL': '1', 01:22:04 INFO - 'TERM': 'linux', 01:22:04 INFO - 'TMOUT': '86400', 01:22:04 INFO - 'USER': 'cltbld', 01:22:04 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:04 INFO - '_': '/tools/buildbot/bin/python'} 01:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:22:04 INFO - Running setup.py (path:/tmp/pip-R2YXcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:22:04 INFO - Running setup.py (path:/tmp/pip-qaOz_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:22:04 INFO - Running setup.py (path:/tmp/pip-i5odej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:22:04 INFO - Running setup.py (path:/tmp/pip-52jxvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:22:04 INFO - Running setup.py (path:/tmp/pip-kT_nP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:22:04 INFO - Running setup.py (path:/tmp/pip-h6Io4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:22:05 INFO - Running setup.py (path:/tmp/pip-UZeuHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:22:05 INFO - Running setup.py (path:/tmp/pip-_fkAkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:22:05 INFO - Running setup.py (path:/tmp/pip-mPUM5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:22:05 INFO - Running setup.py (path:/tmp/pip-eUXdnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:22:05 INFO - Running setup.py (path:/tmp/pip-ApZa9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:22:05 INFO - Running setup.py (path:/tmp/pip-nEyyvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:22:05 INFO - Running setup.py (path:/tmp/pip-U4DdPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:22:05 INFO - Running setup.py (path:/tmp/pip-GrLoUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:05 INFO - Running setup.py (path:/tmp/pip-1BwpA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:22:05 INFO - Running setup.py (path:/tmp/pip-fUPtD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:22:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:22:05 INFO - Running setup.py (path:/tmp/pip-5p8BQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:22:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:22:05 INFO - Running setup.py install for manifestparser 01:22:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:22:05 INFO - Running setup.py install for mozcrash 01:22:06 INFO - Running setup.py install for mozdebug 01:22:06 INFO - Running setup.py install for mozdevice 01:22:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:22:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:22:06 INFO - Running setup.py install for mozfile 01:22:06 INFO - Running setup.py install for mozhttpd 01:22:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:22:06 INFO - Running setup.py install for mozinfo 01:22:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:22:06 INFO - Running setup.py install for mozInstall 01:22:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:22:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:22:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:22:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:22:06 INFO - Running setup.py install for mozleak 01:22:06 INFO - Running setup.py install for mozlog 01:22:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:22:06 INFO - Running setup.py install for moznetwork 01:22:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:22:06 INFO - Running setup.py install for mozprocess 01:22:07 INFO - Running setup.py install for mozprofile 01:22:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:22:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:22:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:22:07 INFO - Running setup.py install for mozrunner 01:22:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:22:07 INFO - Running setup.py install for mozscreenshot 01:22:07 INFO - Running setup.py install for moztest 01:22:07 INFO - Running setup.py install for mozversion 01:22:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:22:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:22:07 INFO - Cleaning up... 01:22:07 INFO - Return code: 0 01:22:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22: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:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22: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:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:07 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 0x7ff0f24c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', '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:22:07 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:22:07 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:22:07 INFO - Using env: {'DISPLAY': ':0', 01:22:07 INFO - 'HOME': '/home/cltbld', 01:22:07 INFO - 'LANG': 'en_US.UTF-8', 01:22:07 INFO - 'LANGUAGE': 'en_US:en', 01:22:07 INFO - 'LOGNAME': 'cltbld', 01:22:07 INFO - 'MAIL': '/var/mail/cltbld', 01:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:07 INFO - 'MOZ_NO_REMOTE': '1', 01:22:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:07 INFO - 'NO_EM_RESTART': '1', 01:22:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:07 INFO - 'PWD': '/builds/slave/test', 01:22:07 INFO - 'SHELL': '/bin/bash', 01:22:07 INFO - 'SHLVL': '1', 01:22:07 INFO - 'TERM': 'linux', 01:22:07 INFO - 'TMOUT': '86400', 01:22:07 INFO - 'USER': 'cltbld', 01:22:07 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:07 INFO - '_': '/tools/buildbot/bin/python'} 01:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:22:07 INFO - Running setup.py (path:/tmp/pip-C_5Wmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:22:07 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:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:22:07 INFO - Running setup.py (path:/tmp/pip-JRrtPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:22:08 INFO - Running setup.py (path:/tmp/pip-RPwSyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:22:08 INFO - Running setup.py (path:/tmp/pip-3ZJ9jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:22:08 INFO - Running setup.py (path:/tmp/pip-pG_Kcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:22:08 INFO - Running setup.py (path:/tmp/pip-o4KUfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:22:08 INFO - Running setup.py (path:/tmp/pip-z745S2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:22:08 INFO - Running setup.py (path:/tmp/pip-eLtQ5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:22:08 INFO - Running setup.py (path:/tmp/pip-LqYdqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:22:08 INFO - Running setup.py (path:/tmp/pip-CQ89eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:22:08 INFO - Running setup.py (path:/tmp/pip-kqu0gW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:22:08 INFO - Running setup.py (path:/tmp/pip-Q1a1yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:22:08 INFO - Running setup.py (path:/tmp/pip-hWL9CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:22:08 INFO - Running setup.py (path:/tmp/pip-DNabwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:08 INFO - Running setup.py (path:/tmp/pip-5_mqhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:08 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:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:22:08 INFO - Running setup.py (path:/tmp/pip-9LA5ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:22:09 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:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:22:09 INFO - Running setup.py (path:/tmp/pip-JFxWNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:22:09 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:22:09 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:22:09 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:22:09 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:22:09 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:22:09 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:22:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:22: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:22: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:22: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:22: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:22: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:22: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:22:11 INFO - Downloading blessings-1.6.tar.gz 01:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:22:11 INFO - Installing collected packages: blessings 01:22:11 INFO - Running setup.py install for blessings 01:22:11 INFO - Successfully installed blessings 01:22:11 INFO - Cleaning up... 01:22:11 INFO - Return code: 0 01:22:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:22:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:22:11 INFO - Reading from file tmpfile_stdout 01:22:11 INFO - Current package versions: 01:22:11 INFO - argparse == 1.2.1 01:22:11 INFO - blessings == 1.6 01:22:11 INFO - blobuploader == 1.2.4 01:22:11 INFO - docopt == 0.6.1 01:22:11 INFO - functools32 == 3.2.3-2 01:22:11 INFO - jsonschema == 2.5.1 01:22:11 INFO - manifestparser == 1.1 01:22:11 INFO - mozInstall == 1.12 01:22:11 INFO - mozcrash == 0.17 01:22:11 INFO - mozdebug == 0.1 01:22:11 INFO - mozdevice == 0.48 01:22:11 INFO - mozfile == 1.2 01:22:11 INFO - mozhttpd == 0.7 01:22:11 INFO - mozinfo == 0.9 01:22:11 INFO - mozleak == 0.1 01:22:11 INFO - mozlog == 3.3 01:22:11 INFO - moznetwork == 0.27 01:22:11 INFO - mozprocess == 0.23 01:22:11 INFO - mozprofile == 0.28 01:22:11 INFO - mozrunner == 6.12 01:22:11 INFO - mozscreenshot == 0.1 01:22:11 INFO - mozsystemmonitor == 0.3 01:22:11 INFO - moztest == 0.7 01:22:11 INFO - mozversion == 1.4 01:22:11 INFO - psutil == 3.1.1 01:22:11 INFO - requests == 1.2.3 01:22:11 INFO - wsgiref == 0.1.2 01:22:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:11 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 0x7ff0f24c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', '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:22:11 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:22:11 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:22:11 INFO - Using env: {'DISPLAY': ':0', 01:22:11 INFO - 'HOME': '/home/cltbld', 01:22:11 INFO - 'LANG': 'en_US.UTF-8', 01:22:11 INFO - 'LANGUAGE': 'en_US:en', 01:22:11 INFO - 'LOGNAME': 'cltbld', 01:22:11 INFO - 'MAIL': '/var/mail/cltbld', 01:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:11 INFO - 'MOZ_NO_REMOTE': '1', 01:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:11 INFO - 'NO_EM_RESTART': '1', 01:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:11 INFO - 'PWD': '/builds/slave/test', 01:22:11 INFO - 'SHELL': '/bin/bash', 01:22:11 INFO - 'SHLVL': '1', 01:22:11 INFO - 'TERM': 'linux', 01:22:11 INFO - 'TMOUT': '86400', 01:22:11 INFO - 'USER': 'cltbld', 01:22:11 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:11 INFO - '_': '/tools/buildbot/bin/python'} 01:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:11 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:22:11 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:22:11 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:22:11 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:22:11 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:22:11 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:22:11 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:22:11 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:22:11 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:22:11 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:22:11 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:22:11 INFO - Cleaning up... 01:22:11 INFO - Return code: 0 01:22:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:11 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 0x7ff0f24c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', '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:22:11 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:22:11 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:22:11 INFO - Using env: {'DISPLAY': ':0', 01:22:11 INFO - 'HOME': '/home/cltbld', 01:22:11 INFO - 'LANG': 'en_US.UTF-8', 01:22:11 INFO - 'LANGUAGE': 'en_US:en', 01:22:11 INFO - 'LOGNAME': 'cltbld', 01:22:11 INFO - 'MAIL': '/var/mail/cltbld', 01:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:11 INFO - 'MOZ_NO_REMOTE': '1', 01:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:11 INFO - 'NO_EM_RESTART': '1', 01:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:11 INFO - 'PWD': '/builds/slave/test', 01:22:11 INFO - 'SHELL': '/bin/bash', 01:22:11 INFO - 'SHLVL': '1', 01:22:11 INFO - 'TERM': 'linux', 01:22:11 INFO - 'TMOUT': '86400', 01:22:11 INFO - 'USER': 'cltbld', 01:22:11 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:11 INFO - '_': '/tools/buildbot/bin/python'} 01:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:22:11 INFO - Cleaning up... 01:22:11 INFO - Return code: 0 01:22:11 INFO - Running post-action listener: _resource_record_post_action 01:22:11 INFO - Running post-action listener: _start_resource_monitoring 01:22:11 INFO - Starting resource monitoring. 01:22:11 INFO - [mozharness: 2016-08-16 08:22:11.998319Z] Finished create-virtualenv step (success) 01:22:11 INFO - [mozharness: 2016-08-16 08:22:11.998794Z] Running install step. 01:22:11 INFO - Running pre-action listener: _resource_record_pre_action 01:22:11 INFO - Running main action method: install 01:22:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:22:12 INFO - Reading from file tmpfile_stdout 01:22:12 INFO - Detecting whether we're running mozinstall >=1.0... 01:22:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:22:12 INFO - Reading from file tmpfile_stdout 01:22:12 INFO - Output received: 01:22:12 INFO - Usage: mozinstall [options] installer 01:22:12 INFO - Options: 01:22:12 INFO - -h, --help show this help message and exit 01:22:12 INFO - -d DEST, --destination=DEST 01:22:12 INFO - Directory to install application into. [default: 01:22:12 INFO - "/builds/slave/test"] 01:22:12 INFO - --app=APP Application being installed. [default: firefox] 01:22:12 INFO - mkdir: /builds/slave/test/build/application 01:22:12 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:22:12 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:22:28 INFO - Reading from file tmpfile_stdout 01:22:28 INFO - Output received: 01:22:28 INFO - /builds/slave/test/build/application/firefox/firefox 01:22:28 INFO - Running post-action listener: _resource_record_post_action 01:22:28 INFO - [mozharness: 2016-08-16 08:22:28.319381Z] Finished install step (success) 01:22:28 INFO - [mozharness: 2016-08-16 08:22:28.319631Z] Running run-tests step. 01:22:28 INFO - Running pre-action listener: _resource_record_pre_action 01:22:28 INFO - Running main action method: run_tests 01:22:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:22:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:22:28 INFO - Python 2.7.3 01:22:28 INFO - Return code: 0 01:22:28 INFO - grabbing minidump binary from tooltool 01:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:28 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 0x1ae4090>, '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 0x1ae79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae7e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:22:28 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:22:28 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:22:28 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:22:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:22:28 INFO - Return code: 0 01:22:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:22:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:22:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:22:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:22:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:22:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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:22:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --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:22:28 INFO - Using env: {'DISPLAY': ':0', 01:22:28 INFO - 'HOME': '/home/cltbld', 01:22:28 INFO - 'LANG': 'en_US.UTF-8', 01:22:28 INFO - 'LANGUAGE': 'en_US:en', 01:22:28 INFO - 'LOGNAME': 'cltbld', 01:22:28 INFO - 'MAIL': '/var/mail/cltbld', 01:22:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:22:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:22:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:28 INFO - 'MOZ_NO_REMOTE': '1', 01:22:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:22:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:28 INFO - 'NO_EM_RESTART': '1', 01:22:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:28 INFO - 'PWD': '/builds/slave/test', 01:22:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:22:28 INFO - 'SHELL': '/bin/bash', 01:22:28 INFO - 'SHLVL': '1', 01:22:28 INFO - 'TERM': 'linux', 01:22:28 INFO - 'TMOUT': '86400', 01:22:28 INFO - 'USER': 'cltbld', 01:22:28 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809', 01:22:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:28 INFO - '_': '/tools/buildbot/bin/python'} 01:22:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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:22:28 INFO - mozversion application_buildid: 20160815234825 01:22:28 INFO - mozversion application_changeset: cc8350ff1a9ba2e9754d9d2d98063e864f38c655 01:22:28 INFO - mozversion application_display_name: Nightly 01:22:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:22:28 INFO - mozversion application_name: Firefox 01:22:28 INFO - mozversion application_remotingname: firefox 01:22:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:22:28 INFO - mozversion application_vendor: Mozilla 01:22:28 INFO - mozversion application_version: 51.0a1 01:22:28 INFO - mozversion platform_buildid: 20160815234825 01:22:28 INFO - mozversion platform_changeset: cc8350ff1a9ba2e9754d9d2d98063e864f38c655 01:22:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:22:28 INFO - mozversion platform_version: 51.0a1 01:22:28 INFO - using testdate: 1471335748 01:22:28 INFO - actual date: 1471335748 01:22:28 INFO - starting webserver on 'localhost:51025' 01:22:28 INFO - SUITE-START | Running 2 tests 01:22:28 INFO - TEST-START | dromaeo_css 01:22:28 INFO - operating with platform_type : linux_ 01:22:28 INFO - Initialising browser for dromaeo_css test... 01:22:28 INFO - TEST-INFO | started process 12531 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRyQcS4/profile http://localhost:51025/getInfo.html) 01:22:33 INFO - PROCESS | 12531 | __metrics Screen width/height:1600/1200 01:22:33 INFO - PROCESS | 12531 | colorDepth:24 01:22:33 INFO - PROCESS | 12531 | Browser inner width/height: 1024/697 01:22:33 INFO - PROCESS | 12531 | __metrics 01:22:34 INFO - PROCESS | 12531 | 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:22:34 INFO - PROCESS | 12531 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:22:34 INFO - PROCESS | 12531 | console.error: PushService: 01:22:34 INFO - PROCESS | 12531 | stateChangeProcessEnqueue: Error transitioning state 01:22:34 INFO - PROCESS | 12531 | UnknownError 01:22:35 INFO - TEST-INFO | 12531: exit 0 01:22:35 INFO - Browser initialized. 01:22:35 INFO - Running cycle 1/1 for dromaeo_css test... 01:22:35 INFO - TEST-INFO | started process 12612 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRyQcS4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:22:47 INFO - PROCESS | 12612 | RSS: Main: 157564928 01:22:47 INFO - PROCESS | 12612 | 01:23:37 INFO - PROCESS | 12612 | 1471335817954 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:25:51 INFO - PROCESS | 12612 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51025/tests/dromaeo/cssquery-ext.html) 01:25:52 INFO - PROCESS | 12612 | RSS: Main: 226770944 01:25:52 INFO - PROCESS | 12612 | 01:28:54 INFO - PROCESS | 12612 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-ext.html (next: http://localhost:51025/tests/dromaeo/cssquery-jquery.html) 01:28:55 INFO - PROCESS | 12612 | RSS: Main: 244174848 01:28:55 INFO - PROCESS | 12612 | 01:31:57 INFO - PROCESS | 12612 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51025/tests/dromaeo/cssquery-mootools.html) 01:31:58 INFO - PROCESS | 12612 | RSS: Main: 229318656 01:31:58 INFO - PROCESS | 12612 | 01:35:00 INFO - PROCESS | 12612 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51025/tests/dromaeo/cssquery-prototype.html) 01:35:00 INFO - PROCESS | 12612 | RSS: Main: 192634880 01:35:00 INFO - PROCESS | 12612 | 01:38:04 INFO - PROCESS | 12612 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51025/tests/dromaeo/cssquery-yui.html) 01:38:04 INFO - PROCESS | 12612 | RSS: Main: 189349888 01:38:04 INFO - PROCESS | 12612 | 01:41:07 INFO - PROCESS | 12612 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-yui.html (next: http://localhost:51025/tests/dromaeo/cssquery-dojo.html) 01:41:07 INFO - PROCESS | 12612 | RSS: Main: 275746816 01:41:07 INFO - PROCESS | 12612 | 01:41:07 INFO - PROCESS | 12612 | __start_tp_report 01:41:07 INFO - PROCESS | 12612 | _x_x_mozilla_page_load 01:41:07 INFO - PROCESS | 12612 | _x_x_mozilla_page_load_details 01:41:07 INFO - PROCESS | 12612 | |i|pagename|runs| 01:41:07 INFO - PROCESS | 12612 | |0;dojo.html;2603.5573952428085;2639.7360263973605;2643.7224091470384;2647.8940842366305;2671.4389978104596;4686.320483529886;4772.260299653551;4794.568488836004;4849.878753031172;4879.024195160969;4766.404199475066;4793.185158523051;4814.398200224972;4837.782299796509;4860.781264843082;4342.761148136859;4353.129374125172;4374.496932852715;4394;4445.866624001285;4435.733855968652;4469.106178764244;4510.639148868083;4549.272116461367;4554.931676024862;4448.8442904498515;4490.820367185309;4494.842680506183;4504.8873778155485;4545.704529205607;4301.462317210348;4350.695451318409;4373.715708478939;4438.223310920576;4447.866564003085;4332.501762297313;4338.501072376654;4341.7394956302405;4346.8913277168;4376.321670141101;4762.1428142934255;4803.519648035169;4804.183288840905;4812.711237325771;4835.032993401333;5028.371453568304;5104.566111880495;5151.639385243035;5160.200168973815;5186.196139598368;5215.269862219292;5242.501962313574;5274.129768588178;5277;5290.523852853262;4852.8301509447;4863.659543831898;4879.902401951977;4905.803767849317;4931.8520444386;5116.386033675983;5209.895802083937;5247.9737601311745;5268.841934741964;5330.360356757214;5043.899122017612;5065;5116.411612664517;5122.487751224848;5123.077845987684;5138.409082955433;5157.535821776059;5197.116490196676;5227.45111763267;5232.084385232569;4923.8276660316715;4958;5052.065367906949;5094.108531007059;5096.592272618182;5115;5116.74416279192;5125.359330083739;5127.435982041972;5162.6128040397125;4341.305391137403;4343.370211319341;4343.739375637472;4372.519022907478;4388.978055109704;3701.03773019017;3717.312297225022;3727.142757165813;3747.138158223624;3772.1701225730294;4313.633341166036;4410.735355878657;4447.221736196153;4448.288273876229;4451.176532341462;5158.690478571298;5160.587153027749;5160.716160611202;5161.122609156452;5213.348331458567;4366.934495982563;4407.493138289138;4408.360787685768;4408.603225709701;4409.4488188976375;4492.258777301774;4557.863264102082;4584.931226031612;4594.540545945379;4656.045510670305;4838.540338667821;4859.222524396079;4860.465348811557;4877.87805304856;4908.337374454403;5136.691798492103;5155.690658560498;5160.638755287094;5162.6386152968935;5206.271122042841;4701.553352431513;4754.5245475452175;4789.44921334051;4793.496682848251;4866.732329721899;4981.978694367718;5005.099082165245;5007;5019.67372120811;5038.345015148007;5080.923786143285;5091.643584949166;5102.030614183293;5107.438181799925;5113.7187454688865;4860.586850117638;4967.925481117713;4985.401751789664;5006.424261210008;5059.924101138412;5005.1991681332265;5018.272350509083;5019.071471777586;5035.899282014412;5066.518680725325;4936.851894443173;4944.5549900507685;4995.950040499549;5005.174146265826;5005.7997680092385;4876.244182151772;4981.302617633607;4994.051130285234;5005.649604527648;5091.847244582668;4930.5069493050405;4937.28409380645;4943.036107958914;4944.8763780905765;4991.575716064175 01:41:07 INFO - PROCESS | 12612 | |1;ext.html;563.788579282769;565.3865555871879;569.3708452160361;574.8275517344796;575.1889835332182;27853.582196267045;38037.61962380375;39179;39435.408468872956;43764.562354376445;40179.79910100453;40363;40409.19181616366;40519.79740101303;40586;7653.349465295457;7810.1408845027;7812.218778122199;7814.062312522506;7816.570088645122;7641.1976742442075;7727.536347819121;7728.0726312842535;7729.227077292254;7736.110347310047;7731.110922243957;7755.883661745078;7759.8060048498955;7760.922390776076;7762.611869406507;7697.576633285167;7720.691172353099;7726.227377262285;7738.458307918448;7739.57432341221;7683.538987660758;7702;7707.730229441942;7720.034995625547;7749.108852481981;38205.23589528194;38569.421458678145;38574.42138367927;38628;38652.61347386518;3084.182691586709;3087.3516561521883;3160.841957902119;3162.3201011782576;3340.3486320167576;29007.70992290071;29408.82364705409;29614.930923486118;29722.25694357637;29859.25351866199;3256.0983490835924;3373.527706121145;3407.4377727674905;3410.4543273076188;3414.6243913169533;240937;242931;243004;243837;244904;51687.74156129195;51941.871335089294;52070.47929520732;52112.21831672528;52177.73911130496;54536.454635453934;54748.72625636846;54799;54879;54937.450625494035;12740.745185096432;12800;12863.29251891155;12879.291638959947;12929.482820687255;10069.748256293506;10183.490825458697;10190.694279171637;10200.897991019994;10215.489225538693;16476.176191190392;16632.916835415745;16756.58108547296;16781.160941952854;16805.83194168067;5861.970690146607;5888.381719919433;5894.839892312543;5928.525717942641;5949.762009519571;740.4705635470557;786.9370450364072;855.6491838346249;923.8060007398387;969.8943204746595;25412.491750165264;28274.2931426716;28624.713752862204;29196.708032919825;31853.203669907973;933.2580598424269;945.3193700535603;969.1955676788249;971.1502435369052;971.4268581536927;14591.41634334636;15185.696286074217;15215.61960950963;15360.539183824503;15387.461438849587;350489;351432.18555165076;351693;352297;352416;47645.5235447648;47851;47881.52118478771;47884.5211547887;47971;1177.7406053061482;1207.93356365399;1210.8668046515052;1223.1988047828686;1228.6744012836605;26180.476390472464;27366.726332736416;27978;28124.156275311772;28409.28976775597;43142.56857431385;43360.349594756735;43380.566194337654;43533.78233108814;43579.782101089295;304952;305219;305899;305964;306295;24626.50746985086;24996.62505062389;25171.87414062918;25345.366365841;25358.239252822445;40342.59657403389;41467.170656586095;41633.58366416418;41665.37501937534;41842.372364413946;34705.65294347024;35232;35470.29059418849;35568.28863422665;35802.28395432025;34919.476207856394;35136.64863351334;35166.82416587901;35341.64658353384;35426;27829.582556261266;29136;35259.47110793289;35514.8224258877;35576.110597235274;30324.69675303219;32135.19662008375;32502.51246231261;32724.34551309008;32859.50710739293;24833.379165521008;24872.875635621705;25260.494790103727;25296;25347 01:41:07 INFO - PROCESS | 12612 | |2;jquery.html;361.6401680328074;365.4135113143405;366.1834109934844;371.2834229936222;384.9403342481916;16384;16836.91581542092;17741.37905173319;17839.73240401395;17867.285308587674;15755.921220393911;15839.762403563956;16371.59071023225;16486.175691215452;16536.255868485918;4880.072786170634;5113.872153196163;5114.1050316194705;5114.769835357418;5117.976404719053;3752.605976372469;3874.554426240976;3895.552011518669;3941.5664276929515;5025.748712564357;3894.9610503894883;5970.373110823366;6063.393660633923;6090.3909609038965;6107.3587273336325;5773.134029895549;5933.792317268896;6005.249343832021;6076.756929722806;6081.878362432749;5265.736713164327;5266.789328426858;5267.499587539216;5269.209618557209;5275.261463395128;19432;20096.39710808678;20118.29585964499;20981.16075356984;21259.893700531553;1826.3425166939892;1884.0579710144928;1902.0869982408522;2183.879886606236;2238.227811405079;8550.700725474579;8812.07473215316;9100.317476189311;9136.908630913673;9320.067993200693;1659.5602165426171;1687.3588036546034;1743.4856717268435;1751.649670065979;1775.8579313654877;67663.66168169127;69643.30356696469;71333.2866671337;71853.64073179601;73360.26639733587;10132.2907396483;10212.948935255276;10402.5838966442;10467.162626989822;10490.842637360445;14078.929605351908;14092.788608170886;14096.647583810487;14099.506517271846;14121.505747298796;6759.898601520981;6845.93887947369;6872.244053154149;7068.681909314093;7083.716651333992;6355.586886852339;6461.321561236096;6588.571742836796;6603.108580341688;6615.305392933769;4308.246056940023;4449.911001780011;4453.1761624099645;4455.420795296591;4593.081383723202;1765.7439671247598;1817.2063309123691;1839.2367167625553;1861.1345724238295;1882.6422979634099;740.9143261718245;799.2447137455049;889.2308153447221;894.2622336572224;942.783159873233;16727.247273872705;16895.746563801553;17188.656226875642;17453.301867925395;17517.649647006987;826.5687029685408;838.5639467476877;839.3075712537182;884.1688812516214;885.7781204895562;9253.259739220846;9428.622855085858;9721.416714997124;10005.445888354247;10031.548580313904;354585;359697;366251;370514;370614;74910;76662;77637.61181194057;78094.609526952;79607;870.4646642315057;882.1090698394539;910.4309806371018;911.7447114807865;912.8950170730318;15323.46367877119;15343;15720.528384148494;16023.839761602701;16093.75859362087;37630.81184594169;38254.42618360671;38772.80613596914;38952.6104738949;38984.415233770946;51056.74471627618;51435.48564514307;51617.741911290206;52017;52695.47304526906;7470.663820127998;7509.549427034395;7683.423743219169;7707.576083315472;8185.467944583583;8878.467291962503;11181.776364472827;11236.83144752813;11896;12226.266424014588;4954.801807927643;10124.64563740295;10240.385576865412;10946.726331841768;11246.7750644985;9689.757756056155;9833.557489912828;10046.397216167054;10155.847662284923;10592;10497.737556561147;10517.9474102629;10550.103241224582;10806.891931080589;10932.289401188897;7556.546607203585;7602.087749470099;7625.8856117157175;7810.984572005603;7891.64487598071;10051.889103288391;10093.293469457067;10267.794644106927;10308.742281443025;10653.467326633483 01:41:07 INFO - PROCESS | 12612 | |3;mootools.html;1229.2194456520108;1360.8026836108766;1381.7581923163443;1454.7599646058402;1658.4610001749427;6672.232693240273;7435.070616172979;7698.384129269659;7749.767506974786;7991.92008079919;7807.492512986666;7862.96068519658;7890.36877049836;7894.763157105296;7901.367890568755;2186.387811412803;2201.8348623853194;2208.9668654970187;2209.7569267380577;2213.513027134028;2117.4706323419146;2164.7402311722535;2171.3051823416513;2191.6931629571873;2195.056125865877;2147.7315335583053;2154.956900861982;2166.6533354663256;2168.078566609193;2181.6618424144285;2114.1860383752214;2118.629239883022;2121.406006318218;2122.1193204819983;2123.1719629344566;2066.9896650516803;2137.7434707835005;2146.2059038155826;2146.3453646637768;2147.2806609785584;7614.695412183506;7649;7786.1435242123325;7789.9221007789765;7815.804604884866;4378.8905277368;4551.840685575989;4560.384348113029;4562.6121779648765;4572.314152877062;2860.7854410919263;2992.4912764830033;3057.755379569629;3059.2963618367676;3061.0816754973644;920.093707697917;923.8290916180528;933.2580598424269;942.1755963531909;942.2320808541085;10108.949455252676;10145.594176232942;10197.592096316139;10215.846762298646;10254.53854576546;3999.1801680655394;4029.2545879012478;4072.205919845661;4122.9381559276635;4125.834966601363;8164.183581641907;8187.631556579968;8197.221263980031;8202.179782021869;8238;4357.8256869724855;4364.60718535327;4371.628411584988;4375.584319489643;4380;3536.2220311531423;3832.770033797981;3978.8607398740905;4034.2738307104414;4037.253108174997;3415.7438192135687;3444.5522082129164;3526.5944416392445;3560.181158333546;3564.3584154852374;1004.6584161385164;1026.9383836969805;1043.634727845248;1048.2295512798087;1048.9423081730579;759.5480688990042;768.7040489411505;768.9692412303446;773.3156156801272;804.4489524675616;4956.355673762388;5092.541671249605;5096.770645320969;5119.641625086283;5486.286782718221;412.6451251923344;419.61605131305123;420.49960546949035;423.18325631530826;429.68632897984645;3243.189202699325;3272.9018129456153;3276.6559511251453;3287.1946373138258;3400.6769356911054;9414.529273536296;9725.319227653996;9784.951075244579;9822.066903644143;9850.655227067018;9750.707478775648;9768.169705575106;9781.119699227103;9785.804283914424;9797.853032204523;7405.111386633638;7454.552726836408;7465.06686664167;7473.290037446434;7479.027726395642;4999.200127979506;5002.624803139852;5104.923426148536;5169.689818610896;5315.574754019746;9014.368994170345;9040.773980650536;9051.864222036544;9105.408148470327;9170.174684278447;8859.689910853089;9048.276137909083;9092.590833412645;9186.448813071238;9191.448513089235;6533.150690410216;6592.17597800275;6624.9337506624315;6791.830204244933;6873.2783057778215;7654.425918056057;7711.11322197932;7736.613169341398;7743.109542402696;7749.108852482037;6729.0915726376315;6740.359665831738;6795.490338224554;6849.041134241111;7100.893486597602;6719.966400167968;6759.357861003196;6789.11741473625;6824.146981627297;6847.589144651337;6071.332153463204;6093.421124993119;6119.510439164945;6149.139120523219;6339.841503962438;4208.452901122834;4270;4369.235383807884;4538.092381523643;4647.140279048387;4491.101779644019;4626.3523106764405;4669.929951050669;4726.952730472652;4898.975505122594 01:41:07 INFO - PROCESS | 12612 | |4;prototype.html;297.40370557033157;300.79095028954873;306.3918122526784;308.37246203974917;311.53736700999013;4801.255805350172;4823.903521929559;4831.227003679412;4843.467218605955;4845.1763200255955;4523.027549076956;4625.90748185037;4686.90626187476;4748.884012257116;4803.855884323467;3797.126660868002;3797.8480860765535;3807.143392736626;3808.16220431506;3823.541175058997;3788.90527736806;3807.58116607173;3817.675497582709;3822.7132965027595;3827.3302172119907;3622.456631505269;3622.5834029086727;3736.8131593420358;3774.3394905891496;3804.5434547854156;3749.1189570450924;3754.3242216401036;3756.981215093907;3760.4547340635454;3763.9059023524496;3339.833008349597;3340.0981734931433;3341.431956567365;3342.88299909502;3343.632200457948;4445.155420470101;4659.370984917026;4686.554777296152;4686.882827929295;4704.059188162381;4241.536669848897;4246.171996460692;4258;4268.9573104268875;4269.530351661315;5015.498450154992;5024.296598476216;5033.546980771748;5040.395152581714;5040.873978150539;3589.0489020409614;3590.622984586607;3595;3597.5323207983056;3604.1350075981836;4883.267509873512;5176.197689358156;5179.533841954242;5181.740912954375;5278.762455689503;3602.243528858969;3615;3626.963730362663;3627.909302267464;3631.5823680276576;3680.9263814723554;3681.4477828325967;3682.6869716074434;3689.2437050405138;3691.9630803692157;3986.963389518746;4204.747715137101;4215.367694845736;4225.936610950838;4227.239096962577;4910;4960.255961605788;4977.527134922177;4989.750512474362;5000.149974504343;1262.61490245476;1273.2742336867896;1277.7827769279245;1281.6667666406802;1283.4545318239711;802.6147449224288;823.8928939237861;854.8674955381802;873.5588527793424;884.2704768565959;4357.276702061212;4359.237133501688;4379.759113248802;4384.386185933972;4390;5063.949360506422;5095.745212739423;5098.08234517783;5103.422771500369;5111.591072714248;4265.829366825292;4356.215881141362;4375.606195442426;4382.408374869416;4409.582573505559;2709.7832173426077;2726.6046423268517;2735.849528275924;2737.219892330716;2740.287525243411;4841.2496063110275;4857.198562237262;4860.003699241718;4860.562549370574;4881.780319885614;5079.1365467870555;5118.206677964921;5168;5220.738963051832;5246.055709972242;4785.9760701196965;4807.519248075235;4810.855674329776;4811.302361157613;4817.097246587648;5093.974530127326;5097.133487307093;5108.3103780989095;5109.9744501277255;5123.590112791042;4735.195016847076;4960.975195124146;4974.9751251243515;5012.518803192443;5016.698998060128;4775.116603428377;4775.856724298276;4780.633586374766;4795.232762757942;4804.885850225889;3613.855445782139;3616.5298511193378;3639.090227443139;3653.9451908220867;3661.10302975762;4869.31829543857;4976.676516026447;5082.288479612931;5127.615428842777;5133.537981581676;4905.5094490550655;4907.312976183409;4913.164761990399;4918.631102667243;4925.359703238556;4911;4923.5814955729165;4952.8018879245865;4960.305557222064;4963.429205641398;4663.595575230033;4867.123917694849;4879.585235254901;4898.902021959612;4954.00919816031;4822.132016237111;4920.745209971435;5043.167877300351;5044.949550504595;5087.87280317995;4930.408350997904;4930.531599498042;4937.456879743298;4940.580050695731;4980.178270585313 01:41:07 INFO - PROCESS | 12612 | |5;yui.html;589.8495883549695;613.7023543581365;654.9377809108136;707.4310105517662;752.2289653105566;12384.628461146169;13678.0425370224;13694.383752731126;13708;13725.58823235302;13817;14034.649133771662;14037.087589306688;14051.297435128228;14052.508162214324;616.981490555284;619.9783007594735;750.8310630108209;766.84279722657;769.7729169894894;753.7625647920887;769.4306213402098;772.8802035684449;777.9611019449034;783.1541934710499;828.3994104274414;829.7386323308169;829.7801082713086;830.796454868555;831.1024093978489;659.7690808217134;822.181928980665;822.3462347433805;822.5681517203426;823.0904850140569;580;590.8936391449537;596.8895754285471;597.0387675841891;598.643806934873;12474.875251247462;12516.74966500665;12564.623061308175;12579.37103144839;12605.180663256859;11853.229540079867;12221.205621634654;12300.507979680802;12713;12993.740125197442;11777;11882.10884183681;11936;11938.462769175321;12224.511019559208;3990.6009399060154;4036.4752582164424;4050.51393832739;4071.389291606253;4220.725652832556;63861.68069159671;64711.99896017688;64746;65183;65310.02034969527;9393.154616084585;9579.089986451276;9867.901320986843;9986.30095893295;10557.208209384327;19187.904060479887;19201.711974320395;19314.420567383;19393.709094363596;19432.8056719431;6481.48148148147;6538.411542961061;6556.18047744032;6557.245916719543;6595.70319335631;3384.915377115592;3468.1156305142026;3505.94741078884;3543.5039094526787;3601.4777857210556;2822.618946442245;2919.8102123361914;2926.707329267056;2934.310437047292;2934.5451455024504;485.2430208874168;502.22406382139684;504.37709428856067;508.0626244578746;512.2904776884043;504.9368828896388;508.1640701046757;521.4785214785214;526.557691539109;528.8651393894532;7020.719171233093;7044.506884518137;7402.481826272217;7410.814729631802;7510.812229694192;489.5349418052793;531.5939340444503;545.8389775016379;553.4050895287581;556.8524341049625;3496.8076755778166;3499.1951851073886;3514.525539052246;3565.4651802229873;3670.6879915207505;54552.727236363564;93870;97011.51494242484;97771;104391.4780426093;85597.57201213954;87065.56467217623;88680;90852.54573727089;92191.53904230436;43552.56447435485;44880;45210;49742.50257497379;79380.20619793728;6971.7211311549;6996.265392133752;7079.610621415872;7124.251953544803;7164.641767911688;45032;45176.77411612921;46389.76805115953;46423.53576464192;47442.05115897593;57574.42425575691;65447.34552654603;67748.6612566934;68678;70009.29990700028;8629.39594228398;9047.32145089134;9075.682351117679;9215.815683686424;9412.105849944244;10207.438590877573;10303.845442318521;10392.636257730943;10516;12459.501619935101;11181.385023823768;11596.652100437;11617.47721352558;11668.824967625322;11849.289042657123;10971.670849874517;11041.503132359223;11206.663800086011;11916.52333906634;12050.156489045683;10984.560617575207;11368.488418021043;11513.597024104116;11761.647150585497;12108.939455302667;10202.438865862448;10230.48847557634;10255.94872025635;10333.74165645865;10638.627648032281;9662.95168524181;9942.105210531086;9947.452890091114;9956.352837065568;9960.900390995997 01:41:07 INFO - PROCESS | 12612 | __end_tp_report 01:41:07 INFO - PROCESS | 12612 | __start_cc_report 01:41:07 INFO - PROCESS | 12612 | _x_x_mozilla_cycle_collect,1072 01:41:07 INFO - PROCESS | 12612 | __end_cc_report 01:41:07 INFO - PROCESS | 12612 | __startTimestamp1471336867695__endTimestamp 01:41:07 INFO - PROCESS | 12612 | 01:41:07 INFO - PROCESS | 12612 | ------- Summary: start ------- 01:41:07 INFO - PROCESS | 12612 | Number of tests: 6 01:41:07 INFO - PROCESS | 12612 | 01:41:07 INFO - PROCESS | 12612 | [#0] dojo.html Cycles:165 Average:4745.89 Median:4879.02 stddev:510.23 (10.5%) stddev-sans-first:483.33 01:41:07 INFO - PROCESS | 12612 | Values: 2603.6 2639.7 2643.7 2647.9 2671.4 4686.3 4772.3 4794.6 4849.9 4879.0 4766.4 4793.2 4814.4 4837.8 4860.8 4342.8 4353.1 4374.5 4394.0 4445.9 4435.7 4469.1 4510.6 4549.3 4554.9 4448.8 4490.8 4494.8 4504.9 4545.7 4301.5 4350.7 4373.7 4438.2 4447.9 4332.5 4338.5 4341.7 4346.9 4376.3 4762.1 4803.5 4804.2 4812.7 4835.0 5028.4 5104.6 5151.6 5160.2 5186.2 5215.3 5242.5 5274.1 5277.0 5290.5 4852.8 4863.7 4879.9 4905.8 4931.9 5116.4 5209.9 5248.0 5268.8 5330.4 5043.9 5065.0 5116.4 5122.5 5123.1 5138.4 5157.5 5197.1 5227.5 5232.1 4923.8 4958.0 5052.1 5094.1 5096.6 5115.0 5116.7 5125.4 5127.4 5162.6 4341.3 4343.4 4343.7 4372.5 4389.0 3701.0 3717.3 3727.1 3747.1 3772.2 4313.6 4410.7 4447.2 4448.3 4451.2 5158.7 5160.6 5160.7 5161.1 5213.3 4366.9 4407.5 4408.4 4408.6 4409.4 4492.3 4557.9 4584.9 4594.5 4656.0 4838.5 4859.2 4860.5 4877.9 4908.3 5136.7 5155.7 5160.6 5162.6 5206.3 4701.6 4754.5 4789.4 4793.5 4866.7 4982.0 5005.1 5007.0 5019.7 5038.3 5080.9 5091.6 5102.0 5107.4 5113.7 4860.6 4967.9 4985.4 5006.4 5059.9 5005.2 5018.3 5019.1 5035.9 5066.5 4936.9 4944.6 4996.0 5005.2 5005.8 4876.2 4981.3 4994.1 5005.6 5091.8 4930.5 4937.3 4943.0 4944.9 4991.6 01:41:07 INFO - PROCESS | 12612 | 01:41:07 INFO - PROCESS | 12612 | [#1] ext.html Cycles:180 Average:45483.04 Median:35448.15 stddev:79690.27 (224.8%) stddev-sans-first:79842.45 01:41:07 INFO - PROCESS | 12612 | Values: 563.8 565.4 569.4 574.8 575.2 27853.6 38037.6 39179.0 39435.4 43764.6 40179.8 40363.0 40409.2 40519.8 40586.0 7653.3 7810.1 7812.2 7814.1 7816.6 7641.2 7727.5 7728.1 7729.2 7736.1 7731.1 7755.9 7759.8 7760.9 7762.6 7697.6 7720.7 7726.2 7738.5 7739.6 7683.5 7702.0 7707.7 7720.0 7749.1 38205.2 38569.4 38574.4 38628.0 38652.6 3084.2 3087.4 3160.8 3162.3 3340.3 29007.7 29408.8 29614.9 29722.3 29859.3 3256.1 3373.5 3407.4 3410.5 3414.6 240937.0 242931.0 243004.0 243837.0 244904.0 51687.7 51941.9 52070.5 52112.2 52177.7 54536.5 54748.7 54799.0 54879.0 54937.5 12740.7 12800.0 12863.3 12879.3 12929.5 10069.7 10183.5 10190.7 10200.9 10215.5 16476.2 16632.9 16756.6 16781.2 16805.8 5862.0 5888.4 5894.8 5928.5 5949.8 740.5 786.9 855.6 923.8 969.9 25412.5 28274.3 28624.7 29196.7 31853.2 933.3 945.3 969.2 971.2 971.4 14591.4 15185.7 15215.6 15360.5 15387.5 350489.0 351432.2 351693.0 352297.0 352416.0 47645.5 47851.0 47881.5 47884.5 47971.0 1177.7 1207.9 1210.9 1223.2 1228.7 26180.5 27366.7 27978.0 28124.2 28409.3 43142.6 43360.3 43380.6 43533.8 43579.8 304952.0 305219.0 305899.0 305964.0 306295.0 24626.5 24996.6 25171.9 25345.4 25358.2 40342.6 41467.2 41633.6 41665.4 41842.4 34705.7 35232.0 35470.3 35568.3 35802.3 34919.5 35136.6 35166.8 35341.6 35426.0 27829.6 29136.0 35259.5 35514.8 35576.1 30324.7 32135.2 32502.5 32724.3 32859.5 24833.4 24872.9 25260.5 25296.0 25347.0 01:41:07 INFO - PROCESS | 12612 | 01:41:07 INFO - PROCESS | 12612 | [#2] jquery.html Cycles:180 Average:23669.95 Median:38968.51 stddev:60421.28 (155.1%) stddev-sans-first:60565.44 01:41:07 INFO - PROCESS | 12612 | Values: 361.6 365.4 366.2 371.3 384.9 16384.0 16836.9 17741.4 17839.7 17867.3 15755.9 15839.8 16371.6 16486.2 16536.3 4880.1 5113.9 5114.1 5114.8 5118.0 3752.6 3874.6 3895.6 3941.6 5025.7 3895.0 5970.4 6063.4 6090.4 6107.4 5773.1 5933.8 6005.2 6076.8 6081.9 5265.7 5266.8 5267.5 5269.2 5275.3 19432.0 20096.4 20118.3 20981.2 21259.9 1826.3 1884.1 1902.1 2183.9 2238.2 8550.7 8812.1 9100.3 9136.9 9320.1 1659.6 1687.4 1743.5 1751.6 1775.9 67663.7 69643.3 71333.3 71853.6 73360.3 10132.3 10212.9 10402.6 10467.2 10490.8 14078.9 14092.8 14096.6 14099.5 14121.5 6759.9 6845.9 6872.2 7068.7 7083.7 6355.6 6461.3 6588.6 6603.1 6615.3 4308.2 4449.9 4453.2 4455.4 4593.1 1765.7 1817.2 1839.2 1861.1 1882.6 740.9 799.2 889.2 894.3 942.8 16727.2 16895.7 17188.7 17453.3 17517.6 826.6 838.6 839.3 884.2 885.8 9253.3 9428.6 9721.4 10005.4 10031.5 354585.0 359697.0 366251.0 370514.0 370614.0 74910.0 76662.0 77637.6 78094.6 79607.0 870.5 882.1 910.4 911.7 912.9 15323.5 15343.0 15720.5 16023.8 16093.8 37630.8 38254.4 38772.8 38952.6 38984.4 51056.7 51435.5 51617.7 52017.0 52695.5 7470.7 7509.5 7683.4 7707.6 8185.5 8878.5 11181.8 11236.8 11896.0 12226.3 4954.8 10124.6 10240.4 10946.7 11246.8 9689.8 9833.6 10046.4 10155.8 10592.0 10497.7 10517.9 10550.1 10806.9 10932.3 7556.5 7602.1 7625.9 7811.0 7891.6 10051.9 10093.3 10267.8 10308.7 10653.5 01:41:07 INFO - PROCESS | 12612 | 01:41:07 INFO - PROCESS | 12612 | [#3] mootools.html Cycles:180 Average:5060.06 Median:4977.78 stddev:2888.26 (58.0%) stddev-sans-first:2882.01 01:41:07 INFO - PROCESS | 12612 | Values: 1229.2 1360.8 1381.8 1454.8 1658.5 6672.2 7435.1 7698.4 7749.8 7991.9 7807.5 7863.0 7890.4 7894.8 7901.4 2186.4 2201.8 2209.0 2209.8 2213.5 2117.5 2164.7 2171.3 2191.7 2195.1 2147.7 2155.0 2166.7 2168.1 2181.7 2114.2 2118.6 2121.4 2122.1 2123.2 2067.0 2137.7 2146.2 2146.3 2147.3 7614.7 7649.0 7786.1 7789.9 7815.8 4378.9 4551.8 4560.4 4562.6 4572.3 2860.8 2992.5 3057.8 3059.3 3061.1 920.1 923.8 933.3 942.2 942.2 10108.9 10145.6 10197.6 10215.8 10254.5 3999.2 4029.3 4072.2 4122.9 4125.8 8164.2 8187.6 8197.2 8202.2 8238.0 4357.8 4364.6 4371.6 4375.6 4380.0 3536.2 3832.8 3978.9 4034.3 4037.3 3415.7 3444.6 3526.6 3560.2 3564.4 1004.7 1026.9 1043.6 1048.2 1048.9 759.5 768.7 769.0 773.3 804.4 4956.4 5092.5 5096.8 5119.6 5486.3 412.6 419.6 420.5 423.2 429.7 3243.2 3272.9 3276.7 3287.2 3400.7 9414.5 9725.3 9785.0 9822.1 9850.7 9750.7 9768.2 9781.1 9785.8 9797.9 7405.1 7454.6 7465.1 7473.3 7479.0 4999.2 5002.6 5104.9 5169.7 5315.6 9014.4 9040.8 9051.9 9105.4 9170.2 8859.7 9048.3 9092.6 9186.4 9191.4 6533.2 6592.2 6624.9 6791.8 6873.3 7654.4 7711.1 7736.6 7743.1 7749.1 6729.1 6740.4 6795.5 6849.0 7100.9 6720.0 6759.4 6789.1 6824.1 6847.6 6071.3 6093.4 6119.5 6149.1 6339.8 4208.5 4270.0 4369.2 4538.1 4647.1 4491.1 4626.4 4669.9 4727.0 4899.0 01:41:07 INFO - PROCESS | 12612 | 01:41:07 INFO - PROCESS | 12612 | [#4] prototype.html Cycles:180 Average:4139.01 Median:4762.00 stddev:1187.62 (24.9%) stddev-sans-first:1155.42 01:41:07 INFO - PROCESS | 12612 | Values: 297.4 300.8 306.4 308.4 311.5 4801.3 4823.9 4831.2 4843.5 4845.2 4523.0 4625.9 4686.9 4748.9 4803.9 3797.1 3797.8 3807.1 3808.2 3823.5 3788.9 3807.6 3817.7 3822.7 3827.3 3622.5 3622.6 3736.8 3774.3 3804.5 3749.1 3754.3 3757.0 3760.5 3763.9 3339.8 3340.1 3341.4 3342.9 3343.6 4445.2 4659.4 4686.6 4686.9 4704.1 4241.5 4246.2 4258.0 4269.0 4269.5 5015.5 5024.3 5033.5 5040.4 5040.9 3589.0 3590.6 3595.0 3597.5 3604.1 4883.3 5176.2 5179.5 5181.7 5278.8 3602.2 3615.0 3627.0 3627.9 3631.6 3680.9 3681.4 3682.7 3689.2 3692.0 3987.0 4204.7 4215.4 4225.9 4227.2 4910.0 4960.3 4977.5 4989.8 5000.1 1262.6 1273.3 1277.8 1281.7 1283.5 802.6 823.9 854.9 873.6 884.3 4357.3 4359.2 4379.8 4384.4 4390.0 5063.9 5095.7 5098.1 5103.4 5111.6 4265.8 4356.2 4375.6 4382.4 4409.6 2709.8 2726.6 2735.8 2737.2 2740.3 4841.2 4857.2 4860.0 4860.6 4881.8 5079.1 5118.2 5168.0 5220.7 5246.1 4786.0 4807.5 4810.9 4811.3 4817.1 5094.0 5097.1 5108.3 5110.0 5123.6 4735.2 4961.0 4975.0 5012.5 5016.7 4775.1 4775.9 4780.6 4795.2 4804.9 3613.9 3616.5 3639.1 3653.9 3661.1 4869.3 4976.7 5082.3 5127.6 5133.5 4905.5 4907.3 4913.2 4918.6 4925.4 4911.0 4923.6 4952.8 4960.3 4963.4 4663.6 4867.1 4879.6 4898.9 4954.0 4822.1 4920.7 5043.2 5044.9 5087.9 4930.4 4930.5 4937.5 4940.6 4980.2 01:41:07 INFO - PROCESS | 12612 | 01:41:07 INFO - PROCESS | 12612 | [#5] yui.html Cycles:180 Average:17130.81 Median:506.50 stddev:24564.83 (4849.9%) stddev-sans-first:24602.34 01:41:07 INFO - PROCESS | 12612 | Values: 589.8 613.7 654.9 707.4 752.2 12384.6 13678.0 13694.4 13708.0 13725.6 13817.0 14034.6 14037.1 14051.3 14052.5 617.0 620.0 750.8 766.8 769.8 753.8 769.4 772.9 778.0 783.2 828.4 829.7 829.8 830.8 831.1 659.8 822.2 822.3 822.6 823.1 580.0 590.9 596.9 597.0 598.6 12474.9 12516.7 12564.6 12579.4 12605.2 11853.2 12221.2 12300.5 12713.0 12993.7 11777.0 11882.1 11936.0 11938.5 12224.5 3990.6 4036.5 4050.5 4071.4 4220.7 63861.7 64712.0 64746.0 65183.0 65310.0 9393.2 9579.1 9867.9 9986.3 10557.2 19187.9 19201.7 19314.4 19393.7 19432.8 6481.5 6538.4 6556.2 6557.2 6595.7 3384.9 3468.1 3505.9 3543.5 3601.5 2822.6 2919.8 2926.7 2934.3 2934.5 485.2 502.2 504.4 508.1 512.3 504.9 508.2 521.5 526.6 528.9 7020.7 7044.5 7402.5 7410.8 7510.8 489.5 531.6 545.8 553.4 556.9 3496.8 3499.2 3514.5 3565.5 3670.7 54552.7 93870.0 97011.5 97771.0 104391.5 85597.6 87065.6 88680.0 90852.5 92191.5 43552.6 44880.0 45210.0 49742.5 79380.2 6971.7 6996.3 7079.6 7124.3 7164.6 45032.0 45176.8 46389.8 46423.5 47442.1 57574.4 65447.3 67748.7 68678.0 70009.3 8629.4 9047.3 9075.7 9215.8 9412.1 10207.4 10303.8 10392.6 10516.0 12459.5 11181.4 11596.7 11617.5 11668.8 11849.3 10971.7 11041.5 11206.7 11916.5 12050.2 10984.6 11368.5 11513.6 11761.6 12108.9 10202.4 10230.5 10255.9 10333.7 10638.6 9663.0 9942.1 9947.5 9956.4 9960.9 01:41:07 INFO - PROCESS | 12612 | -------- Summary: end -------- 01:41:07 INFO - PROCESS | 12612 | 01:41:08 INFO - TEST-INFO | 12612: exit 0 01:41:08 INFO - TEST-OK | dromaeo_css | took 1119956ms 01:41:08 INFO - TEST-START | kraken 01:41:08 INFO - operating with platform_type : linux_ 01:41:08 INFO - Initialising browser for kraken test... 01:41:08 INFO - TEST-INFO | started process 12912 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpix0tFO/profile http://localhost:51025/getInfo.html) 01:41:13 INFO - PROCESS | 12912 | __metrics Screen width/height:1600/1200 01:41:13 INFO - PROCESS | 12912 | colorDepth:24 01:41:13 INFO - PROCESS | 12912 | Browser inner width/height: 1024/697 01:41:13 INFO - PROCESS | 12912 | __metrics 01:41:13 INFO - PROCESS | 12912 | 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:41:13 INFO - PROCESS | 12912 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:41:14 INFO - PROCESS | 12912 | console.error: PushService: 01:41:14 INFO - PROCESS | 12912 | stateChangeProcessEnqueue: Error transitioning state 01:41:14 INFO - PROCESS | 12912 | UnknownError 01:41:14 INFO - TEST-INFO | 12912: exit 0 01:41:14 INFO - Browser initialized. 01:41:14 INFO - Running cycle 1/1 for kraken test... 01:41:14 INFO - TEST-INFO | started process 12994 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpix0tFO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:41:26 INFO - PROCESS | 12994 | RSS: Main: 162054144 01:41:26 INFO - PROCESS | 12994 | 01:41:29 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51025/tests/kraken/driver.html?testName=audio-beat-detection) 01:41:30 INFO - PROCESS | 12994 | RSS: Main: 210132992 01:41:30 INFO - PROCESS | 12994 | 01:41:32 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51025/tests/kraken/driver.html?testName=audio-dft) 01:41:33 INFO - PROCESS | 12994 | RSS: Main: 196915200 01:41:33 INFO - PROCESS | 12994 | 01:41:36 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51025/tests/kraken/driver.html?testName=audio-fft) 01:41:37 INFO - PROCESS | 12994 | RSS: Main: 282161152 01:41:37 INFO - PROCESS | 12994 | 01:41:39 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51025/tests/kraken/driver.html?testName=audio-oscillator) 01:41:40 INFO - PROCESS | 12994 | RSS: Main: 192266240 01:41:40 INFO - PROCESS | 12994 | 01:41:42 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51025/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:41:43 INFO - PROCESS | 12994 | RSS: Main: 196726784 01:41:43 INFO - PROCESS | 12994 | 01:41:46 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51025/tests/kraken/driver.html?testName=imaging-darkroom) 01:41:46 INFO - PROCESS | 12994 | RSS: Main: 233562112 01:41:46 INFO - PROCESS | 12994 | 01:41:50 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51025/tests/kraken/driver.html?testName=imaging-desaturate) 01:41:50 INFO - PROCESS | 12994 | RSS: Main: 226942976 01:41:50 INFO - PROCESS | 12994 | 01:41:53 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51025/tests/kraken/driver.html?testName=json-parse-financial) 01:41:54 INFO - PROCESS | 12994 | RSS: Main: 230256640 01:41:54 INFO - PROCESS | 12994 | 01:41:56 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51025/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:41:56 INFO - PROCESS | 12994 | RSS: Main: 193998848 01:41:56 INFO - PROCESS | 12994 | 01:41:58 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:41:59 INFO - PROCESS | 12994 | RSS: Main: 197451776 01:41:59 INFO - PROCESS | 12994 | 01:42:01 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:42:02 INFO - PROCESS | 12994 | RSS: Main: 194195456 01:42:02 INFO - PROCESS | 12994 | 01:42:05 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:42:05 INFO - PROCESS | 12994 | RSS: Main: 188710912 01:42:05 INFO - PROCESS | 12994 | 01:42:08 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:42:09 INFO - PROCESS | 12994 | RSS: Main: 192782336 01:42:09 INFO - PROCESS | 12994 | 01:42:10 INFO - PROCESS | 12994 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51025/tests/kraken/driver.html?testName=ai-astar) 01:42:11 INFO - PROCESS | 12994 | RSS: Main: 242733056 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | __start_tp_report 01:42:11 INFO - PROCESS | 12994 | _x_x_mozilla_page_load 01:42:11 INFO - PROCESS | 12994 | _x_x_mozilla_page_load_details 01:42:11 INFO - PROCESS | 12994 | |i|pagename|runs| 01:42:11 INFO - PROCESS | 12994 | |0;ai-astar;105;115;115;114;111;114;115;114;115;114 01:42:11 INFO - PROCESS | 12994 | |1;audio-beat-detection;146;123;129;184;121;156;146;128;180;122 01:42:11 INFO - PROCESS | 12994 | |2;audio-dft;161;160;161;160;161;162;162;161;162;161 01:42:11 INFO - PROCESS | 12994 | |3;audio-fft;76;76;76;116;78;78;77;77;108;77 01:42:11 INFO - PROCESS | 12994 | |4;audio-oscillator;86;87;143;88;87;86;131;86;87;120 01:42:11 INFO - PROCESS | 12994 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;118;117;117 01:42:11 INFO - PROCESS | 12994 | |6;imaging-darkroom;146;146;146;146;149;147;145;146;146;146 01:42:11 INFO - PROCESS | 12994 | |7;imaging-desaturate;95;95;95;95;96;95;94;94;95;95 01:42:11 INFO - PROCESS | 12994 | |8;json-parse-financial;67;65;67;66;67;69;67;68;67;65 01:42:11 INFO - PROCESS | 12994 | |9;json-stringify-tinderbox;66;66;66;67;67;66;66;67;67;67 01:42:11 INFO - PROCESS | 12994 | |10;stanford-crypto-aes;82;81;87;85;88;82;81;86;87;82 01:42:11 INFO - PROCESS | 12994 | |11;stanford-crypto-ccm;139;132;141;133;131;143;145;134;148;134 01:42:11 INFO - PROCESS | 12994 | |12;stanford-crypto-pbkdf2;170;170;173;175;172;175;169;171;170;172 01:42:11 INFO - PROCESS | 12994 | |13;stanford-crypto-sha256-iterative;69;68;68;68;66;69;71;68;69;68 01:42:11 INFO - PROCESS | 12994 | __end_tp_report 01:42:11 INFO - PROCESS | 12994 | __start_cc_report 01:42:11 INFO - PROCESS | 12994 | _x_x_mozilla_cycle_collect,1922 01:42:11 INFO - PROCESS | 12994 | __end_cc_report 01:42:11 INFO - PROCESS | 12994 | __startTimestamp1471336931144__endTimestamp 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | ------- Summary: start ------- 01:42:11 INFO - PROCESS | 12994 | Number of tests: 14 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.50 stddev:3.12 (2.7%) stddev-sans-first:1.27 01:42:11 INFO - PROCESS | 12994 | Values: 105.0 115.0 115.0 114.0 111.0 114.0 115.0 114.0 115.0 114.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:146.00 stddev:23.52 (16.1%) stddev-sans-first:24.93 01:42:11 INFO - PROCESS | 12994 | Values: 146.0 123.0 129.0 184.0 121.0 156.0 146.0 128.0 180.0 122.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 01:42:11 INFO - PROCESS | 12994 | Values: 161.0 160.0 161.0 160.0 161.0 162.0 162.0 161.0 162.0 161.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#3] audio-fft Cycles:10 Average:83.90 Median:77.00 stddev:14.95 (19.4%) stddev-sans-first:15.58 01:42:11 INFO - PROCESS | 12994 | Values: 76.0 76.0 76.0 116.0 78.0 78.0 77.0 77.0 108.0 77.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#4] audio-oscillator Cycles:10 Average:100.10 Median:86.50 stddev:22.23 (25.7%) stddev-sans-first:22.99 01:42:11 INFO - PROCESS | 12994 | Values: 86.0 87.0 143.0 88.0 87.0 86.0 131.0 86.0 87.0 120.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 01:42:11 INFO - PROCESS | 12994 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 01:42:11 INFO - PROCESS | 12994 | Values: 146.0 146.0 146.0 146.0 149.0 147.0 145.0 146.0 146.0 146.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 01:42:11 INFO - PROCESS | 12994 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 94.0 94.0 95.0 95.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:67.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 01:42:11 INFO - PROCESS | 12994 | Values: 67.0 65.0 67.0 66.0 67.0 69.0 67.0 68.0 67.0 65.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:67.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 01:42:11 INFO - PROCESS | 12994 | Values: 66.0 66.0 66.0 67.0 67.0 66.0 66.0 67.0 67.0 67.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:85.50 stddev:2.77 (3.2%) stddev-sans-first:2.83 01:42:11 INFO - PROCESS | 12994 | Values: 82.0 81.0 87.0 85.0 88.0 82.0 81.0 86.0 87.0 82.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:140.00 stddev:6.02 (4.3%) stddev-sans-first:6.37 01:42:11 INFO - PROCESS | 12994 | Values: 139.0 132.0 141.0 133.0 131.0 143.0 145.0 134.0 148.0 134.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:172.00 stddev:2.11 (1.2%) stddev-sans-first:2.15 01:42:11 INFO - PROCESS | 12994 | Values: 170.0 170.0 173.0 175.0 172.0 175.0 169.0 171.0 170.0 172.0 01:42:11 INFO - PROCESS | 12994 | 01:42:11 INFO - PROCESS | 12994 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:1.26 (1.8%) stddev-sans-first:1.32 01:42:11 INFO - PROCESS | 12994 | Values: 69.0 68.0 68.0 68.0 66.0 69.0 71.0 68.0 69.0 68.0 01:42:11 INFO - PROCESS | 12994 | -------- Summary: end -------- 01:42:11 INFO - PROCESS | 12994 | 01:42:12 INFO - TEST-INFO | 12994: exit 0 01:42:12 INFO - TEST-OK | kraken | took 63707ms 01:42:12 INFO - SUITE-END | took 1183s 01:42:12 INFO - Completed test suite (00:19:43) 01:42:12 INFO - javascript benchmark 01:42:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2603.5573952428085, 2639.7360263973605, 2643.7224091470384, 2647.8940842366305, 2671.4389978104596, 4686.320483529886, 4772.260299653551, 4794.568488836004, 4849.878753031172, 4879.024195160969, 4766.404199475066, 4793.185158523051, 4814.398200224972, 4837.782299796509, 4860.781264843082, 4342.761148136859, 4353.129374125172, 4374.496932852715, 4394.0, 4445.866624001285, 4435.733855968652, 4469.106178764244, 4510.639148868083, 4549.272116461367, 4554.931676024862, 4448.8442904498515, 4490.820367185309, 4494.842680506183, 4504.8873778155485, 4545.704529205607, 4301.462317210348, 4350.695451318409, 4373.715708478939, 4438.223310920576, 4447.866564003085, 4332.501762297313, 4338.501072376654, 4341.7394956302405, 4346.8913277168, 4376.321670141101, 4762.1428142934255, 4803.519648035169, 4804.183288840905, 4812.711237325771, 4835.032993401333, 5028.371453568304, 5104.566111880495, 5151.639385243035, 5160.200168973815, 5186.196139598368, 5215.269862219292, 5242.501962313574, 5274.129768588178, 5277.0, 5290.523852853262, 4852.8301509447, 4863.659543831898, 4879.902401951977, 4905.803767849317, 4931.8520444386, 5116.386033675983, 5209.895802083937, 5247.9737601311745, 5268.841934741964, 5330.360356757214, 5043.899122017612, 5065.0, 5116.411612664517, 5122.487751224848, 5123.077845987684, 5138.409082955433, 5157.535821776059, 5197.116490196676, 5227.45111763267, 5232.084385232569, 4923.8276660316715, 4958.0, 5052.065367906949, 5094.108531007059, 5096.592272618182, 5115.0, 5116.74416279192, 5125.359330083739, 5127.435982041972, 5162.6128040397125, 4341.305391137403, 4343.370211319341, 4343.739375637472, 4372.519022907478, 4388.978055109704, 3701.03773019017, 3717.312297225022, 3727.142757165813, 3747.138158223624, 3772.1701225730294, 4313.633341166036, 4410.735355878657, 4447.221736196153, 4448.288273876229, 4451.176532341462, 5158.690478571298, 5160.587153027749, 5160.716160611202, 5161.122609156452, 5213.348331458567, 4366.934495982563, 4407.493138289138, 4408.360787685768, 4408.603225709701, 4409.4488188976375, 4492.258777301774, 4557.863264102082, 4584.931226031612, 4594.540545945379, 4656.045510670305, 4838.540338667821, 4859.222524396079, 4860.465348811557, 4877.87805304856, 4908.337374454403, 5136.691798492103, 5155.690658560498, 5160.638755287094, 5162.6386152968935, 5206.271122042841, 4701.553352431513, 4754.5245475452175, 4789.44921334051, 4793.496682848251, 4866.732329721899, 4981.978694367718, 5005.099082165245, 5007.0, 5019.67372120811, 5038.345015148007, 5080.923786143285, 5091.643584949166, 5102.030614183293, 5107.438181799925, 5113.7187454688865, 4860.586850117638, 4967.925481117713, 4985.401751789664, 5006.424261210008, 5059.924101138412, 5005.1991681332265, 5018.272350509083, 5019.071471777586, 5035.899282014412, 5066.518680725325, 4936.851894443173, 4944.5549900507685, 4995.950040499549, 5005.174146265826, 5005.7997680092385, 4876.244182151772, 4981.302617633607, 4994.051130285234, 5005.649604527648, 5091.847244582668, 4930.5069493050405, 4937.28409380645, 4943.036107958914, 4944.8763780905765, 4991.575716064175], "value": 4712.192767678426, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.788579282769, 565.3865555871879, 569.3708452160361, 574.8275517344796, 575.1889835332182, 27853.582196267045, 38037.61962380375, 39179.0, 39435.408468872956, 43764.562354376445, 40179.79910100453, 40363.0, 40409.19181616366, 40519.79740101303, 40586.0, 7653.349465295457, 7810.1408845027, 7812.218778122199, 7814.062312522506, 7816.570088645122, 7641.1976742442075, 7727.536347819121, 7728.0726312842535, 7729.227077292254, 7736.110347310047, 7731.110922243957, 7755.883661745078, 7759.8060048498955, 7760.922390776076, 7762.611869406507, 7697.576633285167, 7720.691172353099, 7726.227377262285, 7738.458307918448, 7739.57432341221, 7683.538987660758, 7702.0, 7707.730229441942, 7720.034995625547, 7749.108852481981, 38205.23589528194, 38569.421458678145, 38574.42138367927, 38628.0, 38652.61347386518, 3084.182691586709, 3087.3516561521883, 3160.841957902119, 3162.3201011782576, 3340.3486320167576, 29007.70992290071, 29408.82364705409, 29614.930923486118, 29722.25694357637, 29859.25351866199, 3256.0983490835924, 3373.527706121145, 3407.4377727674905, 3410.4543273076188, 3414.6243913169533, 240937.0, 242931.0, 243004.0, 243837.0, 244904.0, 51687.74156129195, 51941.871335089294, 52070.47929520732, 52112.21831672528, 52177.73911130496, 54536.454635453934, 54748.72625636846, 54799.0, 54879.0, 54937.450625494035, 12740.745185096432, 12800.0, 12863.29251891155, 12879.291638959947, 12929.482820687255, 10069.748256293506, 10183.490825458697, 10190.694279171637, 10200.897991019994, 10215.489225538693, 16476.176191190392, 16632.916835415745, 16756.58108547296, 16781.160941952854, 16805.83194168067, 5861.970690146607, 5888.381719919433, 5894.839892312543, 5928.525717942641, 5949.762009519571, 740.4705635470557, 786.9370450364072, 855.6491838346249, 923.8060007398387, 969.8943204746595, 25412.491750165264, 28274.2931426716, 28624.713752862204, 29196.708032919825, 31853.203669907973, 933.2580598424269, 945.3193700535603, 969.1955676788249, 971.1502435369052, 971.4268581536927, 14591.41634334636, 15185.696286074217, 15215.61960950963, 15360.539183824503, 15387.461438849587, 350489.0, 351432.18555165076, 351693.0, 352297.0, 352416.0, 47645.5235447648, 47851.0, 47881.52118478771, 47884.5211547887, 47971.0, 1177.7406053061482, 1207.93356365399, 1210.8668046515052, 1223.1988047828686, 1228.6744012836605, 26180.476390472464, 27366.726332736416, 27978.0, 28124.156275311772, 28409.28976775597, 43142.56857431385, 43360.349594756735, 43380.566194337654, 43533.78233108814, 43579.782101089295, 304952.0, 305219.0, 305899.0, 305964.0, 306295.0, 24626.50746985086, 24996.62505062389, 25171.87414062918, 25345.366365841, 25358.239252822445, 40342.59657403389, 41467.170656586095, 41633.58366416418, 41665.37501937534, 41842.372364413946, 34705.65294347024, 35232.0, 35470.29059418849, 35568.28863422665, 35802.28395432025, 34919.476207856394, 35136.64863351334, 35166.82416587901, 35341.64658353384, 35426.0, 27829.582556261266, 29136.0, 35259.47110793289, 35514.8224258877, 35576.110597235274, 30324.69675303219, 32135.19662008375, 32502.51246231261, 32724.34551309008, 32859.50710739293, 24833.379165521008, 24872.875635621705, 25260.494790103727, 25296.0, 25347.0], "value": 17084.55690908006, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.6401680328074, 365.4135113143405, 366.1834109934844, 371.2834229936222, 384.9403342481916, 16384.0, 16836.91581542092, 17741.37905173319, 17839.73240401395, 17867.285308587674, 15755.921220393911, 15839.762403563956, 16371.59071023225, 16486.175691215452, 16536.255868485918, 4880.072786170634, 5113.872153196163, 5114.1050316194705, 5114.769835357418, 5117.976404719053, 3752.605976372469, 3874.554426240976, 3895.552011518669, 3941.5664276929515, 5025.748712564357, 3894.9610503894883, 5970.373110823366, 6063.393660633923, 6090.3909609038965, 6107.3587273336325, 5773.134029895549, 5933.792317268896, 6005.249343832021, 6076.756929722806, 6081.878362432749, 5265.736713164327, 5266.789328426858, 5267.499587539216, 5269.209618557209, 5275.261463395128, 19432.0, 20096.39710808678, 20118.29585964499, 20981.16075356984, 21259.893700531553, 1826.3425166939892, 1884.0579710144928, 1902.0869982408522, 2183.879886606236, 2238.227811405079, 8550.700725474579, 8812.07473215316, 9100.317476189311, 9136.908630913673, 9320.067993200693, 1659.5602165426171, 1687.3588036546034, 1743.4856717268435, 1751.649670065979, 1775.8579313654877, 67663.66168169127, 69643.30356696469, 71333.2866671337, 71853.64073179601, 73360.26639733587, 10132.2907396483, 10212.948935255276, 10402.5838966442, 10467.162626989822, 10490.842637360445, 14078.929605351908, 14092.788608170886, 14096.647583810487, 14099.506517271846, 14121.505747298796, 6759.898601520981, 6845.93887947369, 6872.244053154149, 7068.681909314093, 7083.716651333992, 6355.586886852339, 6461.321561236096, 6588.571742836796, 6603.108580341688, 6615.305392933769, 4308.246056940023, 4449.911001780011, 4453.1761624099645, 4455.420795296591, 4593.081383723202, 1765.7439671247598, 1817.2063309123691, 1839.2367167625553, 1861.1345724238295, 1882.6422979634099, 740.9143261718245, 799.2447137455049, 889.2308153447221, 894.2622336572224, 942.783159873233, 16727.247273872705, 16895.746563801553, 17188.656226875642, 17453.301867925395, 17517.649647006987, 826.5687029685408, 838.5639467476877, 839.3075712537182, 884.1688812516214, 885.7781204895562, 9253.259739220846, 9428.622855085858, 9721.416714997124, 10005.445888354247, 10031.548580313904, 354585.0, 359697.0, 366251.0, 370514.0, 370614.0, 74910.0, 76662.0, 77637.61181194057, 78094.609526952, 79607.0, 870.4646642315057, 882.1090698394539, 910.4309806371018, 911.7447114807865, 912.8950170730318, 15323.46367877119, 15343.0, 15720.528384148494, 16023.839761602701, 16093.75859362087, 37630.81184594169, 38254.42618360671, 38772.80613596914, 38952.6104738949, 38984.415233770946, 51056.74471627618, 51435.48564514307, 51617.741911290206, 52017.0, 52695.47304526906, 7470.663820127998, 7509.549427034395, 7683.423743219169, 7707.576083315472, 8185.467944583583, 8878.467291962503, 11181.776364472827, 11236.83144752813, 11896.0, 12226.266424014588, 4954.801807927643, 10124.64563740295, 10240.385576865412, 10946.726331841768, 11246.7750644985, 9689.757756056155, 9833.557489912828, 10046.397216167054, 10155.847662284923, 10592.0, 10497.737556561147, 10517.9474102629, 10550.103241224582, 10806.891931080589, 10932.289401188897, 7556.546607203585, 7602.087749470099, 7625.8856117157175, 7810.984572005603, 7891.64487598071, 10051.889103288391, 10093.293469457067, 10267.794644106927, 10308.742281443025, 10653.467326633483], "value": 8142.64379516712, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1229.2194456520108, 1360.8026836108766, 1381.7581923163443, 1454.7599646058402, 1658.4610001749427, 6672.232693240273, 7435.070616172979, 7698.384129269659, 7749.767506974786, 7991.92008079919, 7807.492512986666, 7862.96068519658, 7890.36877049836, 7894.763157105296, 7901.367890568755, 2186.387811412803, 2201.8348623853194, 2208.9668654970187, 2209.7569267380577, 2213.513027134028, 2117.4706323419146, 2164.7402311722535, 2171.3051823416513, 2191.6931629571873, 2195.056125865877, 2147.7315335583053, 2154.956900861982, 2166.6533354663256, 2168.078566609193, 2181.6618424144285, 2114.1860383752214, 2118.629239883022, 2121.406006318218, 2122.1193204819983, 2123.1719629344566, 2066.9896650516803, 2137.7434707835005, 2146.2059038155826, 2146.3453646637768, 2147.2806609785584, 7614.695412183506, 7649.0, 7786.1435242123325, 7789.9221007789765, 7815.804604884866, 4378.8905277368, 4551.840685575989, 4560.384348113029, 4562.6121779648765, 4572.314152877062, 2860.7854410919263, 2992.4912764830033, 3057.755379569629, 3059.2963618367676, 3061.0816754973644, 920.093707697917, 923.8290916180528, 933.2580598424269, 942.1755963531909, 942.2320808541085, 10108.949455252676, 10145.594176232942, 10197.592096316139, 10215.846762298646, 10254.53854576546, 3999.1801680655394, 4029.2545879012478, 4072.205919845661, 4122.9381559276635, 4125.834966601363, 8164.183581641907, 8187.631556579968, 8197.221263980031, 8202.179782021869, 8238.0, 4357.8256869724855, 4364.60718535327, 4371.628411584988, 4375.584319489643, 4380.0, 3536.2220311531423, 3832.770033797981, 3978.8607398740905, 4034.2738307104414, 4037.253108174997, 3415.7438192135687, 3444.5522082129164, 3526.5944416392445, 3560.181158333546, 3564.3584154852374, 1004.6584161385164, 1026.9383836969805, 1043.634727845248, 1048.2295512798087, 1048.9423081730579, 759.5480688990042, 768.7040489411505, 768.9692412303446, 773.3156156801272, 804.4489524675616, 4956.355673762388, 5092.541671249605, 5096.770645320969, 5119.641625086283, 5486.286782718221, 412.6451251923344, 419.61605131305123, 420.49960546949035, 423.18325631530826, 429.68632897984645, 3243.189202699325, 3272.9018129456153, 3276.6559511251453, 3287.1946373138258, 3400.6769356911054, 9414.529273536296, 9725.319227653996, 9784.951075244579, 9822.066903644143, 9850.655227067018, 9750.707478775648, 9768.169705575106, 9781.119699227103, 9785.804283914424, 9797.853032204523, 7405.111386633638, 7454.552726836408, 7465.06686664167, 7473.290037446434, 7479.027726395642, 4999.200127979506, 5002.624803139852, 5104.923426148536, 5169.689818610896, 5315.574754019746, 9014.368994170345, 9040.773980650536, 9051.864222036544, 9105.408148470327, 9170.174684278447, 8859.689910853089, 9048.276137909083, 9092.590833412645, 9186.448813071238, 9191.448513089235, 6533.150690410216, 6592.17597800275, 6624.9337506624315, 6791.830204244933, 6873.2783057778215, 7654.425918056057, 7711.11322197932, 7736.613169341398, 7743.109542402696, 7749.108852482037, 6729.0915726376315, 6740.359665831738, 6795.490338224554, 6849.041134241111, 7100.893486597602, 6719.966400167968, 6759.357861003196, 6789.11741473625, 6824.146981627297, 6847.589144651337, 6071.332153463204, 6093.421124993119, 6119.510439164945, 6149.139120523219, 6339.841503962438, 4208.452901122834, 4270.0, 4369.235383807884, 4538.092381523643, 4647.140279048387, 4491.101779644019, 4626.3523106764405, 4669.929951050669, 4726.952730472652, 4898.975505122594], "value": 3982.9842689534044, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.40370557033157, 300.79095028954873, 306.3918122526784, 308.37246203974917, 311.53736700999013, 4801.255805350172, 4823.903521929559, 4831.227003679412, 4843.467218605955, 4845.1763200255955, 4523.027549076956, 4625.90748185037, 4686.90626187476, 4748.884012257116, 4803.855884323467, 3797.126660868002, 3797.8480860765535, 3807.143392736626, 3808.16220431506, 3823.541175058997, 3788.90527736806, 3807.58116607173, 3817.675497582709, 3822.7132965027595, 3827.3302172119907, 3622.456631505269, 3622.5834029086727, 3736.8131593420358, 3774.3394905891496, 3804.5434547854156, 3749.1189570450924, 3754.3242216401036, 3756.981215093907, 3760.4547340635454, 3763.9059023524496, 3339.833008349597, 3340.0981734931433, 3341.431956567365, 3342.88299909502, 3343.632200457948, 4445.155420470101, 4659.370984917026, 4686.554777296152, 4686.882827929295, 4704.059188162381, 4241.536669848897, 4246.171996460692, 4258.0, 4268.9573104268875, 4269.530351661315, 5015.498450154992, 5024.296598476216, 5033.546980771748, 5040.395152581714, 5040.873978150539, 3589.0489020409614, 3590.622984586607, 3595.0, 3597.5323207983056, 3604.1350075981836, 4883.267509873512, 5176.197689358156, 5179.533841954242, 5181.740912954375, 5278.762455689503, 3602.243528858969, 3615.0, 3626.963730362663, 3627.909302267464, 3631.5823680276576, 3680.9263814723554, 3681.4477828325967, 3682.6869716074434, 3689.2437050405138, 3691.9630803692157, 3986.963389518746, 4204.747715137101, 4215.367694845736, 4225.936610950838, 4227.239096962577, 4910.0, 4960.255961605788, 4977.527134922177, 4989.750512474362, 5000.149974504343, 1262.61490245476, 1273.2742336867896, 1277.7827769279245, 1281.6667666406802, 1283.4545318239711, 802.6147449224288, 823.8928939237861, 854.8674955381802, 873.5588527793424, 884.2704768565959, 4357.276702061212, 4359.237133501688, 4379.759113248802, 4384.386185933972, 4390.0, 5063.949360506422, 5095.745212739423, 5098.08234517783, 5103.422771500369, 5111.591072714248, 4265.829366825292, 4356.215881141362, 4375.606195442426, 4382.408374869416, 4409.582573505559, 2709.7832173426077, 2726.6046423268517, 2735.849528275924, 2737.219892330716, 2740.287525243411, 4841.2496063110275, 4857.198562237262, 4860.003699241718, 4860.562549370574, 4881.780319885614, 5079.1365467870555, 5118.206677964921, 5168.0, 5220.738963051832, 5246.055709972242, 4785.9760701196965, 4807.519248075235, 4810.855674329776, 4811.302361157613, 4817.097246587648, 5093.974530127326, 5097.133487307093, 5108.3103780989095, 5109.9744501277255, 5123.590112791042, 4735.195016847076, 4960.975195124146, 4974.9751251243515, 5012.518803192443, 5016.698998060128, 4775.116603428377, 4775.856724298276, 4780.633586374766, 4795.232762757942, 4804.885850225889, 3613.855445782139, 3616.5298511193378, 3639.090227443139, 3653.9451908220867, 3661.10302975762, 4869.31829543857, 4976.676516026447, 5082.288479612931, 5127.615428842777, 5133.537981581676, 4905.5094490550655, 4907.312976183409, 4913.164761990399, 4918.631102667243, 4925.359703238556, 4911.0, 4923.5814955729165, 4952.8018879245865, 4960.305557222064, 4963.429205641398, 4663.595575230033, 4867.123917694849, 4879.585235254901, 4898.902021959612, 4954.00919816031, 4822.132016237111, 4920.745209971435, 5043.167877300351, 5044.949550504595, 5087.87280317995, 4930.408350997904, 4930.531599498042, 4937.456879743298, 4940.580050695731, 4980.178270585313], "value": 3763.677673696957, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.8495883549695, 613.7023543581365, 654.9377809108136, 707.4310105517662, 752.2289653105566, 12384.628461146169, 13678.0425370224, 13694.383752731126, 13708.0, 13725.58823235302, 13817.0, 14034.649133771662, 14037.087589306688, 14051.297435128228, 14052.508162214324, 616.981490555284, 619.9783007594735, 750.8310630108209, 766.84279722657, 769.7729169894894, 753.7625647920887, 769.4306213402098, 772.8802035684449, 777.9611019449034, 783.1541934710499, 828.3994104274414, 829.7386323308169, 829.7801082713086, 830.796454868555, 831.1024093978489, 659.7690808217134, 822.181928980665, 822.3462347433805, 822.5681517203426, 823.0904850140569, 580.0, 590.8936391449537, 596.8895754285471, 597.0387675841891, 598.643806934873, 12474.875251247462, 12516.74966500665, 12564.623061308175, 12579.37103144839, 12605.180663256859, 11853.229540079867, 12221.205621634654, 12300.507979680802, 12713.0, 12993.740125197442, 11777.0, 11882.10884183681, 11936.0, 11938.462769175321, 12224.511019559208, 3990.6009399060154, 4036.4752582164424, 4050.51393832739, 4071.389291606253, 4220.725652832556, 63861.68069159671, 64711.99896017688, 64746.0, 65183.0, 65310.02034969527, 9393.154616084585, 9579.089986451276, 9867.901320986843, 9986.30095893295, 10557.208209384327, 19187.904060479887, 19201.711974320395, 19314.420567383, 19393.709094363596, 19432.8056719431, 6481.48148148147, 6538.411542961061, 6556.18047744032, 6557.245916719543, 6595.70319335631, 3384.915377115592, 3468.1156305142026, 3505.94741078884, 3543.5039094526787, 3601.4777857210556, 2822.618946442245, 2919.8102123361914, 2926.707329267056, 2934.310437047292, 2934.5451455024504, 485.2430208874168, 502.22406382139684, 504.37709428856067, 508.0626244578746, 512.2904776884043, 504.9368828896388, 508.1640701046757, 521.4785214785214, 526.557691539109, 528.8651393894532, 7020.719171233093, 7044.506884518137, 7402.481826272217, 7410.814729631802, 7510.812229694192, 489.5349418052793, 531.5939340444503, 545.8389775016379, 553.4050895287581, 556.8524341049625, 3496.8076755778166, 3499.1951851073886, 3514.525539052246, 3565.4651802229873, 3670.6879915207505, 54552.727236363564, 93870.0, 97011.51494242484, 97771.0, 104391.4780426093, 85597.57201213954, 87065.56467217623, 88680.0, 90852.54573727089, 92191.53904230436, 43552.56447435485, 44880.0, 45210.0, 49742.50257497379, 79380.20619793728, 6971.7211311549, 6996.265392133752, 7079.610621415872, 7124.251953544803, 7164.641767911688, 45032.0, 45176.77411612921, 46389.76805115953, 46423.53576464192, 47442.05115897593, 57574.42425575691, 65447.34552654603, 67748.6612566934, 68678.0, 70009.29990700028, 8629.39594228398, 9047.32145089134, 9075.682351117679, 9215.815683686424, 9412.105849944244, 10207.438590877573, 10303.845442318521, 10392.636257730943, 10516.0, 12459.501619935101, 11181.385023823768, 11596.652100437, 11617.47721352558, 11668.824967625322, 11849.289042657123, 10971.670849874517, 11041.503132359223, 11206.663800086011, 11916.52333906634, 12050.156489045683, 10984.560617575207, 11368.488418021043, 11513.597024104116, 11761.647150585497, 12108.939455302667, 10202.438865862448, 10230.48847557634, 10255.94872025635, 10333.74165645865, 10638.627648032281, 9662.95168524181, 9942.105210531086, 9947.452890091114, 9956.352837065568, 9960.900390995997], "value": 6356.3031056263035, "unit": "score"}], "name": "dromaeo_css", "value": 6299.092227292007, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 115.0, 114.0, 111.0, 114.0, 115.0, 114.0, 115.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 129.0, 184.0, 121.0, 156.0, 146.0, 128.0, 180.0, 122.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 161.0, 162.0, 162.0, 161.0, 162.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 116.0, 78.0, 78.0, 77.0, 77.0, 108.0, 77.0], "value": 83.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 143.0, 88.0, 87.0, 86.0, 131.0, 86.0, 87.0, 120.0], "value": 100.1, "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, 118.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 149.0, 147.0, 145.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 66.0, 67.0, 69.0, 67.0, 68.0, 67.0, 65.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 67.0, 66.0, 66.0, 67.0, 67.0, 67.0], "value": 66.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 87.0, 85.0, 88.0, 82.0, 81.0, 86.0, 87.0, 82.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 132.0, 141.0, 133.0, 131.0, 143.0, 145.0, 134.0, 148.0, 134.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 173.0, 175.0, 172.0, 175.0, 169.0, 171.0, 170.0, 172.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 68.0, 66.0, 69.0, 71.0, 68.0, 69.0, 68.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1555.6000000000001, "alertThreshold": 2.0}]} 01:42:12 INFO - Return code: 0 01:42:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:42:12 INFO - # TBPL SUCCESS # 01:42:12 INFO - Running post-action listener: _resource_record_post_action 01:42:12 INFO - [mozharness: 2016-08-16 08:42:12.464311Z] Finished run-tests step (success) 01:42:12 INFO - Running post-run listener: _resource_record_post_run 01:42:12 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 396099584; Read time: 8; Write time: 397332 01:42:12 INFO - TinderboxPrint: CPU usage
13.0% 01:42:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 01:42:12 INFO - TinderboxPrint: I/O write bytes / time
396,099,584 / 397,332 01:42:12 INFO - TinderboxPrint: CPU idle
8,334.8 (87.0%) 01:42:12 INFO - TinderboxPrint: CPU user
1,197.8 (12.5%) 01:42:12 INFO - TinderboxPrint: Swap in / out
0 / 0 01:42:12 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114245632; Read time: 0; Write time: 139132 01:42:12 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 281853952; Read time: 0; Write time: 258200 01:42:13 INFO - Running post-run listener: _upload_blobber_files 01:42:13 INFO - Blob upload gear active. 01:42:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:42:13 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 01:42:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:42:13 INFO - (blobuploader) - INFO - Open directory for files ... 01:42:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:42:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:42:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:17 INFO - (blobuploader) - INFO - Done attempting. 01:42:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:42:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:42:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:18 INFO - (blobuploader) - INFO - Done attempting. 01:42:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:42:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:42:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:19 INFO - (blobuploader) - INFO - Done attempting. 01:42:19 INFO - (blobuploader) - INFO - Iteration through files over. 01:42:19 INFO - Return code: 0 01:42:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:42:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:42:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bf6cd50e29b653a58e44725b806129a07a42c5783bfd26d5e838a1e2f8101a509a5b63c643244c32f14da4af93f800642350ff0141310b5ebe269c1b0dd2f87a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4d6d2f6e7dab16e45275ee5a6f694763b890fc6f272adbed875f16fd89b0b533e5478fbca14504f89b65ade796e110b81902fe51fa583b839479a80f1a8b8232", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7b1da9b800f490f3b0a480987628b1a7eb6775dfe2e3f5e1d17ca9438b7ab6db7c4dd3f1f09f81acc28cef4d4b16dc391905777cfb6f75b4f5ade90b20e608d3"} 01:42:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:42:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:42:19 INFO - Contents: 01:42:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bf6cd50e29b653a58e44725b806129a07a42c5783bfd26d5e838a1e2f8101a509a5b63c643244c32f14da4af93f800642350ff0141310b5ebe269c1b0dd2f87a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4d6d2f6e7dab16e45275ee5a6f694763b890fc6f272adbed875f16fd89b0b533e5478fbca14504f89b65ade796e110b81902fe51fa583b839479a80f1a8b8232", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7b1da9b800f490f3b0a480987628b1a7eb6775dfe2e3f5e1d17ca9438b7ab6db7c4dd3f1f09f81acc28cef4d4b16dc391905777cfb6f75b4f5ade90b20e608d3"} 01:42:19 INFO - Running post-run listener: copy_logs_to_upload_dir 01:42:19 INFO - Copying logs to upload dir... 01:42:19 INFO - mkdir: /builds/slave/test/build/upload/logs 01:42:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.591647 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-16 01:42:19.434531) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 01:42:19.438799) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bf6cd50e29b653a58e44725b806129a07a42c5783bfd26d5e838a1e2f8101a509a5b63c643244c32f14da4af93f800642350ff0141310b5ebe269c1b0dd2f87a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4d6d2f6e7dab16e45275ee5a6f694763b890fc6f272adbed875f16fd89b0b533e5478fbca14504f89b65ade796e110b81902fe51fa583b839479a80f1a8b8232", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7b1da9b800f490f3b0a480987628b1a7eb6775dfe2e3f5e1d17ca9438b7ab6db7c4dd3f1f09f81acc28cef4d4b16dc391905777cfb6f75b4f5ade90b20e608d3"} build_url:https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011378 build_url: 'https://queue.taskcluster.net/v1/task/K5QDVUJbRC-PXqJYi5MlWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bf6cd50e29b653a58e44725b806129a07a42c5783bfd26d5e838a1e2f8101a509a5b63c643244c32f14da4af93f800642350ff0141310b5ebe269c1b0dd2f87a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4d6d2f6e7dab16e45275ee5a6f694763b890fc6f272adbed875f16fd89b0b533e5478fbca14504f89b65ade796e110b81902fe51fa583b839479a80f1a8b8232", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7b1da9b800f490f3b0a480987628b1a7eb6775dfe2e3f5e1d17ca9438b7ab6db7c4dd3f1f09f81acc28cef4d4b16dc391905777cfb6f75b4f5ade90b20e608d3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 01:42:19.465451) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 01:42:19.465766) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471335344.431304-130111809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 01:42:19.547032) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 01:42:19.550364) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 01:42:19.550651) ========= ========= Total master_lag: 0.20 =========