builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-002 starttime: 1472712337.32 results: success (0) buildid: 20160831203012 builduid: ac0101aac8484daca6913ddc6a387a51 revision: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:37.315915) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:37.316426) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:37.316781) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:37.332019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:37.332343) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-31 23:45:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-08-31 23:45:37 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126824 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:37.475769) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:37.476129) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014217 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:37.525427) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 23:45:37.525808) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 --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 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 --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=a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-31 23:45:38,039 truncating revision to first 12 chars 2016-08-31 23:45:38,039 Setting DEBUG logging. 2016-08-31 23:45:38,039 attempt 1/10 2016-08-31 23:45:38,039 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9f33ab9fd502?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 23:45:39,258 unpacking tar archive at: fx-team-9f33ab9fd502/testing/mozharness/ program finished with exit code 0 elapsedTime=1.479571 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 23:45:39.019367) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:39.019754) ========= script_repo_revision: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:39.020221) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:39.020587) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 23:45:39.031300) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-31 23:45:39.031612) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:45:39 INFO - MultiFileLogger online at 20160831 23:45:39 in /builds/slave/test 23:45:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:45:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:45:39 INFO - {'append_to_log': False, 23:45:39 INFO - 'base_work_dir': '/builds/slave/test', 23:45:39 INFO - 'blob_upload_branch': 'Fx-Team', 23:45:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:45:39 INFO - 'branch': 'Fx-Team', 23:45:39 INFO - 'buildbot_json_path': 'buildprops.json', 23:45:39 INFO - 'config_files': ('talos/linux_config.py',), 23:45:39 INFO - 'default_actions': ('clobber', 23:45:39 INFO - 'read-buildbot-config', 23:45:39 INFO - 'download-and-extract', 23:45:39 INFO - 'populate-webroot', 23:45:39 INFO - 'create-virtualenv', 23:45:39 INFO - 'install', 23:45:39 INFO - 'run-tests'), 23:45:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:45:39 INFO - 'download_minidump_stackwalk': True, 23:45:39 INFO - 'download_symbols': 'ondemand', 23:45:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:45:39 INFO - 'tooltool.py': '/tools/tooltool.py', 23:45:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:45:39 INFO - '/tools/misc-python/virtualenv.py')}, 23:45:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:45:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:45:39 INFO - 'installer_path': 'installer.exe', 23:45:39 INFO - 'log_level': 'info', 23:45:39 INFO - 'log_name': 'talos', 23:45:39 INFO - 'log_to_console': True, 23:45:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:45:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:45:39 INFO - 'opt_config_files': (), 23:45:39 INFO - 'pip_index': False, 23:45:39 INFO - 'sps_profile': False, 23:45:39 INFO - 'sps_profile_interval': 0, 23:45:39 INFO - 'suite': 'dromaeojs', 23:45:39 INFO - 'system_bits': '32', 23:45:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:45:39 INFO - 'title': 'talos-linux64-ix-002', 23:45:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:45:39 INFO - 'use_talos_json': True, 23:45:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:45:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:45:39 INFO - 'work_dir': 'build'} 23:45:39 INFO - [mozharness: 2016-09-01 06:45:39.638676Z] Running clobber step. 23:45:39 INFO - Running pre-action listener: _resource_record_pre_action 23:45:39 INFO - Running main action method: clobber 23:45:39 INFO - rmtree: /builds/slave/test/build 23:45:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:45:40 INFO - Running post-action listener: _resource_record_post_action 23:45:40 INFO - [mozharness: 2016-09-01 06:45:40.104213Z] Finished clobber step (success) 23:45:40 INFO - [mozharness: 2016-09-01 06:45:40.104315Z] Running read-buildbot-config step. 23:45:40 INFO - Running pre-action listener: _resource_record_pre_action 23:45:40 INFO - Running main action method: read_buildbot_config 23:45:40 INFO - Using buildbot properties: 23:45:40 INFO - { 23:45:40 INFO - "project": "", 23:45:40 INFO - "product": "firefox", 23:45:40 INFO - "script_repo_revision": "production", 23:45:40 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:45:40 INFO - "repository": "", 23:45:40 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:45:40 INFO - "buildid": "20160831203012", 23:45:40 INFO - "pgo_build": "True", 23:45:40 INFO - "basedir": "/builds/slave/test", 23:45:40 INFO - "buildnumber": 1515, 23:45:40 INFO - "slavename": "talos-linux64-ix-002", 23:45:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:45:40 INFO - "platform": "ubuntu64_hw", 23:45:40 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:45:40 INFO - "branch": "fx-team", 23:45:40 INFO - "repo_path": "integration/fx-team", 23:45:40 INFO - "revision": "9f33ab9fd502d2a4c38aef7744ab68c0aaa78234", 23:45:40 INFO - "stage_platform": "linux64-pgo", 23:45:40 INFO - "builduid": "ac0101aac8484daca6913ddc6a387a51", 23:45:40 INFO - "slavebuilddir": "test" 23:45:40 INFO - } 23:45:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 23:45:40 INFO - Running post-action listener: _resource_record_post_action 23:45:40 INFO - [mozharness: 2016-09-01 06:45:40.106778Z] Finished read-buildbot-config step (success) 23:45:40 INFO - [mozharness: 2016-09-01 06:45:40.106869Z] Running download-and-extract step. 23:45:40 INFO - Running pre-action listener: _resource_record_pre_action 23:45:40 INFO - Running main action method: download_and_extract 23:45:40 INFO - mkdir: /builds/slave/test/build/tests 23:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:40 INFO - trying https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:45:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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 23:45:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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 23:45:40 INFO - Downloaded 1361 bytes. 23:45:40 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:45:40 INFO - Using the following test package requirements: 23:45:40 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 23:45:40 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:45:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:45:40 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:45:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:45:40 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:45:40 INFO - u'jsshell-linux-x86_64.zip'], 23:45:40 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:45:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:45:40 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 23:45:40 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:45:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:45:40 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:45:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:45:40 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:45:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:45:40 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:45:40 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:45:40 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:40 INFO - trying https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:45:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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 23:45:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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 23:45:41 INFO - Downloaded 21906704 bytes. 23:45:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 23:45:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:45: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')]} 23:45:45 INFO - trying https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:45:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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 23:45:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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 23:45:46 INFO - Downloaded 21906704 bytes. 23:45:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 23:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:51 INFO - trying https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 23:45:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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 23:45:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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 23:45:52 INFO - Downloaded 14413998 bytes. 23:45:52 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 23:45: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')]} 23:45:52 INFO - trying https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:45:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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 23:45:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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 23:45:53 INFO - Downloaded 59735366 bytes. 23:45:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:45:53 INFO - mkdir: /builds/slave/test/properties 23:45:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:45:53 INFO - Writing to file /builds/slave/test/properties/build_url 23:45:53 INFO - Contents: 23:45:53 INFO - build_url:https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:45:54 INFO - Running post-action listener: _resource_record_post_action 23:45:54 INFO - Running post-action listener: set_extra_try_arguments 23:45:54 INFO - [mozharness: 2016-09-01 06:45:54.078203Z] Finished download-and-extract step (success) 23:45:54 INFO - [mozharness: 2016-09-01 06:45:54.078433Z] Running populate-webroot step. 23:45:54 INFO - Running pre-action listener: _resource_record_pre_action 23:45:54 INFO - Running main action method: populate_webroot 23:45:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:45:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:45:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:45:54 INFO - '--tppagecycles', 23:45:54 INFO - '7'], 23:45:54 INFO - 'tests': ['tsvgm']}}, 23:45:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:45:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:45:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:45:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:45:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:45:54 INFO - 'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:45:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:45:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:45:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:45:54 INFO - 'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['damp', 'tps']}, 23:45:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:45:54 INFO - 'tests': ['damp', 'tps']}, 23:45:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['dromaeo_dom']}, 23:45:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:45:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['basic_compositor_video']}, 23:45:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:45:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['a11yr', 23:45:54 INFO - 'ts_paint', 23:45:54 INFO - 'tpaint', 23:45:54 INFO - 'sessionrestore', 23:45:54 INFO - 'sessionrestore_no_auto_restore', 23:45:54 INFO - 'tabpaint']}, 23:45:54 INFO - 'other-e10s': {'tests': ['a11yr', 23:45:54 INFO - 'ts_paint', 23:45:54 INFO - 'tpaint', 23:45:54 INFO - 'sessionrestore', 23:45:54 INFO - 'sessionrestore_no_auto_restore', 23:45:54 INFO - 'tabpaint']}, 23:45:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:45:54 INFO - 'ts_paint', 23:45:54 INFO - 'tpaint', 23:45:54 INFO - 'sessionrestore', 23:45:54 INFO - 'sessionrestore_no_auto_restore', 23:45:54 INFO - 'tabpaint']}, 23:45:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:45:54 INFO - 'ts_paint', 23:45:54 INFO - 'tpaint', 23:45:54 INFO - 'sessionrestore', 23:45:54 INFO - 'sessionrestore_no_auto_restore', 23:45:54 INFO - 'tabpaint']}, 23:45:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['a11yr', 23:45:54 INFO - 'ts_paint', 23:45:54 INFO - 'tpaint', 23:45:54 INFO - 'sessionrestore', 23:45:54 INFO - 'sessionrestore_no_auto_restore', 23:45:54 INFO - 'tabpaint']}, 23:45:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['a11yr', 23:45:54 INFO - 'ts_paint', 23:45:54 INFO - 'tpaint', 23:45:54 INFO - 'sessionrestore', 23:45:54 INFO - 'sessionrestore_no_auto_restore', 23:45:54 INFO - 'tabpaint']}, 23:45:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['tsvgx', 23:45:54 INFO - 'tsvgr_opacity', 23:45:54 INFO - 'tart', 23:45:54 INFO - 'tscrollx', 23:45:54 INFO - 'cart']}, 23:45:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:45:54 INFO - 'tsvgr_opacity', 23:45:54 INFO - 'tart', 23:45:54 INFO - 'tscrollx', 23:45:54 INFO - 'cart']}, 23:45:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:45:54 INFO - 'talos_options': ['--disable-e10s'], 23:45:54 INFO - 'tests': ['tp5o']}, 23:45:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:45:54 INFO - 'tests': ['tp5o']}, 23:45:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:45:54 INFO - 'talos_options': ['--disable-e10s', 23:45:54 INFO - '--xperf_path', 23:45:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:45:54 INFO - 'tests': ['tp5n']}, 23:45:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:45:54 INFO - 'talos_options': ['--xperf_path', 23:45:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:45:54 INFO - 'tests': ['tp5n']}}, 23:45:54 INFO - 'talos.zip': {'path': '', 23:45:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:45:54 INFO - Running post-action listener: _resource_record_post_action 23:45:54 INFO - [mozharness: 2016-09-01 06:45:54.095912Z] Finished populate-webroot step (success) 23:45:54 INFO - [mozharness: 2016-09-01 06:45:54.096002Z] Running create-virtualenv step. 23:45:54 INFO - Running pre-action listener: _resource_record_pre_action 23:45:54 INFO - Running main action method: create_virtualenv 23:45:54 INFO - Creating virtualenv /builds/slave/test/build/venv 23:45:54 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 23:45:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:45:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:45:54 INFO - Using real prefix '/usr' 23:45:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:45:54 INFO - Installing distribute.............................................................................................................................................................................................done. 23:45:56 INFO - Installing pip.................done. 23:45:56 INFO - Return code: 0 23:45:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:45:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:45:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0a2425f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', '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 23:45:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:45:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:45:56 INFO - Using env: {'DISPLAY': ':0', 23:45:56 INFO - 'HOME': '/home/cltbld', 23:45:56 INFO - 'LANG': 'en_US.UTF-8', 23:45:56 INFO - 'LANGUAGE': 'en_US:en', 23:45:56 INFO - 'LOGNAME': 'cltbld', 23:45:56 INFO - 'MAIL': '/var/mail/cltbld', 23:45:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:56 INFO - 'MOZ_NO_REMOTE': '1', 23:45:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:45:56 INFO - 'NO_EM_RESTART': '1', 23:45:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:45:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:56 INFO - 'PWD': '/builds/slave/test', 23:45:56 INFO - 'SHELL': '/bin/bash', 23:45:56 INFO - 'SHLVL': '1', 23:45:56 INFO - 'TERM': 'linux', 23:45:56 INFO - 'TMOUT': '86400', 23:45:56 INFO - 'USER': 'cltbld', 23:45:56 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:45:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:56 INFO - '_': '/tools/buildbot/bin/python'} 23:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:45:56 INFO - Cleaning up... 23:45:56 INFO - Return code: 0 23:45:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:45:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:45:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0a2425f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', '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 23:45:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:45:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:45:56 INFO - Using env: {'DISPLAY': ':0', 23:45:56 INFO - 'HOME': '/home/cltbld', 23:45:56 INFO - 'LANG': 'en_US.UTF-8', 23:45:56 INFO - 'LANGUAGE': 'en_US:en', 23:45:56 INFO - 'LOGNAME': 'cltbld', 23:45:56 INFO - 'MAIL': '/var/mail/cltbld', 23:45:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:56 INFO - 'MOZ_NO_REMOTE': '1', 23:45:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:45:56 INFO - 'NO_EM_RESTART': '1', 23:45:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:45:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:56 INFO - 'PWD': '/builds/slave/test', 23:45:56 INFO - 'SHELL': '/bin/bash', 23:45:56 INFO - 'SHLVL': '1', 23:45:56 INFO - 'TERM': 'linux', 23:45:56 INFO - 'TMOUT': '86400', 23:45:56 INFO - 'USER': 'cltbld', 23:45:56 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:45:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:56 INFO - '_': '/tools/buildbot/bin/python'} 23:45:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:45:57 INFO - Downloading/unpacking psutil>=3.1.1 23:45:57 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 23:45:57 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 23:45:57 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 23:45:57 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 23:45:57 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 23:45:57 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 23:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:45:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:45:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:45:59 INFO - Installing collected packages: psutil 23:45:59 INFO - Running setup.py install for psutil 23:45:59 INFO - building 'psutil._psutil_linux' extension 23:45:59 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 23:45:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:45:59 INFO - building 'psutil._psutil_posix' extension 23:45:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:45:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:45:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:45:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:45:59 INFO - Successfully installed psutil 23:45:59 INFO - Cleaning up... 23:45:59 INFO - Return code: 0 23:45:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0a2425f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', '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 23:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:45:59 INFO - Using env: {'DISPLAY': ':0', 23:45:59 INFO - 'HOME': '/home/cltbld', 23:45:59 INFO - 'LANG': 'en_US.UTF-8', 23:45:59 INFO - 'LANGUAGE': 'en_US:en', 23:45:59 INFO - 'LOGNAME': 'cltbld', 23:45:59 INFO - 'MAIL': '/var/mail/cltbld', 23:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:59 INFO - 'MOZ_NO_REMOTE': '1', 23:45:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:45:59 INFO - 'NO_EM_RESTART': '1', 23:45:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:59 INFO - 'PWD': '/builds/slave/test', 23:45:59 INFO - 'SHELL': '/bin/bash', 23:45:59 INFO - 'SHLVL': '1', 23:45:59 INFO - 'TERM': 'linux', 23:45:59 INFO - 'TMOUT': '86400', 23:45:59 INFO - 'USER': 'cltbld', 23:45:59 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:59 INFO - '_': '/tools/buildbot/bin/python'} 23:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:45:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:45:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:45:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:46:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:46:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:46:01 INFO - Installing collected packages: mozsystemmonitor 23:46:01 INFO - Running setup.py install for mozsystemmonitor 23:46:01 INFO - Successfully installed mozsystemmonitor 23:46:01 INFO - Cleaning up... 23:46:01 INFO - Return code: 0 23:46:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:46:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0a2425f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', '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 23:46:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:46:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:46:01 INFO - Using env: {'DISPLAY': ':0', 23:46:01 INFO - 'HOME': '/home/cltbld', 23:46:01 INFO - 'LANG': 'en_US.UTF-8', 23:46:01 INFO - 'LANGUAGE': 'en_US:en', 23:46:01 INFO - 'LOGNAME': 'cltbld', 23:46:01 INFO - 'MAIL': '/var/mail/cltbld', 23:46:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:01 INFO - 'MOZ_NO_REMOTE': '1', 23:46:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:46:01 INFO - 'NO_EM_RESTART': '1', 23:46:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:46:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:01 INFO - 'PWD': '/builds/slave/test', 23:46:01 INFO - 'SHELL': '/bin/bash', 23:46:01 INFO - 'SHLVL': '1', 23:46:01 INFO - 'TERM': 'linux', 23:46:01 INFO - 'TMOUT': '86400', 23:46:01 INFO - 'USER': 'cltbld', 23:46:01 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:46:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:01 INFO - '_': '/tools/buildbot/bin/python'} 23:46:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:02 INFO - Downloading/unpacking jsonschema==2.5.1 23:46:02 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 23:46:02 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 23:46:02 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 23:46:02 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 23:46:02 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 23:46:02 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 23:46:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:46:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:46:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:46:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:46:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:04 INFO - Downloading functools32-3.2.3-2.tar.gz 23:46:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:46:04 INFO - warning: no files found matching '*.txt' 23:46:04 INFO - no previously-included directories found matching 'build' 23:46:04 INFO - no previously-included directories found matching 'dist' 23:46:04 INFO - no previously-included directories found matching '.git*' 23:46:04 INFO - Installing collected packages: jsonschema, functools32 23:46:04 INFO - Running setup.py install for functools32 23:46:04 INFO - warning: no files found matching '*.txt' 23:46:04 INFO - no previously-included directories found matching 'build' 23:46:04 INFO - no previously-included directories found matching 'dist' 23:46:04 INFO - no previously-included directories found matching '.git*' 23:46:04 INFO - Successfully installed jsonschema functools32 23:46:04 INFO - Cleaning up... 23:46:04 INFO - Return code: 0 23:46:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:46: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')]} 23:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46: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')]} 23:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0a2425f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', '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 23:46:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:46:04 INFO - Using env: {'DISPLAY': ':0', 23:46:04 INFO - 'HOME': '/home/cltbld', 23:46:04 INFO - 'LANG': 'en_US.UTF-8', 23:46:04 INFO - 'LANGUAGE': 'en_US:en', 23:46:04 INFO - 'LOGNAME': 'cltbld', 23:46:04 INFO - 'MAIL': '/var/mail/cltbld', 23:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:04 INFO - 'MOZ_NO_REMOTE': '1', 23:46:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:46:04 INFO - 'NO_EM_RESTART': '1', 23:46:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:04 INFO - 'PWD': '/builds/slave/test', 23:46:04 INFO - 'SHELL': '/bin/bash', 23:46:04 INFO - 'SHLVL': '1', 23:46:04 INFO - 'TERM': 'linux', 23:46:04 INFO - 'TMOUT': '86400', 23:46:04 INFO - 'USER': 'cltbld', 23:46:04 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:04 INFO - '_': '/tools/buildbot/bin/python'} 23:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:46:04 INFO - Cleaning up... 23:46:04 INFO - Return code: 0 23:46:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:46: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')]} 23:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46: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')]} 23:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0a2425f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', '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 23:46:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:46:04 INFO - Using env: {'DISPLAY': ':0', 23:46:04 INFO - 'HOME': '/home/cltbld', 23:46:04 INFO - 'LANG': 'en_US.UTF-8', 23:46:04 INFO - 'LANGUAGE': 'en_US:en', 23:46:04 INFO - 'LOGNAME': 'cltbld', 23:46:04 INFO - 'MAIL': '/var/mail/cltbld', 23:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:04 INFO - 'MOZ_NO_REMOTE': '1', 23:46:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:46:04 INFO - 'NO_EM_RESTART': '1', 23:46:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:04 INFO - 'PWD': '/builds/slave/test', 23:46:04 INFO - 'SHELL': '/bin/bash', 23:46:04 INFO - 'SHLVL': '1', 23:46:04 INFO - 'TERM': 'linux', 23:46:04 INFO - 'TMOUT': '86400', 23:46:04 INFO - 'USER': 'cltbld', 23:46:04 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:04 INFO - '_': '/tools/buildbot/bin/python'} 23:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:04 INFO - Downloading/unpacking blobuploader==1.2.4 23:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:46:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:46:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:46:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:06 INFO - Downloading blobuploader-1.2.4.tar.gz 23:46:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:46:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:46:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:46:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:46:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:46:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:46:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:07 INFO - Downloading docopt-0.6.1.tar.gz 23:46:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:46:07 INFO - Installing collected packages: blobuploader, requests, docopt 23:46:07 INFO - Running setup.py install for blobuploader 23:46:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:46:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:46:07 INFO - Running setup.py install for requests 23:46:08 INFO - Running setup.py install for docopt 23:46:08 INFO - Successfully installed blobuploader requests docopt 23:46:08 INFO - Cleaning up... 23:46:08 INFO - Return code: 0 23:46:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:08 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 0x7fd0a2425f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', '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 23:46:08 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 23:46:08 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 23:46:08 INFO - Using env: {'DISPLAY': ':0', 23:46:08 INFO - 'HOME': '/home/cltbld', 23:46:08 INFO - 'LANG': 'en_US.UTF-8', 23:46:08 INFO - 'LANGUAGE': 'en_US:en', 23:46:08 INFO - 'LOGNAME': 'cltbld', 23:46:08 INFO - 'MAIL': '/var/mail/cltbld', 23:46:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:08 INFO - 'MOZ_NO_REMOTE': '1', 23:46:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:46:08 INFO - 'NO_EM_RESTART': '1', 23:46:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:46:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:08 INFO - 'PWD': '/builds/slave/test', 23:46:08 INFO - 'SHELL': '/bin/bash', 23:46:08 INFO - 'SHLVL': '1', 23:46:08 INFO - 'TERM': 'linux', 23:46:08 INFO - 'TMOUT': '86400', 23:46:08 INFO - 'USER': 'cltbld', 23:46:08 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:46:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:08 INFO - '_': '/tools/buildbot/bin/python'} 23:46:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:46:08 INFO - Running setup.py (path:/tmp/pip-ehWJx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:46:08 INFO - Running setup.py (path:/tmp/pip-OXIiDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:46:08 INFO - Running setup.py (path:/tmp/pip-Hc9y1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:46:08 INFO - Running setup.py (path:/tmp/pip-6FliIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:46:08 INFO - Running setup.py (path:/tmp/pip-tIeDSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:46:08 INFO - Running setup.py (path:/tmp/pip-Iw1MKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:46:08 INFO - Running setup.py (path:/tmp/pip-AcaZGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:46:08 INFO - Running setup.py (path:/tmp/pip-7ZSnWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:46:08 INFO - Running setup.py (path:/tmp/pip-pPSu7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:46:09 INFO - Running setup.py (path:/tmp/pip-FB2O5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:46:09 INFO - Running setup.py (path:/tmp/pip-h7gdWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:46:09 INFO - Running setup.py (path:/tmp/pip-ykSRbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:46:09 INFO - Running setup.py (path:/tmp/pip-SLDHT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:46:09 INFO - Running setup.py (path:/tmp/pip-kPglBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:46:09 INFO - Running setup.py (path:/tmp/pip-Lr64IB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:46:09 INFO - Running setup.py (path:/tmp/pip-KTlk1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:46:09 INFO - Running setup.py (path:/tmp/pip-_l14EI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:46:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:46:09 INFO - Running setup.py install for manifestparser 23:46:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:46:09 INFO - Running setup.py install for mozcrash 23:46:09 INFO - Running setup.py install for mozdebug 23:46:09 INFO - Running setup.py install for mozdevice 23:46:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Running setup.py install for mozfile 23:46:10 INFO - Running setup.py install for mozhttpd 23:46:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Running setup.py install for mozinfo 23:46:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Running setup.py install for mozInstall 23:46:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Running setup.py install for mozleak 23:46:10 INFO - Running setup.py install for mozlog 23:46:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Running setup.py install for moznetwork 23:46:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Running setup.py install for mozprocess 23:46:10 INFO - Running setup.py install for mozprofile 23:46:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:46:10 INFO - Running setup.py install for mozrunner 23:46:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:46:11 INFO - Running setup.py install for mozscreenshot 23:46:11 INFO - Running setup.py install for moztest 23:46:11 INFO - Running setup.py install for mozversion 23:46:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:46:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:46:11 INFO - Cleaning up... 23:46:11 INFO - Return code: 0 23:46:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:46: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')]} 23:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46: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')]} 23:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0a2425f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', '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 23:46:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:46:11 INFO - Using env: {'DISPLAY': ':0', 23:46:11 INFO - 'HOME': '/home/cltbld', 23:46:11 INFO - 'LANG': 'en_US.UTF-8', 23:46:11 INFO - 'LANGUAGE': 'en_US:en', 23:46:11 INFO - 'LOGNAME': 'cltbld', 23:46:11 INFO - 'MAIL': '/var/mail/cltbld', 23:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:11 INFO - 'MOZ_NO_REMOTE': '1', 23:46:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:46:11 INFO - 'NO_EM_RESTART': '1', 23:46:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:11 INFO - 'PWD': '/builds/slave/test', 23:46:11 INFO - 'SHELL': '/bin/bash', 23:46:11 INFO - 'SHLVL': '1', 23:46:11 INFO - 'TERM': 'linux', 23:46:11 INFO - 'TMOUT': '86400', 23:46:11 INFO - 'USER': 'cltbld', 23:46:11 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:11 INFO - '_': '/tools/buildbot/bin/python'} 23:46:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:46:11 INFO - Running setup.py (path:/tmp/pip-cIP2ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:46:11 INFO - Running setup.py (path:/tmp/pip-WOI1LW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:46:11 INFO - Running setup.py (path:/tmp/pip-7gljpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:46:11 INFO - Running setup.py (path:/tmp/pip-MvJPJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:46:11 INFO - Running setup.py (path:/tmp/pip-qsNis7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:46:12 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)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:46:12 INFO - Running setup.py (path:/tmp/pip-L_y1aA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:46:12 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)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:46:12 INFO - Running setup.py (path:/tmp/pip-pbUCD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:46:12 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)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:46:12 INFO - Running setup.py (path:/tmp/pip-1wfD5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:46:12 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)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:46:12 INFO - Running setup.py (path:/tmp/pip-zRg97C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:46:12 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)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:46:12 INFO - Running setup.py (path:/tmp/pip-YuW74d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:46:12 INFO - Running setup.py (path:/tmp/pip-euZBDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:46:12 INFO - Running setup.py (path:/tmp/pip-DURwlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:46:12 INFO - Running setup.py (path:/tmp/pip-JmmVJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:46:12 INFO - Running setup.py (path:/tmp/pip-c09MGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:46:12 INFO - Running setup.py (path:/tmp/pip-j2qvsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:46:12 INFO - Running setup.py (path:/tmp/pip-R9yg05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:46:12 INFO - Running setup.py (path:/tmp/pip-XIws1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:46:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:46:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:46:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:46:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:46:14 INFO - Downloading blessings-1.6.tar.gz 23:46:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:46:14 INFO - Installing collected packages: blessings 23:46:14 INFO - Running setup.py install for blessings 23:46:14 INFO - Successfully installed blessings 23:46:14 INFO - Cleaning up... 23:46:15 INFO - Return code: 0 23:46:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:46:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:46:15 INFO - Reading from file tmpfile_stdout 23:46:15 INFO - Current package versions: 23:46:15 INFO - argparse == 1.2.1 23:46:15 INFO - blessings == 1.6 23:46:15 INFO - blobuploader == 1.2.4 23:46:15 INFO - docopt == 0.6.1 23:46:15 INFO - functools32 == 3.2.3-2 23:46:15 INFO - jsonschema == 2.5.1 23:46:15 INFO - manifestparser == 1.1 23:46:15 INFO - mozInstall == 1.12 23:46:15 INFO - mozcrash == 0.17 23:46:15 INFO - mozdebug == 0.1 23:46:15 INFO - mozdevice == 0.48 23:46:15 INFO - mozfile == 1.2 23:46:15 INFO - mozhttpd == 0.7 23:46:15 INFO - mozinfo == 0.9 23:46:15 INFO - mozleak == 0.1 23:46:15 INFO - mozlog == 3.3 23:46:15 INFO - moznetwork == 0.27 23:46:15 INFO - mozprocess == 0.23 23:46:15 INFO - mozprofile == 0.28 23:46:15 INFO - mozrunner == 6.12 23:46:15 INFO - mozscreenshot == 0.1 23:46:15 INFO - mozsystemmonitor == 0.3 23:46:15 INFO - moztest == 0.7 23:46:15 INFO - mozversion == 1.4 23:46:15 INFO - psutil == 3.1.1 23:46:15 INFO - requests == 1.2.3 23:46:15 INFO - wsgiref == 0.1.2 23:46:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0a2425f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', '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 23:46:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:46:15 INFO - Using env: {'DISPLAY': ':0', 23:46:15 INFO - 'HOME': '/home/cltbld', 23:46:15 INFO - 'LANG': 'en_US.UTF-8', 23:46:15 INFO - 'LANGUAGE': 'en_US:en', 23:46:15 INFO - 'LOGNAME': 'cltbld', 23:46:15 INFO - 'MAIL': '/var/mail/cltbld', 23:46:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:15 INFO - 'MOZ_NO_REMOTE': '1', 23:46:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:46:15 INFO - 'NO_EM_RESTART': '1', 23:46:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:46:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:15 INFO - 'PWD': '/builds/slave/test', 23:46:15 INFO - 'SHELL': '/bin/bash', 23:46:15 INFO - 'SHLVL': '1', 23:46:15 INFO - 'TERM': 'linux', 23:46:15 INFO - 'TMOUT': '86400', 23:46:15 INFO - 'USER': 'cltbld', 23:46:15 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:46:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:15 INFO - '_': '/tools/buildbot/bin/python'} 23:46:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:46:15 INFO - Cleaning up... 23:46:15 INFO - Return code: 0 23:46:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0a2425f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', '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 23:46:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:46:15 INFO - Using env: {'DISPLAY': ':0', 23:46:15 INFO - 'HOME': '/home/cltbld', 23:46:15 INFO - 'LANG': 'en_US.UTF-8', 23:46:15 INFO - 'LANGUAGE': 'en_US:en', 23:46:15 INFO - 'LOGNAME': 'cltbld', 23:46:15 INFO - 'MAIL': '/var/mail/cltbld', 23:46:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:15 INFO - 'MOZ_NO_REMOTE': '1', 23:46:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:46:15 INFO - 'NO_EM_RESTART': '1', 23:46:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:46:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:15 INFO - 'PWD': '/builds/slave/test', 23:46:15 INFO - 'SHELL': '/bin/bash', 23:46:15 INFO - 'SHLVL': '1', 23:46:15 INFO - 'TERM': 'linux', 23:46:15 INFO - 'TMOUT': '86400', 23:46:15 INFO - 'USER': 'cltbld', 23:46:15 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:46:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:15 INFO - '_': '/tools/buildbot/bin/python'} 23:46:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:46:15 INFO - Cleaning up... 23:46:15 INFO - Return code: 0 23:46:15 INFO - Running post-action listener: _resource_record_post_action 23:46:15 INFO - Running post-action listener: _start_resource_monitoring 23:46:15 INFO - Starting resource monitoring. 23:46:15 INFO - [mozharness: 2016-09-01 06:46:15.748789Z] Finished create-virtualenv step (success) 23:46:15 INFO - [mozharness: 2016-09-01 06:46:15.749448Z] Running install step. 23:46:15 INFO - Running pre-action listener: _resource_record_pre_action 23:46:15 INFO - Running main action method: install 23:46:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:46:15 INFO - Reading from file tmpfile_stdout 23:46:15 INFO - Detecting whether we're running mozinstall >=1.0... 23:46:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:46:16 INFO - Reading from file tmpfile_stdout 23:46:16 INFO - Output received: 23:46:16 INFO - Usage: mozinstall [options] installer 23:46:16 INFO - Options: 23:46:16 INFO - -h, --help show this help message and exit 23:46:16 INFO - -d DEST, --destination=DEST 23:46:16 INFO - Directory to install application into. [default: 23:46:16 INFO - "/builds/slave/test"] 23:46:16 INFO - --app=APP Application being installed. [default: firefox] 23:46:16 INFO - mkdir: /builds/slave/test/build/application 23:46:16 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'] 23:46:16 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 23:46:33 INFO - Reading from file tmpfile_stdout 23:46:33 INFO - Output received: 23:46:33 INFO - /builds/slave/test/build/application/firefox/firefox 23:46:33 INFO - Running post-action listener: _resource_record_post_action 23:46:33 INFO - [mozharness: 2016-09-01 06:46:33.061830Z] Finished install step (success) 23:46:33 INFO - [mozharness: 2016-09-01 06:46:33.062113Z] Running run-tests step. 23:46:33 INFO - Running pre-action listener: _resource_record_pre_action 23:46:33 INFO - Running main action method: run_tests 23:46:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:46:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:46:33 INFO - Python 2.7.3 23:46:33 INFO - Return code: 0 23:46:33 INFO - grabbing minidump binary from tooltool 23:46: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')]} 23:46:33 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 0x2391370>, '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 0x23948f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2394d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:46:33 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 23:46:33 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 23:46:33 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 23:46:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:46:33 INFO - Return code: 0 23:46:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:46:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:46:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:46:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:46:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:46:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 23:46:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 23:46:33 INFO - Using env: {'DISPLAY': ':0', 23:46:33 INFO - 'HOME': '/home/cltbld', 23:46:33 INFO - 'LANG': 'en_US.UTF-8', 23:46:33 INFO - 'LANGUAGE': 'en_US:en', 23:46:33 INFO - 'LOGNAME': 'cltbld', 23:46:33 INFO - 'MAIL': '/var/mail/cltbld', 23:46:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:46:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:33 INFO - 'MOZ_NO_REMOTE': '1', 23:46:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:46:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:46:33 INFO - 'NO_EM_RESTART': '1', 23:46:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:33 INFO - 'PWD': '/builds/slave/test', 23:46:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:46:33 INFO - 'SHELL': '/bin/bash', 23:46:33 INFO - 'SHLVL': '1', 23:46:33 INFO - 'TERM': 'linux', 23:46:33 INFO - 'TMOUT': '86400', 23:46:33 INFO - 'USER': 'cltbld', 23:46:33 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466', 23:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:33 INFO - '_': '/tools/buildbot/bin/python'} 23:46:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 23:46:33 INFO - mozversion application_buildid: 20160831203012 23:46:33 INFO - mozversion application_changeset: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 23:46:33 INFO - mozversion application_display_name: Nightly 23:46:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:46:33 INFO - mozversion application_name: Firefox 23:46:33 INFO - mozversion application_remotingname: firefox 23:46:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:46:33 INFO - mozversion application_vendor: Mozilla 23:46:33 INFO - mozversion application_version: 51.0a1 23:46:33 INFO - mozversion platform_buildid: 20160831203012 23:46:33 INFO - mozversion platform_changeset: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 23:46:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:46:33 INFO - mozversion platform_version: 51.0a1 23:46:33 INFO - using testdate: 1472712393 23:46:33 INFO - actual date: 1472712393 23:46:33 INFO - starting webserver on 'localhost:60131' 23:46:33 INFO - SUITE-START | Running 2 tests 23:46:33 INFO - TEST-START | dromaeo_css 23:46:33 INFO - operating with platform_type : linux_ 23:46:33 INFO - Initialising browser for dromaeo_css test... 23:46:33 INFO - TEST-INFO | started process 27488 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnzv5np/profile http://localhost:60131/getInfo.html) 23:46:38 INFO - PROCESS | 27488 | __metrics Screen width/height:1600/1200 23:46:38 INFO - PROCESS | 27488 | colorDepth:24 23:46:38 INFO - PROCESS | 27488 | Browser inner width/height: 1024/697 23:46:38 INFO - PROCESS | 27488 | __metrics 23:46:38 INFO - PROCESS | 27488 | 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" 23:46:38 INFO - PROCESS | 27488 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:46:39 INFO - PROCESS | 27488 | console.error: PushService: 23:46:39 INFO - PROCESS | 27488 | stateChangeProcessEnqueue: Error transitioning state 23:46:39 INFO - PROCESS | 27488 | UnknownError 23:46:39 INFO - TEST-INFO | 27488: exit 0 23:46:39 INFO - Browser initialized. 23:46:39 INFO - Running cycle 1/1 for dromaeo_css test... 23:46:39 INFO - TEST-INFO | started process 27573 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnzv5np/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:46:51 INFO - PROCESS | 27573 | RSS: Main: 181116928 23:46:51 INFO - PROCESS | 27573 | 23:47:43 INFO - PROCESS | 27573 | 1472712463263 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] 23:49:52 INFO - PROCESS | 27573 | Cycle 1(1): loaded http://localhost:60131/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60131/tests/dromaeo/cssquery-ext.html) 23:49:53 INFO - PROCESS | 27573 | RSS: Main: 284078080 23:49:53 INFO - PROCESS | 27573 | 23:52:56 INFO - PROCESS | 27573 | Cycle 1(1): loaded http://localhost:60131/tests/dromaeo/cssquery-ext.html (next: http://localhost:60131/tests/dromaeo/cssquery-jquery.html) 23:52:56 INFO - PROCESS | 27573 | RSS: Main: 274530304 23:52:56 INFO - PROCESS | 27573 | 23:55:58 INFO - PROCESS | 27573 | Cycle 1(1): loaded http://localhost:60131/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60131/tests/dromaeo/cssquery-mootools.html) 23:55:59 INFO - PROCESS | 27573 | RSS: Main: 245260288 23:55:59 INFO - PROCESS | 27573 | 23:59:01 INFO - PROCESS | 27573 | Cycle 1(1): loaded http://localhost:60131/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60131/tests/dromaeo/cssquery-prototype.html) 23:59:02 INFO - PROCESS | 27573 | RSS: Main: 213852160 23:59:02 INFO - PROCESS | 27573 | 00:02:04 INFO - PROCESS | 27573 | Cycle 1(1): loaded http://localhost:60131/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60131/tests/dromaeo/cssquery-yui.html) 00:02:05 INFO - PROCESS | 27573 | RSS: Main: 216272896 00:02:05 INFO - PROCESS | 27573 | 00:05:08 INFO - PROCESS | 27573 | Cycle 1(1): loaded http://localhost:60131/tests/dromaeo/cssquery-yui.html (next: http://localhost:60131/tests/dromaeo/cssquery-dojo.html) 00:05:08 INFO - PROCESS | 27573 | RSS: Main: 310415360 00:05:08 INFO - PROCESS | 27573 | 00:05:08 INFO - PROCESS | 27573 | __start_tp_report 00:05:08 INFO - PROCESS | 27573 | _x_x_mozilla_page_load 00:05:08 INFO - PROCESS | 27573 | _x_x_mozilla_page_load_details 00:05:08 INFO - PROCESS | 27573 | |i|pagename|runs| 00:05:08 INFO - PROCESS | 27573 | |0;dojo.html;3025.8487075646217;3033.6056312679357;3046.162305366024;3072.2319420144963;3102.3640153768465;5211.088059589576;5260.053190425722;5287.4976877196605;5353.14349704047;5390.056740070491;5328.866778330544;5340.732963351837;5402.189671549259;5416.566674666027;5508.614396992212;4789.449213340458;4832.347633069526;4856.7571621418965;4894.143524883149;4899.118158731436;4803.975980120094;4907.509249075082;4985.6759310644875;4992.975035124838;4997.075541024904;4887.413510378778;4932.210846264598;4938.530839570253;4946.15915294399;4988.501149885019;4757.857264282077;4772.522747725235;4785.928211076836;4811.975940120277;4858.368412106439;4776.0925424169645;4776.211925032366;4781.211100168468;4810.6151507879285;4849.903001939977;5308.575313974873;5320.76056577455;5336.039512887679;5354;5369.140937450028;5487.149491828773;5539.418361072069;5608.887822243573;5613.186088017255;5645.32256129263;5634.943650563483;5667.518260947824;5679.801206957736;5683.204351390808;5742.2247996520355;5293.947060529384;5296.4703529647495;5299.072662284162;5300.549453296474;5319.0957537218765;5632.098864181711;5765.221695071195;5782.855428614318;5852.356240813507;5852.619579727304;5616.634918730269;5621.49406553404;5622.634528755618;5629.634073785191;5688.317401911797;5705.372409035002;5710.771569137271;5712.314522257356;5713.74288157034;5714.914276285859;5490.121580547093;5574.331080270394;5619.943800562024;5633.183188437735;5678.971605141948;5636.2954630671165;5637.548996080303;5638.182463542846;5646.378898321182;5706.543476521952;4402.647788176938;4418.955810441918;4421.270490369053;4423.911521769546;4428.31361139024;3876.457295978565;3883.86406475772;3916.8629097981434;3959.4456776051375;3979.5423526294267;5431.891362172734;5438.728063596884;5439.374471935699;5475;5503.807366742145;5674.659520428788;5723.971380143073;5725.570582206351;5736.340320863071;5739.110437882135;5256.816011439581;5390.784368625211;5410.215518749759;5453.318335208099;5479;5012.674176178537;5013.147764879979;5019.0463811875625;5065.341505604248;5075.847724568269;5881.353051164454;5929.05135178378;5935;5936.673482958392;5946.464818166386;5606.467385598362;5645.548356131418;5722.713864306685;5743.167240749985;5815.912761308668;5899.852503687443;5905.704714764159;5907.143464197753;5965.463108320098;5970.940290597038;5615.859603509945;5624.775008999758;5627.803026894039;5630.014747419267;5631.7184140793615;5556.416576259435;5644.745986430538;5702.743376547983;5720.628159169641;5731.283589551306;5612.045952188057;5612.326520817528;5614.382417934105;5660.830175094754;5690.032694442038;5536.75084621201;5541.556675465872;5586.804461843816;5588.916166257428;5599.356074051536;5440;5515.806946756844;5568.331800184003;5571.164325351229;5588.077967135378;5620.4660557247;5622.662640241599;5623.606347555796;5698.629589076697;5703.5722320826935;5481.945180548143;5488.451154884639;5493.615446918678;5493.807716729895;5507.394186639386 00:05:08 INFO - PROCESS | 27573 | |1;ext.html;566.8639526513635;605.9545534084945;606.9393060693931;616.8858761129188;621.071498110325;32369;42766.57233427669;43813;43887;43982.12035759287;45072.77463612677;45588.77205613967;45609.77195114028;45613.54386456135;45630;8103.797405064862;8777.429467084636;8931;8938.4190027648;8941.105889411072;7860.606969651523;8106.513609183438;10541.262111652188;10811.729706757314;10816.891831081706;10933.726656833564;10984;10986.560537578487;10990;10993;10846;10897.400642964634;10904.781904361947;10927;10939.507722152523;10715.303505272132;10763.946180269128;10765.892341076567;10772.78454430907;10779.78440431187;42669.35995960063;42926.35610465846;42946.57053429457;42963.57036429658;43012.78493607543;3464.168599536093;3475.200703838131;3483.442649176145;3552.1297282166033;3562.386352938849;31717.682823171705;32409.189770255696;32424.6757532424;33084.17289567756;33506.6649333506;4337.219300525904;4395.670324725658;4422.557744225552;4626.953730462686;4660.627149828006;332242;332660;334379;334649;334676;58049.41950580524;58366.70816645974;58508.70745646244;58662;58670;36916.63083369186;37890.8105459471;37904.052398690255;61172;61443.38556614376;18012.54968625795;18141.90929045373;18150.183241754152;18171.636567268844;18185.45443636693;13919.304034798422;14008.929955350159;14046.71906561863;14050.789238161437;14056.508022219174;17051.232694528775;18730.719039214422;18891.338803141825;19063.52341191481;19719.50701232452;7062.611556364346;7317;7346.302101300368;7358.595277259695;7367.410607151415;929.7443203119196;997.7056768253383;1037.775424998494;1092.7650555130526;1105.5743538737484;31849.8407507961;32105;32304.35391292161;32351.676483234867;32466.675333246367;1069.7165251208437;1114.793763153819;1118.9048930840806;1144.3648774929993;1167.6672148437824;15803.525894223192;17026.23381947815;17049.31802727902;17061.658766824596;17157;584372;589424;589776;590078;591723;58270.708646457344;58832.41167588355;58935;58952.7052364744;58969;1221.8045112781913;1296.9870301296867;1352.2157148853444;1355.9050866439254;1362.8023936529148;31005;31987.520187197675;33016.83491582527;33068.33863322768;33140.668593313756;50171.2200364942;50856;50905.236421452966;50951.74524127356;50999.49000509948;314038;315210;315842;316308;316546;28171.154865354074;29185.70814291915;29275;30540.08379748611;30547;47710.284345735534;47954.76022619865;48421.51578484311;48701.512984869696;49463.50536494589;39630.20739585134;39908.2018359637;40246.79876600598;40484.19031619292;41225.38161927633;39118.41322380109;39675.80162099171;40123;40747.59252407438;41651.750447485414;38802.80598596989;39777;40584;40799;40804.1839163221;31485.37029259356;35902;35985.640143598226;36769.448458272615;36805.63194368022;27011;27161.86419067892;27335.58996615092;27766.585515825027;27781.861090694416 00:05:08 INFO - PROCESS | 27573 | |2;jquery.html;375.23639393833554;393.75980651802394;401.3838757507227;407.8837531303579;418.8157210827236;15874.68250634988;18719.812801871994;18795.62408751824;19095.331663391786;19804.40586782399;19268.518287042833;19557.315493957678;19649.901750491226;19813.108410121542;20143;5467.863303417426;5580.693061881595;5775.306963164426;5789.015867302548;5826.57248974002;5557.944420555783;5738.139279108125;5739.942600574003;5743.081107022877;5744.16709577111;5531.308586426697;5673.546116310686;5696.715164241792;5700.087985922253;5768.798092066779;5687.374388817227;5721;5725.799597014085;5730.885382292352;5732.541396688255;4959.6528243023;5108.7445627718835;5128.487151284879;5129.84610461683;5144.305518754958;22484.88757556218;22866.542669146525;22894.542109157723;23042.76957230423;23226.76773232263;2266.1388672304583;2444.792192663626;2500.94998100037;2557.4117952870756;3054.266975925784;9937.304388692794;10356.689299321033;10390.792184156275;14424.423023079064;14500.41998320066;1896.4026331705468;1899.325739362534;1901.6672082385528;1910.7324974503442;1928.556432020629;80605;84390.15609843885;84404.57797711034;84778.57610711906;85263;10708.518116684612;10966.670999870017;11041.447927603587;11139.777204455866;11213.439328033566;15164.924175379052;15175.696486070217;15181.240937953278;15192.088474691333;15283.159426231441;8136;8182.918170818216;8320.70877519296;8362.8327433451;8600.268977136888;8091.635876385577;8211.794705132419;8363.790905227417;8404.78988025292;8426.789330266793;4530.728156310632;4538.931916021263;4546.840860569864;4643.907121857612;4745.525447455296;1975.190172029461;2017.2032047341218;2038.2254743197786;2071.6685330347073;2097.7587577428485;896.3277541843618;922.727795300388;969.9030096990385;978.6917121106828;1003.2275148135894;18009.279628814962;19599;19736.605267894563;19852.801471985094;20017.499562510762;936.4194199596294;957.4494665567327;957.5738796235747;1008.5511947183579;1046.1839764983324;10652.733681658021;10850.511726972147;10899.910008999037;10908.454577271104;10932.56269749201;465431;474606;490937;492844;494261;58965;59314.40685593089;60174.07998080189;93124;99724;972.6498460554195;983.2920297385871;1003.0320740485466;1006.6812475656913;1041.8124737547162;17058.402955896483;17407.545019940157;18106.72839907376;18114.72827907556;18159.636807263516;42349.36475952925;43001.56998430101;43258;43379.78310108429;43839.78080109707;55977;59252;60074;60472;61730.38269617426;8166.550839703873;8357.164283571594;8658.307335413278;8697.304215662605;8857.158569935846;8337.916620833714;12209.450574723982;13046.608601741962;13600.795988059988;13773.38019789132;11305.26515776472;11505;11572;11772.52909883595;11837.230580012272;11609.419529023346;11646.184767066563;11647.242929209575;11727.589534366256;12208.084393670688;11223.88776112261;11364.772704546029;11405;11536.538538458368;11875.524979000744;8102.635381407968;8148.22591853773;8367.330613551023;8812.427192232484;8849.698600109652;8212.794680133044;10432;11356;11588.6523404298;11835.467403967014 00:05:08 INFO - PROCESS | 27573 | |3;mootools.html;1343.489473999881;1472.9189894555798;1492.4552538323512;1617.3045590396125;1773.9467815965518;8468.57657117144;8751.212390884819;8836.558172091402;9053.637854485813;9057.95471022644;8874.068222836606;8972.506512141847;8988.68539601114;8988.820223595541;8990.955045224764;2488.7262401135818;2512.9748702513016;2514.383975925901;2515.308290220186;2518.659980902582;2420.878956052199;2505.0856437400307;2511.8744062796877;2515.2328539795353;2516.0564788204424;2427.5387676341525;2472.678551788265;2491.825572209937;2494.9376265593414;2497;2396.113438027923;2416.734159242482;2423.72127205371;2449.265220433863;2464.039024780337;2401.4116541447456;2418.4558474343307;2420.9152679656304;2432.3311089450367;2444.498877730061;8490.10853860342;8795.736127916172;8884.600192991331;8900.154485323885;8901.376903616749;5236;5404.297441332641;5436.10304299794;5442.428545002796;5446.128619420913;3561.821908904544;3606.6393360663724;3624.0758606555423;3642.690371318441;3727.4781530585738;1186.8575770907546;1205.6021512900722;1213.9575114871025;1216.2702378572874;1235.9258444493269;12553.811692824524;12761.872381276162;12763.36183190846;12796.104272700915;12800.551980680631;4813.989062296957;4844.95155048445;4912.70523768575;4924.75376231187;5050.974745126325;10722.088622467178;10729.302595331279;10732.2487425881;10733.677989660322;10740.516676749565;5512.5589952803675;5526.309211348582;5561.916571251434;5566.165075238666;5578.832635020955;4491.955080449154;4664.55686709762;4697.419981358688;4944.851654450372;5101.719405432663;4095.415087177948;4102.671786257152;4163.83344666216;4222.641075508554;4251.319788833772;1302.3553341096217;1366.8086467894502;1382.6128683968304;1389.3192335755407;1402.4109873853006;785.3167744062588;918.536139249688;934.761635782871;935.1957316707626;945.1540870920487;5794.88410231793;5935.139404786368;5949.613275137102;6093.939060609426;6288.434040936338;452.0642270500099;452.8407277369943;455.7220095741595;455.9179347717443;458.8852786803299;4176.644985176294;4213.91572168561;4271;4313.2883074292395;4337.110892267077;10937;11417.092658941387;11421.486033128529;11446.484908179153;11448.656540303804;10919.181061420426;11083.778324433466;11198.496067676973;11210.775784484265;11219.102471802236;7955.721549745731;8025.157358477276;8030.43786934909;8034.67861285542;8075.192480751878;5898.144769008555;5937.7921772737745;5983.730732116978;6005.699715014144;6108.11432342299;10134.189264858942;10287.279890407599;10345.793084138426;10347.43089130105;10360.222983276435;9542.952285238529;9887.752806179904;10224.539895704562;10475.685729428129;10725.08836748885;7486.176520582622;7579.962100189464;7679.347255483346;7821.256980586835;7822.45242833019;8319.209675080858;8455.365847561581;8664.566771661517;8681.652733890574;8803.691870784747;7639.923600764144;7682.193369696101;7699.191584883731;7848.803779905548;7856.607169641609;7608.581528016012;7672.808179795326;7743.070831500031;7744.186860379579;7782.416318776001;7225.24134965821;7229.674664639999;7308.634568271459;7313.122425308997;7317.3780228679025;5319.515029400452;5344.7172678557645;5356.946430535645;5473.534749546333;5515.834524964257;5330.8933821324135;5485.149801780731;5500.092484739973;5509.696966666872;5570 00:05:08 INFO - PROCESS | 27573 | |4;prototype.html;321.8406888590147;322.32633795367684;329.62258214344575;339.67560979264806;340.29049431934413;5245.790168393264;5300.462865768932;5307.734613269336;5332.893342133155;5343.919841202385;5234.214867769836;5237.86905327367;5266.972940276648;5323.653962492445;5334.879675268198;3884.35908075168;3966.583508731585;4063.756174629517;4086.3053280942313;4136.27615167346;4107.8973025674295;4108.157827645326;4113.177364527091;4127.876163715094;4136.503619565657;4061.776602286873;4067.9593204068024;4079.653229475498;4081.0205550667924;4086.5300490443533;3867.091233560111;3975.403689446577;4022.1352409231863;4054.3715724062818;4064.166845796605;3556.395412779808;3587.3722098632634;3590.317839610492;3593.586737525166;3596.62235465275;5111.539961403491;5134.922976155361;5157.174852023696;5158.484151584811;5164.328637277167;4536.228841096989;4656.790444429975;4670.898095856844;4684.227102528079;4694.788734506956;5432.478905906352;5437.184422336642;5446.673199608036;5455.945440545584;5462.535684466785;3929.174873276587;3954.8728612345344;3959.98020009901;3963.286608410485;3970.4441378207075;5497.587680923947;5645.2096706461125;5680.545556355523;5695.202671625976;5761.279840019997;3988.720789544762;4021.2560676274984;4031.274370613259;4033.394990751352;4036.919261614751;4076.144009757984;4092.263392589303;4113.588641135922;4124;4127.834886604503;4593.839215627437;4605.240135377692;4628.791704373311;4689.554492323224;4722.9763851181215;5456.91814622781;5461.290032295776;5485.75314110858;5499.917501237484;5531.806386776524;1390.8539603341706;1401.4884567132924;1410.3159967415795;1414.8938829587821;1416.1927701210418;858.9613467393982;917.4403613795579;925.8842644669417;935.1630290889751;938.1650331205233;5043.3191519145175;5179.7928082877015;5287.312649355559;5303.337082864642;5320.707361095177;5626.606137570413;5632.549396048306;5648.378678345461;5661.235733176051;5665.745041473144;5157;5323.680579165262;5374.596905232045;5375.704336261543;5381.7578208980685;2925.4880395930627;2976.389840082821;2976.553516972428;2979.657339405953;2987.731104200632;5803.662893836061;5803.680797556175;5820.650760954356;5827.708614569255;5833.154192642043;5504.587155963319;5735.254416925773;5748.5688573357165;5749.54003679713;5773.816367644623;5800.867531532925;5822.282426684138;5827.650340979555;5830.650160990354;5868.2664666916635;5505.944940550543;5617.606767526234;5657.5473962082115;5660.660360378391;5683.488486036276;5550.333959924835;5550.69471179089;5571.582131340247;5593.8321850344555;5608.775648974159;5781.479666829838;5791;5813.011787995966;5819.476247137778;5838.94161058384;4002.9199416010933;4100.835966561423;4102.815373308149;4120.175964806991;4126.525449573338;5636.746346414339;5642.98426283273;5649.378568357395;5656.5757568181725;5663.318842752244;5525.530329921841;5570.693611851225;5578.191162281528;5589.608727389043;5604.467575580314;5367.577591938218;5483.54536641913;5487.393488093124;5492.69790161529;5503.229547863222;5462.371827239918;5473.233747275305;5473.863153421037;5495.862603434946;5505.614606977473;4983.401991761012;5258.842234732964;5326.467353264643;5383.5962302826665;5549.639273447213;5365.624406291522;5366.436524165063;5367.731613419392;5381.461853814587;5400.433874176445 00:05:08 INFO - PROCESS | 27573 | |5;yui.html;709.6416309763571;731.8024133483956;767.9769606911791;795.5544894858878;829.7925518620345;15261.389544418224;16198.271077801497;16276.75584866228;16343.019418834863;16343.83656163438;14823.258837058158;14959.40162393503;14963.850361496381;14969.625759356022;14979.176145312003;642.299893116503;765.1048273519995;779.4426984705948;787.873940169573;792.6829268292671;779.8830175473668;780.3640033372811;780.9687612495493;781.4295564238109;788.0385929166408;823.9917600824004;825.7440193539992;826.0252901576138;826.6197549127379;831.4720152703017;814.6822739131743;818.3003531980161;818.7380038387719;820.4543978254453;821.1172989036779;501.8507617555793;509.9184130539133;515.4227265462669;602.4939051197016;613.1569092497815;15522.534323970185;15547.689046219013;15602.921985390114;15678.294476748573;15686.450974215963;13861.376238069312;14317;14458.927705361406;14481.565553033426;14636.634084147876;13459.798103028463;13571.524996625169;13613.251271180081;13721;14366.281685915663;4486.12520558488;4487.775611219426;4532.229520981441;4591.403117594725;4620.514845941161;81770.59114704389;82068.58965705193;83217;84254;84716.57641711814;10941.671749847517;10951.233413660968;11092;11112.833307500394;11467.046125012996;19175.041247937548;19192.616147676967;19193.424197273824;19222.135003924857;19223.327183548787;7887.921120788718;7921.247481489249;8131;8769.079246679006;8981.910180898238;4740.7392593407;4884.218525035977;4940.505949405031;4948.5546300832375;5042.369703787027;3565.714742820568;3624.8187590620364;3653.707703383723;3663.9450408243897;3679.9264014720093;608.8660494691161;629.9464545513591;631.2992578238152;637.7289651897925;640.0271587187449;639.6130341143635;689.3692271571556;698.0018573440029;715.7351779841492;721.9639018049077;9798;9829.655962041294;9949.452780097019;10036.717267079455;10082.69751907444;716.8387112899555;725.8693435181718;733.3876213361796;742.721479445208;747.6523416611302;4699.17764391237;4777.283407488904;4906.705597664151;5045.192769156917;5082.237664350377;75867;86909;124251.75748242583;124921.37539312245;136833;109702;113389.43305283421;114200;118783.40608296903;119217;71592.28407715961;75811;88697.55651221832;90422.54788726014;90663;8315.251627353568;9227.630894764134;9252.352335336462;9399.906000939904;9504.144626983605;52970;53255.46744532505;53580;54701;54731;71639;73576;77719;77947.61026194833;78276;13429.932850335686;13685.24731139757;13708;13926.791098133739;14588.489402870848;14433.711325773216;14554.708905821613;15029.774553381927;15781.921090394475;17286.82713172852;14327.212003339917;14657.56027319182;15369.1546964918;15682.151150622476;15859.762103568686;11933.3436660981;13892.657621707982;14021;14049.648758781112;14489.927550362181;13991.440342386191;14308.588932648934;14345.64135896611;14416.062955907832;14493.492727754478;11060.944695276472;11291.833770405541;12108.394580271128;12293.56972505958;12397.132200745862;11577.189596728485;11682.94158529202;11899.345535995604;11941.283522988648;12030.819537707117 00:05:08 INFO - PROCESS | 27573 | __end_tp_report 00:05:08 INFO - PROCESS | 27573 | __start_cc_report 00:05:08 INFO - PROCESS | 27573 | _x_x_mozilla_cycle_collect,903 00:05:08 INFO - PROCESS | 27573 | __end_cc_report 00:05:08 INFO - PROCESS | 27573 | __startTimestamp1472713508690__endTimestamp 00:05:08 INFO - PROCESS | 27573 | 00:05:08 INFO - PROCESS | 27573 | ------- Summary: start ------- 00:05:08 INFO - PROCESS | 27573 | Number of tests: 6 00:05:08 INFO - PROCESS | 27573 | 00:05:08 INFO - PROCESS | 27573 | [#0] dojo.html Cycles:165 Average:5305.01 Median:5508.61 stddev:594.08 (10.8%) stddev-sans-first:568.36 00:05:08 INFO - PROCESS | 27573 | Values: 3025.8 3033.6 3046.2 3072.2 3102.4 5211.1 5260.1 5287.5 5353.1 5390.1 5328.9 5340.7 5402.2 5416.6 5508.6 4789.4 4832.3 4856.8 4894.1 4899.1 4804.0 4907.5 4985.7 4993.0 4997.1 4887.4 4932.2 4938.5 4946.2 4988.5 4757.9 4772.5 4785.9 4812.0 4858.4 4776.1 4776.2 4781.2 4810.6 4849.9 5308.6 5320.8 5336.0 5354.0 5369.1 5487.1 5539.4 5608.9 5613.2 5645.3 5634.9 5667.5 5679.8 5683.2 5742.2 5293.9 5296.5 5299.1 5300.5 5319.1 5632.1 5765.2 5782.9 5852.4 5852.6 5616.6 5621.5 5622.6 5629.6 5688.3 5705.4 5710.8 5712.3 5713.7 5714.9 5490.1 5574.3 5619.9 5633.2 5679.0 5636.3 5637.5 5638.2 5646.4 5706.5 4402.6 4419.0 4421.3 4423.9 4428.3 3876.5 3883.9 3916.9 3959.4 3979.5 5431.9 5438.7 5439.4 5475.0 5503.8 5674.7 5724.0 5725.6 5736.3 5739.1 5256.8 5390.8 5410.2 5453.3 5479.0 5012.7 5013.1 5019.0 5065.3 5075.8 5881.4 5929.1 5935.0 5936.7 5946.5 5606.5 5645.5 5722.7 5743.2 5815.9 5899.9 5905.7 5907.1 5965.5 5970.9 5615.9 5624.8 5627.8 5630.0 5631.7 5556.4 5644.7 5702.7 5720.6 5731.3 5612.0 5612.3 5614.4 5660.8 5690.0 5536.8 5541.6 5586.8 5588.9 5599.4 5440.0 5515.8 5568.3 5571.2 5588.1 5620.5 5622.7 5623.6 5698.6 5703.6 5481.9 5488.5 5493.6 5493.8 5507.4 00:05:08 INFO - PROCESS | 27573 | 00:05:08 INFO - PROCESS | 27573 | [#1] ext.html Cycles:180 Average:57779.66 Median:3517.79 stddev:114611.51 (3258.1%) stddev-sans-first:114852.52 00:05:08 INFO - PROCESS | 27573 | Values: 566.9 606.0 606.9 616.9 621.1 32369.0 42766.6 43813.0 43887.0 43982.1 45072.8 45588.8 45609.8 45613.5 45630.0 8103.8 8777.4 8931.0 8938.4 8941.1 7860.6 8106.5 10541.3 10811.7 10816.9 10933.7 10984.0 10986.6 10990.0 10993.0 10846.0 10897.4 10904.8 10927.0 10939.5 10715.3 10763.9 10765.9 10772.8 10779.8 42669.4 42926.4 42946.6 42963.6 43012.8 3464.2 3475.2 3483.4 3552.1 3562.4 31717.7 32409.2 32424.7 33084.2 33506.7 4337.2 4395.7 4422.6 4627.0 4660.6 332242.0 332660.0 334379.0 334649.0 334676.0 58049.4 58366.7 58508.7 58662.0 58670.0 36916.6 37890.8 37904.1 61172.0 61443.4 18012.5 18141.9 18150.2 18171.6 18185.5 13919.3 14008.9 14046.7 14050.8 14056.5 17051.2 18730.7 18891.3 19063.5 19719.5 7062.6 7317.0 7346.3 7358.6 7367.4 929.7 997.7 1037.8 1092.8 1105.6 31849.8 32105.0 32304.4 32351.7 32466.7 1069.7 1114.8 1118.9 1144.4 1167.7 15803.5 17026.2 17049.3 17061.7 17157.0 584372.0 589424.0 589776.0 590078.0 591723.0 58270.7 58832.4 58935.0 58952.7 58969.0 1221.8 1297.0 1352.2 1355.9 1362.8 31005.0 31987.5 33016.8 33068.3 33140.7 50171.2 50856.0 50905.2 50951.7 50999.5 314038.0 315210.0 315842.0 316308.0 316546.0 28171.2 29185.7 29275.0 30540.1 30547.0 47710.3 47954.8 48421.5 48701.5 49463.5 39630.2 39908.2 40246.8 40484.2 41225.4 39118.4 39675.8 40123.0 40747.6 41651.8 38802.8 39777.0 40584.0 40799.0 40804.2 31485.4 35902.0 35985.6 36769.4 36805.6 27011.0 27161.9 27335.6 27766.6 27781.9 00:05:08 INFO - PROCESS | 27573 | 00:05:08 INFO - PROCESS | 27573 | [#2] jquery.html Cycles:180 Average:28482.07 Median:404.63 stddev:79598.12 (19671.6%) stddev-sans-first:79793.43 00:05:08 INFO - PROCESS | 27573 | Values: 375.2 393.8 401.4 407.9 418.8 15874.7 18719.8 18795.6 19095.3 19804.4 19268.5 19557.3 19649.9 19813.1 20143.0 5467.9 5580.7 5775.3 5789.0 5826.6 5557.9 5738.1 5739.9 5743.1 5744.2 5531.3 5673.5 5696.7 5700.1 5768.8 5687.4 5721.0 5725.8 5730.9 5732.5 4959.7 5108.7 5128.5 5129.8 5144.3 22484.9 22866.5 22894.5 23042.8 23226.8 2266.1 2444.8 2500.9 2557.4 3054.3 9937.3 10356.7 10390.8 14424.4 14500.4 1896.4 1899.3 1901.7 1910.7 1928.6 80605.0 84390.2 84404.6 84778.6 85263.0 10708.5 10966.7 11041.4 11139.8 11213.4 15164.9 15175.7 15181.2 15192.1 15283.2 8136.0 8182.9 8320.7 8362.8 8600.3 8091.6 8211.8 8363.8 8404.8 8426.8 4530.7 4538.9 4546.8 4643.9 4745.5 1975.2 2017.2 2038.2 2071.7 2097.8 896.3 922.7 969.9 978.7 1003.2 18009.3 19599.0 19736.6 19852.8 20017.5 936.4 957.4 957.6 1008.6 1046.2 10652.7 10850.5 10899.9 10908.5 10932.6 465431.0 474606.0 490937.0 492844.0 494261.0 58965.0 59314.4 60174.1 93124.0 99724.0 972.6 983.3 1003.0 1006.7 1041.8 17058.4 17407.5 18106.7 18114.7 18159.6 42349.4 43001.6 43258.0 43379.8 43839.8 55977.0 59252.0 60074.0 60472.0 61730.4 8166.6 8357.2 8658.3 8697.3 8857.2 8337.9 12209.5 13046.6 13600.8 13773.4 11305.3 11505.0 11572.0 11772.5 11837.2 11609.4 11646.2 11647.2 11727.6 12208.1 11223.9 11364.8 11405.0 11536.5 11875.5 8102.6 8148.2 8367.3 8812.4 8849.7 8212.8 10432.0 11356.0 11588.7 11835.5 00:05:08 INFO - PROCESS | 27573 | 00:05:08 INFO - PROCESS | 27573 | [#3] mootools.html Cycles:180 Average:5927.59 Median:4755.70 stddev:3358.28 (70.6%) stddev-sans-first:3350.02 00:05:08 INFO - PROCESS | 27573 | Values: 1343.5 1472.9 1492.5 1617.3 1773.9 8468.6 8751.2 8836.6 9053.6 9058.0 8874.1 8972.5 8988.7 8988.8 8991.0 2488.7 2513.0 2514.4 2515.3 2518.7 2420.9 2505.1 2511.9 2515.2 2516.1 2427.5 2472.7 2491.8 2494.9 2497.0 2396.1 2416.7 2423.7 2449.3 2464.0 2401.4 2418.5 2420.9 2432.3 2444.5 8490.1 8795.7 8884.6 8900.2 8901.4 5236.0 5404.3 5436.1 5442.4 5446.1 3561.8 3606.6 3624.1 3642.7 3727.5 1186.9 1205.6 1214.0 1216.3 1235.9 12553.8 12761.9 12763.4 12796.1 12800.6 4814.0 4845.0 4912.7 4924.8 5051.0 10722.1 10729.3 10732.2 10733.7 10740.5 5512.6 5526.3 5561.9 5566.2 5578.8 4492.0 4664.6 4697.4 4944.9 5101.7 4095.4 4102.7 4163.8 4222.6 4251.3 1302.4 1366.8 1382.6 1389.3 1402.4 785.3 918.5 934.8 935.2 945.2 5794.9 5935.1 5949.6 6093.9 6288.4 452.1 452.8 455.7 455.9 458.9 4176.6 4213.9 4271.0 4313.3 4337.1 10937.0 11417.1 11421.5 11446.5 11448.7 10919.2 11083.8 11198.5 11210.8 11219.1 7955.7 8025.2 8030.4 8034.7 8075.2 5898.1 5937.8 5983.7 6005.7 6108.1 10134.2 10287.3 10345.8 10347.4 10360.2 9543.0 9887.8 10224.5 10475.7 10725.1 7486.2 7580.0 7679.3 7821.3 7822.5 8319.2 8455.4 8664.6 8681.7 8803.7 7639.9 7682.2 7699.2 7848.8 7856.6 7608.6 7672.8 7743.1 7744.2 7782.4 7225.2 7229.7 7308.6 7313.1 7317.4 5319.5 5344.7 5356.9 5473.5 5515.8 5330.9 5485.1 5500.1 5509.7 5570.0 00:05:08 INFO - PROCESS | 27573 | 00:05:08 INFO - PROCESS | 27573 | [#4] prototype.html Cycles:180 Average:4628.40 Median:5325.07 stddev:1362.51 (25.6%) stddev-sans-first:1327.44 00:05:08 INFO - PROCESS | 27573 | Values: 321.8 322.3 329.6 339.7 340.3 5245.8 5300.5 5307.7 5332.9 5343.9 5234.2 5237.9 5267.0 5323.7 5334.9 3884.4 3966.6 4063.8 4086.3 4136.3 4107.9 4108.2 4113.2 4127.9 4136.5 4061.8 4068.0 4079.7 4081.0 4086.5 3867.1 3975.4 4022.1 4054.4 4064.2 3556.4 3587.4 3590.3 3593.6 3596.6 5111.5 5134.9 5157.2 5158.5 5164.3 4536.2 4656.8 4670.9 4684.2 4694.8 5432.5 5437.2 5446.7 5455.9 5462.5 3929.2 3954.9 3960.0 3963.3 3970.4 5497.6 5645.2 5680.5 5695.2 5761.3 3988.7 4021.3 4031.3 4033.4 4036.9 4076.1 4092.3 4113.6 4124.0 4127.8 4593.8 4605.2 4628.8 4689.6 4723.0 5456.9 5461.3 5485.8 5499.9 5531.8 1390.9 1401.5 1410.3 1414.9 1416.2 859.0 917.4 925.9 935.2 938.2 5043.3 5179.8 5287.3 5303.3 5320.7 5626.6 5632.5 5648.4 5661.2 5665.7 5157.0 5323.7 5374.6 5375.7 5381.8 2925.5 2976.4 2976.6 2979.7 2987.7 5803.7 5803.7 5820.7 5827.7 5833.2 5504.6 5735.3 5748.6 5749.5 5773.8 5800.9 5822.3 5827.7 5830.7 5868.3 5505.9 5617.6 5657.5 5660.7 5683.5 5550.3 5550.7 5571.6 5593.8 5608.8 5781.5 5791.0 5813.0 5819.5 5838.9 4002.9 4100.8 4102.8 4120.2 4126.5 5636.7 5643.0 5649.4 5656.6 5663.3 5525.5 5570.7 5578.2 5589.6 5604.5 5367.6 5483.5 5487.4 5492.7 5503.2 5462.4 5473.2 5473.9 5495.9 5505.6 4983.4 5258.8 5326.5 5383.6 5549.6 5365.6 5366.4 5367.7 5381.5 5400.4 00:05:08 INFO - PROCESS | 27573 | 00:05:08 INFO - PROCESS | 27573 | [#5] yui.html Cycles:180 Average:21507.03 Median:4719.96 stddev:31316.99 (663.5%) stddev-sans-first:31365.91 00:05:08 INFO - PROCESS | 27573 | Values: 709.6 731.8 768.0 795.6 829.8 15261.4 16198.3 16276.8 16343.0 16343.8 14823.3 14959.4 14963.9 14969.6 14979.2 642.3 765.1 779.4 787.9 792.7 779.9 780.4 781.0 781.4 788.0 824.0 825.7 826.0 826.6 831.5 814.7 818.3 818.7 820.5 821.1 501.9 509.9 515.4 602.5 613.2 15522.5 15547.7 15602.9 15678.3 15686.5 13861.4 14317.0 14458.9 14481.6 14636.6 13459.8 13571.5 13613.3 13721.0 14366.3 4486.1 4487.8 4532.2 4591.4 4620.5 81770.6 82068.6 83217.0 84254.0 84716.6 10941.7 10951.2 11092.0 11112.8 11467.0 19175.0 19192.6 19193.4 19222.1 19223.3 7887.9 7921.2 8131.0 8769.1 8981.9 4740.7 4884.2 4940.5 4948.6 5042.4 3565.7 3624.8 3653.7 3663.9 3679.9 608.9 629.9 631.3 637.7 640.0 639.6 689.4 698.0 715.7 722.0 9798.0 9829.7 9949.5 10036.7 10082.7 716.8 725.9 733.4 742.7 747.7 4699.2 4777.3 4906.7 5045.2 5082.2 75867.0 86909.0 124251.8 124921.4 136833.0 109702.0 113389.4 114200.0 118783.4 119217.0 71592.3 75811.0 88697.6 90422.5 90663.0 8315.3 9227.6 9252.4 9399.9 9504.1 52970.0 53255.5 53580.0 54701.0 54731.0 71639.0 73576.0 77719.0 77947.6 78276.0 13429.9 13685.2 13708.0 13926.8 14588.5 14433.7 14554.7 15029.8 15781.9 17286.8 14327.2 14657.6 15369.2 15682.2 15859.8 11933.3 13892.7 14021.0 14049.6 14489.9 13991.4 14308.6 14345.6 14416.1 14493.5 11060.9 11291.8 12108.4 12293.6 12397.1 11577.2 11682.9 11899.3 11941.3 12030.8 00:05:08 INFO - PROCESS | 27573 | -------- Summary: end -------- 00:05:08 INFO - PROCESS | 27573 | 00:05:09 INFO - TEST-INFO | 27573: exit 0 00:05:09 INFO - TEST-OK | dromaeo_css | took 1116271ms 00:05:09 INFO - TEST-START | kraken 00:05:09 INFO - operating with platform_type : linux_ 00:05:09 INFO - Initialising browser for kraken test... 00:05:09 INFO - TEST-INFO | started process 27877 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT2Wfsc/profile http://localhost:60131/getInfo.html) 00:05:14 INFO - PROCESS | 27877 | __metrics Screen width/height:1600/1200 00:05:14 INFO - PROCESS | 27877 | colorDepth:24 00:05:14 INFO - PROCESS | 27877 | Browser inner width/height: 1024/697 00:05:14 INFO - PROCESS | 27877 | __metrics 00:05:14 INFO - PROCESS | 27877 | 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" 00:05:14 INFO - PROCESS | 27877 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:05:15 INFO - PROCESS | 27877 | console.error: PushService: 00:05:15 INFO - PROCESS | 27877 | stateChangeProcessEnqueue: Error transitioning state 00:05:15 INFO - PROCESS | 27877 | UnknownError 00:05:15 INFO - TEST-INFO | 27877: exit 0 00:05:15 INFO - Browser initialized. 00:05:15 INFO - Running cycle 1/1 for kraken test... 00:05:15 INFO - TEST-INFO | started process 27960 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT2Wfsc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:05:27 INFO - PROCESS | 27960 | RSS: Main: 180346880 00:05:27 INFO - PROCESS | 27960 | 00:05:30 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60131/tests/kraken/driver.html?testName=audio-beat-detection) 00:05:31 INFO - PROCESS | 27960 | RSS: Main: 237846528 00:05:31 INFO - PROCESS | 27960 | 00:05:33 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60131/tests/kraken/driver.html?testName=audio-dft) 00:05:34 INFO - PROCESS | 27960 | RSS: Main: 224817152 00:05:34 INFO - PROCESS | 27960 | 00:05:37 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60131/tests/kraken/driver.html?testName=audio-fft) 00:05:38 INFO - PROCESS | 27960 | RSS: Main: 301936640 00:05:38 INFO - PROCESS | 27960 | 00:05:40 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60131/tests/kraken/driver.html?testName=audio-oscillator) 00:05:40 INFO - PROCESS | 27960 | RSS: Main: 212131840 00:05:40 INFO - PROCESS | 27960 | 00:05:42 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60131/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:05:43 INFO - PROCESS | 27960 | RSS: Main: 213876736 00:05:43 INFO - PROCESS | 27960 | 00:05:46 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60131/tests/kraken/driver.html?testName=imaging-darkroom) 00:05:47 INFO - PROCESS | 27960 | RSS: Main: 252370944 00:05:47 INFO - PROCESS | 27960 | 00:05:50 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60131/tests/kraken/driver.html?testName=imaging-desaturate) 00:05:51 INFO - PROCESS | 27960 | RSS: Main: 251260928 00:05:51 INFO - PROCESS | 27960 | 00:05:53 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60131/tests/kraken/driver.html?testName=json-parse-financial) 00:05:54 INFO - PROCESS | 27960 | RSS: Main: 251449344 00:05:54 INFO - PROCESS | 27960 | 00:05:55 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60131/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:05:56 INFO - PROCESS | 27960 | RSS: Main: 213651456 00:05:56 INFO - PROCESS | 27960 | 00:05:58 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60131/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:05:58 INFO - PROCESS | 27960 | RSS: Main: 220164096 00:05:58 INFO - PROCESS | 27960 | 00:06:00 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60131/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:06:01 INFO - PROCESS | 27960 | RSS: Main: 222208000 00:06:01 INFO - PROCESS | 27960 | 00:06:04 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60131/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:06:04 INFO - PROCESS | 27960 | RSS: Main: 223281152 00:06:04 INFO - PROCESS | 27960 | 00:06:07 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60131/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:06:08 INFO - PROCESS | 27960 | RSS: Main: 221691904 00:06:08 INFO - PROCESS | 27960 | 00:06:09 INFO - PROCESS | 27960 | Cycle 1(1): loaded http://localhost:60131/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60131/tests/kraken/driver.html?testName=ai-astar) 00:06:10 INFO - PROCESS | 27960 | RSS: Main: 272818176 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | __start_tp_report 00:06:10 INFO - PROCESS | 27960 | _x_x_mozilla_page_load 00:06:10 INFO - PROCESS | 27960 | _x_x_mozilla_page_load_details 00:06:10 INFO - PROCESS | 27960 | |i|pagename|runs| 00:06:10 INFO - PROCESS | 27960 | |0;ai-astar;112;111;129;111;111;111;112;110;112;111 00:06:10 INFO - PROCESS | 27960 | |1;audio-beat-detection;147;118;147;181;119;142;142;119;184;125 00:06:10 INFO - PROCESS | 27960 | |2;audio-dft;159;159;161;160;160;161;161;160;160;161 00:06:10 INFO - PROCESS | 27960 | |3;audio-fft;75;75;75;178;77;76;76;76;169;76 00:06:10 INFO - PROCESS | 27960 | |4;audio-oscillator;82;82;153;85;84;82;152;82;82;148 00:06:10 INFO - PROCESS | 27960 | |5;imaging-gaussian-blur;117;117;117;118;116;116;117;116;117;117 00:06:10 INFO - PROCESS | 27960 | |6;imaging-darkroom;145;145;145;146;145;146;146;146;145;145 00:06:10 INFO - PROCESS | 27960 | |7;imaging-desaturate;96;96;96;95;96;96;96;95;96;95 00:06:10 INFO - PROCESS | 27960 | |8;json-parse-financial;52;51;52;53;53;53;53;53;54;52 00:06:10 INFO - PROCESS | 27960 | |9;json-stringify-tinderbox;62;63;62;62;62;62;65;62;63;62 00:06:10 INFO - PROCESS | 27960 | |10;stanford-crypto-aes;81;78;78;79;79;79;78;79;77;77 00:06:10 INFO - PROCESS | 27960 | |11;stanford-crypto-ccm;135;131;123;125;125;134;126;129;170;133 00:06:10 INFO - PROCESS | 27960 | |12;stanford-crypto-pbkdf2;164;166;162;164;162;166;166;162;165;164 00:06:10 INFO - PROCESS | 27960 | |13;stanford-crypto-sha256-iterative;66;66;65;67;63;63;63;63;65;65 00:06:10 INFO - PROCESS | 27960 | __end_tp_report 00:06:10 INFO - PROCESS | 27960 | __start_cc_report 00:06:10 INFO - PROCESS | 27960 | _x_x_mozilla_cycle_collect,1749 00:06:10 INFO - PROCESS | 27960 | __end_cc_report 00:06:10 INFO - PROCESS | 27960 | __startTimestamp1472713570100__endTimestamp 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | ------- Summary: start ------- 00:06:10 INFO - PROCESS | 27960 | Number of tests: 14 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#0] ai-astar Cycles:10 Average:113.00 Median:111.50 stddev:5.66 (5.1%) stddev-sans-first:5.99 00:06:10 INFO - PROCESS | 27960 | Values: 112.0 111.0 129.0 111.0 111.0 111.0 112.0 110.0 112.0 111.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#1] audio-beat-detection Cycles:10 Average:142.40 Median:144.50 stddev:24.17 (16.7%) stddev-sans-first:25.58 00:06:10 INFO - PROCESS | 27960 | Values: 147.0 118.0 147.0 181.0 119.0 142.0 142.0 119.0 184.0 125.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:0.79 (0.5%) stddev-sans-first:0.71 00:06:10 INFO - PROCESS | 27960 | Values: 159.0 159.0 161.0 160.0 160.0 161.0 161.0 160.0 160.0 161.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#3] audio-fft Cycles:10 Average:95.30 Median:76.00 stddev:41.27 (54.3%) stddev-sans-first:43.12 00:06:10 INFO - PROCESS | 27960 | Values: 75.0 75.0 75.0 178.0 77.0 76.0 76.0 76.0 169.0 76.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:82.00 stddev:33.02 (40.3%) stddev-sans-first:34.13 00:06:10 INFO - PROCESS | 27960 | Values: 82.0 82.0 153.0 85.0 84.0 82.0 152.0 82.0 82.0 148.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 00:06:10 INFO - PROCESS | 27960 | Values: 117.0 117.0 117.0 118.0 116.0 116.0 117.0 116.0 117.0 117.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 00:06:10 INFO - PROCESS | 27960 | Values: 145.0 145.0 145.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 00:06:10 INFO - PROCESS | 27960 | Values: 96.0 96.0 96.0 95.0 96.0 96.0 96.0 95.0 96.0 95.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:53.00 stddev:0.84 (1.6%) stddev-sans-first:0.87 00:06:10 INFO - PROCESS | 27960 | Values: 52.0 51.0 52.0 53.0 53.0 53.0 53.0 53.0 54.0 52.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:62.00 stddev:0.97 (1.6%) stddev-sans-first:1.01 00:06:10 INFO - PROCESS | 27960 | Values: 62.0 63.0 62.0 62.0 62.0 62.0 65.0 62.0 63.0 62.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#10] stanford-crypto-aes Cycles:10 Average:78.50 Median:79.00 stddev:1.18 (1.5%) stddev-sans-first:0.83 00:06:10 INFO - PROCESS | 27960 | Values: 81.0 78.0 78.0 79.0 79.0 79.0 78.0 79.0 77.0 77.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#11] stanford-crypto-ccm Cycles:10 Average:133.10 Median:132.00 stddev:13.63 (10.3%) stddev-sans-first:14.43 00:06:10 INFO - PROCESS | 27960 | Values: 135.0 131.0 123.0 125.0 125.0 134.0 126.0 129.0 170.0 133.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:164.50 stddev:1.66 (1.0%) stddev-sans-first:1.76 00:06:10 INFO - PROCESS | 27960 | Values: 164.0 166.0 162.0 164.0 162.0 166.0 166.0 162.0 165.0 164.0 00:06:10 INFO - PROCESS | 27960 | 00:06:10 INFO - PROCESS | 27960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.00 stddev:1.51 (2.3%) stddev-sans-first:1.51 00:06:10 INFO - PROCESS | 27960 | Values: 66.0 66.0 65.0 67.0 63.0 63.0 63.0 63.0 65.0 65.0 00:06:10 INFO - PROCESS | 27960 | -------- Summary: end -------- 00:06:10 INFO - PROCESS | 27960 | 00:06:11 INFO - TEST-INFO | 27960: exit 0 00:06:11 INFO - TEST-OK | kraken | took 61641ms 00:06:11 INFO - SUITE-END | took 1177s 00:06:11 INFO - Completed test suite (00:19:37) 00:06:11 INFO - javascript benchmark 00:06:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3025.8487075646217, 3033.6056312679357, 3046.162305366024, 3072.2319420144963, 3102.3640153768465, 5211.088059589576, 5260.053190425722, 5287.4976877196605, 5353.14349704047, 5390.056740070491, 5328.866778330544, 5340.732963351837, 5402.189671549259, 5416.566674666027, 5508.614396992212, 4789.449213340458, 4832.347633069526, 4856.7571621418965, 4894.143524883149, 4899.118158731436, 4803.975980120094, 4907.509249075082, 4985.6759310644875, 4992.975035124838, 4997.075541024904, 4887.413510378778, 4932.210846264598, 4938.530839570253, 4946.15915294399, 4988.501149885019, 4757.857264282077, 4772.522747725235, 4785.928211076836, 4811.975940120277, 4858.368412106439, 4776.0925424169645, 4776.211925032366, 4781.211100168468, 4810.6151507879285, 4849.903001939977, 5308.575313974873, 5320.76056577455, 5336.039512887679, 5354.0, 5369.140937450028, 5487.149491828773, 5539.418361072069, 5608.887822243573, 5613.186088017255, 5645.32256129263, 5634.943650563483, 5667.518260947824, 5679.801206957736, 5683.204351390808, 5742.2247996520355, 5293.947060529384, 5296.4703529647495, 5299.072662284162, 5300.549453296474, 5319.0957537218765, 5632.098864181711, 5765.221695071195, 5782.855428614318, 5852.356240813507, 5852.619579727304, 5616.634918730269, 5621.49406553404, 5622.634528755618, 5629.634073785191, 5688.317401911797, 5705.372409035002, 5710.771569137271, 5712.314522257356, 5713.74288157034, 5714.914276285859, 5490.121580547093, 5574.331080270394, 5619.943800562024, 5633.183188437735, 5678.971605141948, 5636.2954630671165, 5637.548996080303, 5638.182463542846, 5646.378898321182, 5706.543476521952, 4402.647788176938, 4418.955810441918, 4421.270490369053, 4423.911521769546, 4428.31361139024, 3876.457295978565, 3883.86406475772, 3916.8629097981434, 3959.4456776051375, 3979.5423526294267, 5431.891362172734, 5438.728063596884, 5439.374471935699, 5475.0, 5503.807366742145, 5674.659520428788, 5723.971380143073, 5725.570582206351, 5736.340320863071, 5739.110437882135, 5256.816011439581, 5390.784368625211, 5410.215518749759, 5453.318335208099, 5479.0, 5012.674176178537, 5013.147764879979, 5019.0463811875625, 5065.341505604248, 5075.847724568269, 5881.353051164454, 5929.05135178378, 5935.0, 5936.673482958392, 5946.464818166386, 5606.467385598362, 5645.548356131418, 5722.713864306685, 5743.167240749985, 5815.912761308668, 5899.852503687443, 5905.704714764159, 5907.143464197753, 5965.463108320098, 5970.940290597038, 5615.859603509945, 5624.775008999758, 5627.803026894039, 5630.014747419267, 5631.7184140793615, 5556.416576259435, 5644.745986430538, 5702.743376547983, 5720.628159169641, 5731.283589551306, 5612.045952188057, 5612.326520817528, 5614.382417934105, 5660.830175094754, 5690.032694442038, 5536.75084621201, 5541.556675465872, 5586.804461843816, 5588.916166257428, 5599.356074051536, 5440.0, 5515.806946756844, 5568.331800184003, 5571.164325351229, 5588.077967135378, 5620.4660557247, 5622.662640241599, 5623.606347555796, 5698.629589076697, 5703.5722320826935, 5481.945180548143, 5488.451154884639, 5493.615446918678, 5493.807716729895, 5507.394186639386], "value": 5264.975129867143, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.8639526513635, 605.9545534084945, 606.9393060693931, 616.8858761129188, 621.071498110325, 32369.0, 42766.57233427669, 43813.0, 43887.0, 43982.12035759287, 45072.77463612677, 45588.77205613967, 45609.77195114028, 45613.54386456135, 45630.0, 8103.797405064862, 8777.429467084636, 8931.0, 8938.4190027648, 8941.105889411072, 7860.606969651523, 8106.513609183438, 10541.262111652188, 10811.729706757314, 10816.891831081706, 10933.726656833564, 10984.0, 10986.560537578487, 10990.0, 10993.0, 10846.0, 10897.400642964634, 10904.781904361947, 10927.0, 10939.507722152523, 10715.303505272132, 10763.946180269128, 10765.892341076567, 10772.78454430907, 10779.78440431187, 42669.35995960063, 42926.35610465846, 42946.57053429457, 42963.57036429658, 43012.78493607543, 3464.168599536093, 3475.200703838131, 3483.442649176145, 3552.1297282166033, 3562.386352938849, 31717.682823171705, 32409.189770255696, 32424.6757532424, 33084.17289567756, 33506.6649333506, 4337.219300525904, 4395.670324725658, 4422.557744225552, 4626.953730462686, 4660.627149828006, 332242.0, 332660.0, 334379.0, 334649.0, 334676.0, 58049.41950580524, 58366.70816645974, 58508.70745646244, 58662.0, 58670.0, 36916.63083369186, 37890.8105459471, 37904.052398690255, 61172.0, 61443.38556614376, 18012.54968625795, 18141.90929045373, 18150.183241754152, 18171.636567268844, 18185.45443636693, 13919.304034798422, 14008.929955350159, 14046.71906561863, 14050.789238161437, 14056.508022219174, 17051.232694528775, 18730.719039214422, 18891.338803141825, 19063.52341191481, 19719.50701232452, 7062.611556364346, 7317.0, 7346.302101300368, 7358.595277259695, 7367.410607151415, 929.7443203119196, 997.7056768253383, 1037.775424998494, 1092.7650555130526, 1105.5743538737484, 31849.8407507961, 32105.0, 32304.35391292161, 32351.676483234867, 32466.675333246367, 1069.7165251208437, 1114.793763153819, 1118.9048930840806, 1144.3648774929993, 1167.6672148437824, 15803.525894223192, 17026.23381947815, 17049.31802727902, 17061.658766824596, 17157.0, 584372.0, 589424.0, 589776.0, 590078.0, 591723.0, 58270.708646457344, 58832.41167588355, 58935.0, 58952.7052364744, 58969.0, 1221.8045112781913, 1296.9870301296867, 1352.2157148853444, 1355.9050866439254, 1362.8023936529148, 31005.0, 31987.520187197675, 33016.83491582527, 33068.33863322768, 33140.668593313756, 50171.2200364942, 50856.0, 50905.236421452966, 50951.74524127356, 50999.49000509948, 314038.0, 315210.0, 315842.0, 316308.0, 316546.0, 28171.154865354074, 29185.70814291915, 29275.0, 30540.08379748611, 30547.0, 47710.284345735534, 47954.76022619865, 48421.51578484311, 48701.512984869696, 49463.50536494589, 39630.20739585134, 39908.2018359637, 40246.79876600598, 40484.19031619292, 41225.38161927633, 39118.41322380109, 39675.80162099171, 40123.0, 40747.59252407438, 41651.750447485414, 38802.80598596989, 39777.0, 40584.0, 40799.0, 40804.1839163221, 31485.37029259356, 35902.0, 35985.640143598226, 36769.448458272615, 36805.63194368022, 27011.0, 27161.86419067892, 27335.58996615092, 27766.585515825027, 27781.861090694416], "value": 20297.12768863664, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.23639393833554, 393.75980651802394, 401.3838757507227, 407.8837531303579, 418.8157210827236, 15874.68250634988, 18719.812801871994, 18795.62408751824, 19095.331663391786, 19804.40586782399, 19268.518287042833, 19557.315493957678, 19649.901750491226, 19813.108410121542, 20143.0, 5467.863303417426, 5580.693061881595, 5775.306963164426, 5789.015867302548, 5826.57248974002, 5557.944420555783, 5738.139279108125, 5739.942600574003, 5743.081107022877, 5744.16709577111, 5531.308586426697, 5673.546116310686, 5696.715164241792, 5700.087985922253, 5768.798092066779, 5687.374388817227, 5721.0, 5725.799597014085, 5730.885382292352, 5732.541396688255, 4959.6528243023, 5108.7445627718835, 5128.487151284879, 5129.84610461683, 5144.305518754958, 22484.88757556218, 22866.542669146525, 22894.542109157723, 23042.76957230423, 23226.76773232263, 2266.1388672304583, 2444.792192663626, 2500.94998100037, 2557.4117952870756, 3054.266975925784, 9937.304388692794, 10356.689299321033, 10390.792184156275, 14424.423023079064, 14500.41998320066, 1896.4026331705468, 1899.325739362534, 1901.6672082385528, 1910.7324974503442, 1928.556432020629, 80605.0, 84390.15609843885, 84404.57797711034, 84778.57610711906, 85263.0, 10708.518116684612, 10966.670999870017, 11041.447927603587, 11139.777204455866, 11213.439328033566, 15164.924175379052, 15175.696486070217, 15181.240937953278, 15192.088474691333, 15283.159426231441, 8136.0, 8182.918170818216, 8320.70877519296, 8362.8327433451, 8600.268977136888, 8091.635876385577, 8211.794705132419, 8363.790905227417, 8404.78988025292, 8426.789330266793, 4530.728156310632, 4538.931916021263, 4546.840860569864, 4643.907121857612, 4745.525447455296, 1975.190172029461, 2017.2032047341218, 2038.2254743197786, 2071.6685330347073, 2097.7587577428485, 896.3277541843618, 922.727795300388, 969.9030096990385, 978.6917121106828, 1003.2275148135894, 18009.279628814962, 19599.0, 19736.605267894563, 19852.801471985094, 20017.499562510762, 936.4194199596294, 957.4494665567327, 957.5738796235747, 1008.5511947183579, 1046.1839764983324, 10652.733681658021, 10850.511726972147, 10899.910008999037, 10908.454577271104, 10932.56269749201, 465431.0, 474606.0, 490937.0, 492844.0, 494261.0, 58965.0, 59314.40685593089, 60174.07998080189, 93124.0, 99724.0, 972.6498460554195, 983.2920297385871, 1003.0320740485466, 1006.6812475656913, 1041.8124737547162, 17058.402955896483, 17407.545019940157, 18106.72839907376, 18114.72827907556, 18159.636807263516, 42349.36475952925, 43001.56998430101, 43258.0, 43379.78310108429, 43839.78080109707, 55977.0, 59252.0, 60074.0, 60472.0, 61730.38269617426, 8166.550839703873, 8357.164283571594, 8658.307335413278, 8697.304215662605, 8857.158569935846, 8337.916620833714, 12209.450574723982, 13046.608601741962, 13600.795988059988, 13773.38019789132, 11305.26515776472, 11505.0, 11572.0, 11772.52909883595, 11837.230580012272, 11609.419529023346, 11646.184767066563, 11647.242929209575, 11727.589534366256, 12208.084393670688, 11223.88776112261, 11364.772704546029, 11405.0, 11536.538538458368, 11875.524979000744, 8102.635381407968, 8148.22591853773, 8367.330613551023, 8812.427192232484, 8849.698600109652, 8212.794680133044, 10432.0, 11356.0, 11588.6523404298, 11835.467403967014], "value": 9177.714638392743, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.489473999881, 1472.9189894555798, 1492.4552538323512, 1617.3045590396125, 1773.9467815965518, 8468.57657117144, 8751.212390884819, 8836.558172091402, 9053.637854485813, 9057.95471022644, 8874.068222836606, 8972.506512141847, 8988.68539601114, 8988.820223595541, 8990.955045224764, 2488.7262401135818, 2512.9748702513016, 2514.383975925901, 2515.308290220186, 2518.659980902582, 2420.878956052199, 2505.0856437400307, 2511.8744062796877, 2515.2328539795353, 2516.0564788204424, 2427.5387676341525, 2472.678551788265, 2491.825572209937, 2494.9376265593414, 2497.0, 2396.113438027923, 2416.734159242482, 2423.72127205371, 2449.265220433863, 2464.039024780337, 2401.4116541447456, 2418.4558474343307, 2420.9152679656304, 2432.3311089450367, 2444.498877730061, 8490.10853860342, 8795.736127916172, 8884.600192991331, 8900.154485323885, 8901.376903616749, 5236.0, 5404.297441332641, 5436.10304299794, 5442.428545002796, 5446.128619420913, 3561.821908904544, 3606.6393360663724, 3624.0758606555423, 3642.690371318441, 3727.4781530585738, 1186.8575770907546, 1205.6021512900722, 1213.9575114871025, 1216.2702378572874, 1235.9258444493269, 12553.811692824524, 12761.872381276162, 12763.36183190846, 12796.104272700915, 12800.551980680631, 4813.989062296957, 4844.95155048445, 4912.70523768575, 4924.75376231187, 5050.974745126325, 10722.088622467178, 10729.302595331279, 10732.2487425881, 10733.677989660322, 10740.516676749565, 5512.5589952803675, 5526.309211348582, 5561.916571251434, 5566.165075238666, 5578.832635020955, 4491.955080449154, 4664.55686709762, 4697.419981358688, 4944.851654450372, 5101.719405432663, 4095.415087177948, 4102.671786257152, 4163.83344666216, 4222.641075508554, 4251.319788833772, 1302.3553341096217, 1366.8086467894502, 1382.6128683968304, 1389.3192335755407, 1402.4109873853006, 785.3167744062588, 918.536139249688, 934.761635782871, 935.1957316707626, 945.1540870920487, 5794.88410231793, 5935.139404786368, 5949.613275137102, 6093.939060609426, 6288.434040936338, 452.0642270500099, 452.8407277369943, 455.7220095741595, 455.9179347717443, 458.8852786803299, 4176.644985176294, 4213.91572168561, 4271.0, 4313.2883074292395, 4337.110892267077, 10937.0, 11417.092658941387, 11421.486033128529, 11446.484908179153, 11448.656540303804, 10919.181061420426, 11083.778324433466, 11198.496067676973, 11210.775784484265, 11219.102471802236, 7955.721549745731, 8025.157358477276, 8030.43786934909, 8034.67861285542, 8075.192480751878, 5898.144769008555, 5937.7921772737745, 5983.730732116978, 6005.699715014144, 6108.11432342299, 10134.189264858942, 10287.279890407599, 10345.793084138426, 10347.43089130105, 10360.222983276435, 9542.952285238529, 9887.752806179904, 10224.539895704562, 10475.685729428129, 10725.08836748885, 7486.176520582622, 7579.962100189464, 7679.347255483346, 7821.256980586835, 7822.45242833019, 8319.209675080858, 8455.365847561581, 8664.566771661517, 8681.652733890574, 8803.691870784747, 7639.923600764144, 7682.193369696101, 7699.191584883731, 7848.803779905548, 7856.607169641609, 7608.581528016012, 7672.808179795326, 7743.070831500031, 7744.186860379579, 7782.416318776001, 7225.24134965821, 7229.674664639999, 7308.634568271459, 7313.122425308997, 7317.3780228679025, 5319.515029400452, 5344.7172678557645, 5356.946430535645, 5473.534749546333, 5515.834524964257, 5330.8933821324135, 5485.149801780731, 5500.092484739973, 5509.696966666872, 5570.0], "value": 4679.742694996908, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.8406888590147, 322.32633795367684, 329.62258214344575, 339.67560979264806, 340.29049431934413, 5245.790168393264, 5300.462865768932, 5307.734613269336, 5332.893342133155, 5343.919841202385, 5234.214867769836, 5237.86905327367, 5266.972940276648, 5323.653962492445, 5334.879675268198, 3884.35908075168, 3966.583508731585, 4063.756174629517, 4086.3053280942313, 4136.27615167346, 4107.8973025674295, 4108.157827645326, 4113.177364527091, 4127.876163715094, 4136.503619565657, 4061.776602286873, 4067.9593204068024, 4079.653229475498, 4081.0205550667924, 4086.5300490443533, 3867.091233560111, 3975.403689446577, 4022.1352409231863, 4054.3715724062818, 4064.166845796605, 3556.395412779808, 3587.3722098632634, 3590.317839610492, 3593.586737525166, 3596.62235465275, 5111.539961403491, 5134.922976155361, 5157.174852023696, 5158.484151584811, 5164.328637277167, 4536.228841096989, 4656.790444429975, 4670.898095856844, 4684.227102528079, 4694.788734506956, 5432.478905906352, 5437.184422336642, 5446.673199608036, 5455.945440545584, 5462.535684466785, 3929.174873276587, 3954.8728612345344, 3959.98020009901, 3963.286608410485, 3970.4441378207075, 5497.587680923947, 5645.2096706461125, 5680.545556355523, 5695.202671625976, 5761.279840019997, 3988.720789544762, 4021.2560676274984, 4031.274370613259, 4033.394990751352, 4036.919261614751, 4076.144009757984, 4092.263392589303, 4113.588641135922, 4124.0, 4127.834886604503, 4593.839215627437, 4605.240135377692, 4628.791704373311, 4689.554492323224, 4722.9763851181215, 5456.91814622781, 5461.290032295776, 5485.75314110858, 5499.917501237484, 5531.806386776524, 1390.8539603341706, 1401.4884567132924, 1410.3159967415795, 1414.8938829587821, 1416.1927701210418, 858.9613467393982, 917.4403613795579, 925.8842644669417, 935.1630290889751, 938.1650331205233, 5043.3191519145175, 5179.7928082877015, 5287.312649355559, 5303.337082864642, 5320.707361095177, 5626.606137570413, 5632.549396048306, 5648.378678345461, 5661.235733176051, 5665.745041473144, 5157.0, 5323.680579165262, 5374.596905232045, 5375.704336261543, 5381.7578208980685, 2925.4880395930627, 2976.389840082821, 2976.553516972428, 2979.657339405953, 2987.731104200632, 5803.662893836061, 5803.680797556175, 5820.650760954356, 5827.708614569255, 5833.154192642043, 5504.587155963319, 5735.254416925773, 5748.5688573357165, 5749.54003679713, 5773.816367644623, 5800.867531532925, 5822.282426684138, 5827.650340979555, 5830.650160990354, 5868.2664666916635, 5505.944940550543, 5617.606767526234, 5657.5473962082115, 5660.660360378391, 5683.488486036276, 5550.333959924835, 5550.69471179089, 5571.582131340247, 5593.8321850344555, 5608.775648974159, 5781.479666829838, 5791.0, 5813.011787995966, 5819.476247137778, 5838.94161058384, 4002.9199416010933, 4100.835966561423, 4102.815373308149, 4120.175964806991, 4126.525449573338, 5636.746346414339, 5642.98426283273, 5649.378568357395, 5656.5757568181725, 5663.318842752244, 5525.530329921841, 5570.693611851225, 5578.191162281528, 5589.608727389043, 5604.467575580314, 5367.577591938218, 5483.54536641913, 5487.393488093124, 5492.69790161529, 5503.229547863222, 5462.371827239918, 5473.233747275305, 5473.863153421037, 5495.862603434946, 5505.614606977473, 4983.401991761012, 5258.842234732964, 5326.467353264643, 5383.5962302826665, 5549.639273447213, 5365.624406291522, 5366.436524165063, 5367.731613419392, 5381.461853814587, 5400.433874176445], "value": 4192.875402601198, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.6416309763571, 731.8024133483956, 767.9769606911791, 795.5544894858878, 829.7925518620345, 15261.389544418224, 16198.271077801497, 16276.75584866228, 16343.019418834863, 16343.83656163438, 14823.258837058158, 14959.40162393503, 14963.850361496381, 14969.625759356022, 14979.176145312003, 642.299893116503, 765.1048273519995, 779.4426984705948, 787.873940169573, 792.6829268292671, 779.8830175473668, 780.3640033372811, 780.9687612495493, 781.4295564238109, 788.0385929166408, 823.9917600824004, 825.7440193539992, 826.0252901576138, 826.6197549127379, 831.4720152703017, 814.6822739131743, 818.3003531980161, 818.7380038387719, 820.4543978254453, 821.1172989036779, 501.8507617555793, 509.9184130539133, 515.4227265462669, 602.4939051197016, 613.1569092497815, 15522.534323970185, 15547.689046219013, 15602.921985390114, 15678.294476748573, 15686.450974215963, 13861.376238069312, 14317.0, 14458.927705361406, 14481.565553033426, 14636.634084147876, 13459.798103028463, 13571.524996625169, 13613.251271180081, 13721.0, 14366.281685915663, 4486.12520558488, 4487.775611219426, 4532.229520981441, 4591.403117594725, 4620.514845941161, 81770.59114704389, 82068.58965705193, 83217.0, 84254.0, 84716.57641711814, 10941.671749847517, 10951.233413660968, 11092.0, 11112.833307500394, 11467.046125012996, 19175.041247937548, 19192.616147676967, 19193.424197273824, 19222.135003924857, 19223.327183548787, 7887.921120788718, 7921.247481489249, 8131.0, 8769.079246679006, 8981.910180898238, 4740.7392593407, 4884.218525035977, 4940.505949405031, 4948.5546300832375, 5042.369703787027, 3565.714742820568, 3624.8187590620364, 3653.707703383723, 3663.9450408243897, 3679.9264014720093, 608.8660494691161, 629.9464545513591, 631.2992578238152, 637.7289651897925, 640.0271587187449, 639.6130341143635, 689.3692271571556, 698.0018573440029, 715.7351779841492, 721.9639018049077, 9798.0, 9829.655962041294, 9949.452780097019, 10036.717267079455, 10082.69751907444, 716.8387112899555, 725.8693435181718, 733.3876213361796, 742.721479445208, 747.6523416611302, 4699.17764391237, 4777.283407488904, 4906.705597664151, 5045.192769156917, 5082.237664350377, 75867.0, 86909.0, 124251.75748242583, 124921.37539312245, 136833.0, 109702.0, 113389.43305283421, 114200.0, 118783.40608296903, 119217.0, 71592.28407715961, 75811.0, 88697.55651221832, 90422.54788726014, 90663.0, 8315.251627353568, 9227.630894764134, 9252.352335336462, 9399.906000939904, 9504.144626983605, 52970.0, 53255.46744532505, 53580.0, 54701.0, 54731.0, 71639.0, 73576.0, 77719.0, 77947.61026194833, 78276.0, 13429.932850335686, 13685.24731139757, 13708.0, 13926.791098133739, 14588.489402870848, 14433.711325773216, 14554.708905821613, 15029.774553381927, 15781.921090394475, 17286.82713172852, 14327.212003339917, 14657.56027319182, 15369.1546964918, 15682.151150622476, 15859.762103568686, 11933.3436660981, 13892.657621707982, 14021.0, 14049.648758781112, 14489.927550362181, 13991.440342386191, 14308.588932648934, 14345.64135896611, 14416.062955907832, 14493.492727754478, 11060.944695276472, 11291.833770405541, 12108.394580271128, 12293.56972505958, 12397.132200745862, 11577.189596728485, 11682.94158529202, 11899.345535995604, 11941.283522988648, 12030.819537707117], "value": 7700.365320377374, "unit": "score"}], "name": "dromaeo_css", "value": 7274.604978689987, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 129.0, 111.0, 111.0, 111.0, 112.0, 110.0, 112.0, 111.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 147.0, 181.0, 119.0, 142.0, 142.0, 119.0, 184.0, 125.0], "value": 142.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 161.0, 160.0, 160.0, 161.0, 161.0, 160.0, 160.0, 161.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 178.0, 77.0, 76.0, 76.0, 76.0, 169.0, 76.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 153.0, 85.0, 84.0, 82.0, 152.0, 82.0, 82.0, 148.0], "value": 103.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 53.0, 53.0, 53.0, 53.0, 53.0, 54.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 62.0, 62.0, 65.0, 62.0, 63.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 78.0, 79.0, 79.0, 79.0, 78.0, 79.0, 77.0, 77.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 131.0, 123.0, 125.0, 125.0, 134.0, 126.0, 129.0, 170.0, 133.0], "value": 133.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 162.0, 164.0, 162.0, 166.0, 166.0, 162.0, 165.0, 164.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 67.0, 63.0, 63.0, 63.0, 63.0, 65.0, 65.0], "value": 64.6, "unit": "score"}], "name": "kraken", "value": 1527.3999999999996, "alertThreshold": 2.0}]} 00:06:11 INFO - Return code: 0 00:06:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:06:11 INFO - # TBPL SUCCESS # 00:06:11 INFO - Running post-action listener: _resource_record_post_action 00:06:11 INFO - [mozharness: 2016-09-01 07:06:11.392793Z] Finished run-tests step (success) 00:06:11 INFO - Running post-run listener: _resource_record_post_run 00:06:11 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 416714752; Read time: 20; Write time: 382324 00:06:11 INFO - TinderboxPrint: CPU usage
13.1% 00:06:11 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 00:06:11 INFO - TinderboxPrint: I/O write bytes / time
416,714,752 / 382,324 00:06:11 INFO - TinderboxPrint: CPU idle
8,286.7 (86.9%) 00:06:11 INFO - TinderboxPrint: CPU user
1,197.6 (12.6%) 00:06:11 INFO - TinderboxPrint: Swap in / out
0 / 0 00:06:11 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 127488000; Read time: 0; Write time: 139760 00:06:11 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 289226752; Read time: 0; Write time: 242564 00:06:12 INFO - Running post-run listener: _upload_blobber_files 00:06:12 INFO - Blob upload gear active. 00:06:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:06:12 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 00:06:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:06:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:06:12 INFO - (blobuploader) - INFO - Open directory for files ... 00:06:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:06:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:06:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:06:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:06:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:06:13 INFO - (blobuploader) - INFO - Done attempting. 00:06:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:06:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:06:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:06:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:06:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:06:17 INFO - (blobuploader) - INFO - Done attempting. 00:06:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:06:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:06:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:06:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:06:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:06:18 INFO - (blobuploader) - INFO - Done attempting. 00:06:18 INFO - (blobuploader) - INFO - Iteration through files over. 00:06:18 INFO - Return code: 0 00:06:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:06:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:06:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e0a6de9369f3765bcd8e168d564b15a09b0f376ab0b04c11f4f2dd7be6c3e27c6840dcb064fb64c734bf7f6115b887f134bca48cb84875091ed0557b8b9532d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9edbe2804fdb7e309f96908ef060232a88481ff0f65cfd8acc960f482c69659a134d82b7ee47e303290c2261e4df79b271ccf7e8925dae7641867554725a262f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cc418002ef32854e27eeca3a581065619b3e8631776d659a3c7e8c3afbc9d2b474abacdb83e6d3a41543a5b4db061f86f1d042ccc06147e7b1a70777ec11a87"} 00:06:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:06:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:06:18 INFO - Contents: 00:06:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e0a6de9369f3765bcd8e168d564b15a09b0f376ab0b04c11f4f2dd7be6c3e27c6840dcb064fb64c734bf7f6115b887f134bca48cb84875091ed0557b8b9532d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9edbe2804fdb7e309f96908ef060232a88481ff0f65cfd8acc960f482c69659a134d82b7ee47e303290c2261e4df79b271ccf7e8925dae7641867554725a262f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cc418002ef32854e27eeca3a581065619b3e8631776d659a3c7e8c3afbc9d2b474abacdb83e6d3a41543a5b4db061f86f1d042ccc06147e7b1a70777ec11a87"} 00:06:18 INFO - Running post-run listener: copy_logs_to_upload_dir 00:06:18 INFO - Copying logs to upload dir... 00:06:18 INFO - mkdir: /builds/slave/test/build/upload/logs 00:06:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.715098 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-09-01 00:06:17.770182) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 00:06:17.774062) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466 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/sha512/e0a6de9369f3765bcd8e168d564b15a09b0f376ab0b04c11f4f2dd7be6c3e27c6840dcb064fb64c734bf7f6115b887f134bca48cb84875091ed0557b8b9532d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9edbe2804fdb7e309f96908ef060232a88481ff0f65cfd8acc960f482c69659a134d82b7ee47e303290c2261e4df79b271ccf7e8925dae7641867554725a262f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cc418002ef32854e27eeca3a581065619b3e8631776d659a3c7e8c3afbc9d2b474abacdb83e6d3a41543a5b4db061f86f1d042ccc06147e7b1a70777ec11a87"} build_url:https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/Am6SJUvWSXusVEMhyU9avA/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/sha512/e0a6de9369f3765bcd8e168d564b15a09b0f376ab0b04c11f4f2dd7be6c3e27c6840dcb064fb64c734bf7f6115b887f134bca48cb84875091ed0557b8b9532d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9edbe2804fdb7e309f96908ef060232a88481ff0f65cfd8acc960f482c69659a134d82b7ee47e303290c2261e4df79b271ccf7e8925dae7641867554725a262f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cc418002ef32854e27eeca3a581065619b3e8631776d659a3c7e8c3afbc9d2b474abacdb83e6d3a41543a5b4db061f86f1d042ccc06147e7b1a70777ec11a87"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 00:06:17.802383) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 00:06:17.803148) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472712336.348382-556345466 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 00:06:17.886336) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 00:06:17.886698) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 00:06:17.886999) ========= ========= Total master_lag: 0.18 =========