builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1472534484.57 results: success (0) buildid: 20160829193030 builduid: 13592bb2cfd54673a83a367233381dc9 revision: fecb1018cdcbf931db0892b6fba2e8348286f1d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:24.573238) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:24.573647) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:24.573934) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:24.622670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:24.622931) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-29 22:21:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.8M=0s 2016-08-29 22:21:24 (40.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090384 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:24.729729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:24.730096) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016526 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:24.779998) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 22:21:24.780393) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fecb1018cdcbf931db0892b6fba2e8348286f1d8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fecb1018cdcbf931db0892b6fba2e8348286f1d8 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-29 22:21:24,998 truncating revision to first 12 chars 2016-08-29 22:21:24,998 Setting DEBUG logging. 2016-08-29 22:21:24,998 attempt 1/10 2016-08-29 22:21:24,998 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fecb1018cdcb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 22:21:26,108 unpacking tar archive at: mozilla-central-fecb1018cdcb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.386343 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 22:21:26.179826) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:26.180119) ========= script_repo_revision: fecb1018cdcbf931db0892b6fba2e8348286f1d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:26.180489) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:26.180776) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 22:21:26.190897) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-29 22:21:26.191175) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:21:26 INFO - MultiFileLogger online at 20160829 22:21:26 in /builds/slave/test 22:21:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:21:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:21:26 INFO - {'append_to_log': False, 22:21:26 INFO - 'base_work_dir': '/builds/slave/test', 22:21:26 INFO - 'blob_upload_branch': 'Firefox', 22:21:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:21:26 INFO - 'branch': 'Firefox', 22:21:26 INFO - 'buildbot_json_path': 'buildprops.json', 22:21:26 INFO - 'config_files': ('talos/linux_config.py',), 22:21:26 INFO - 'default_actions': ('clobber', 22:21:26 INFO - 'read-buildbot-config', 22:21:26 INFO - 'download-and-extract', 22:21:26 INFO - 'populate-webroot', 22:21:26 INFO - 'create-virtualenv', 22:21:26 INFO - 'install', 22:21:26 INFO - 'run-tests'), 22:21:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:21:26 INFO - 'download_minidump_stackwalk': True, 22:21:26 INFO - 'download_symbols': 'ondemand', 22:21:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:21:26 INFO - 'tooltool.py': '/tools/tooltool.py', 22:21:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:21:26 INFO - '/tools/misc-python/virtualenv.py')}, 22:21:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:21:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:21:26 INFO - 'installer_path': 'installer.exe', 22:21:26 INFO - 'log_level': 'info', 22:21:26 INFO - 'log_name': 'talos', 22:21:26 INFO - 'log_to_console': True, 22:21:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:21:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:21:26 INFO - 'opt_config_files': (), 22:21:26 INFO - 'pip_index': False, 22:21:26 INFO - 'sps_profile': False, 22:21:26 INFO - 'sps_profile_interval': 0, 22:21:26 INFO - 'suite': 'dromaeojs', 22:21:26 INFO - 'system_bits': '32', 22:21:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:21:26 INFO - 'title': 'talos-linux64-ix-015', 22:21:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:21:26 INFO - 'use_talos_json': True, 22:21:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:21:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:21:26 INFO - 'work_dir': 'build'} 22:21:26 INFO - [mozharness: 2016-08-30 05:21:26.502516Z] Running clobber step. 22:21:26 INFO - Running pre-action listener: _resource_record_pre_action 22:21:26 INFO - Running main action method: clobber 22:21:26 INFO - rmtree: /builds/slave/test/build 22:21:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:21:26 INFO - Running post-action listener: _resource_record_post_action 22:21:26 INFO - [mozharness: 2016-08-30 05:21:26.957923Z] Finished clobber step (success) 22:21:26 INFO - [mozharness: 2016-08-30 05:21:26.958025Z] Running read-buildbot-config step. 22:21:26 INFO - Running pre-action listener: _resource_record_pre_action 22:21:26 INFO - Running main action method: read_buildbot_config 22:21:26 INFO - Using buildbot properties: 22:21:26 INFO - { 22:21:26 INFO - "project": "", 22:21:26 INFO - "product": "firefox", 22:21:26 INFO - "script_repo_revision": "production", 22:21:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:21:26 INFO - "repository": "", 22:21:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:21:26 INFO - "buildid": "20160829193030", 22:21:26 INFO - "pgo_build": "True", 22:21:26 INFO - "basedir": "/builds/slave/test", 22:21:26 INFO - "buildnumber": 435, 22:21:26 INFO - "slavename": "talos-linux64-ix-015", 22:21:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:21:26 INFO - "platform": "ubuntu64_hw", 22:21:26 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:21:26 INFO - "branch": "mozilla-central", 22:21:26 INFO - "repo_path": "mozilla-central", 22:21:26 INFO - "revision": "fecb1018cdcbf931db0892b6fba2e8348286f1d8", 22:21:26 INFO - "stage_platform": "linux64-pgo", 22:21:26 INFO - "builduid": "13592bb2cfd54673a83a367233381dc9", 22:21:26 INFO - "slavebuilddir": "test" 22:21:26 INFO - } 22:21:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:21:26 INFO - Running post-action listener: _resource_record_post_action 22:21:26 INFO - [mozharness: 2016-08-30 05:21:26.960467Z] Finished read-buildbot-config step (success) 22:21:26 INFO - [mozharness: 2016-08-30 05:21:26.960557Z] Running download-and-extract step. 22:21:26 INFO - Running pre-action listener: _resource_record_pre_action 22:21:26 INFO - Running main action method: download_and_extract 22:21:26 INFO - mkdir: /builds/slave/test/build/tests 22:21:26 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')]} 22:21:26 INFO - trying https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:21:26 INFO - Downloading https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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 22:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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 22:21:27 INFO - Downloaded 1361 bytes. 22:21:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:21:27 INFO - Using the following test package requirements: 22:21:27 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:21:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:21:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:21:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:27 INFO - u'jsshell-linux-x86_64.zip'], 22:21:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:21:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:21:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:21:27 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:21:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:21:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:21:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:21:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:21:27 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')]} 22:21:27 INFO - trying https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:21:27 INFO - Downloading https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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 22:21:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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 22:21:28 INFO - Downloaded 21887053 bytes. 22:21:28 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 22:21:32 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 22:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:32 INFO - trying https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:21:32 INFO - Downloading https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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 22:21:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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 22:21:33 INFO - Downloaded 21887053 bytes. 22:21:33 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 22:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:38 INFO - trying https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:21:38 INFO - Downloading https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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 22:21:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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 22:21:38 INFO - Downloaded 14413998 bytes. 22:21:38 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 22:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:39 INFO - trying https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:21:39 INFO - Downloading https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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 22:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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 22:21:40 INFO - Downloaded 59736965 bytes. 22:21:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:21:40 INFO - mkdir: /builds/slave/test/properties 22:21:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:21:40 INFO - Writing to file /builds/slave/test/properties/build_url 22:21:40 INFO - Contents: 22:21:40 INFO - build_url:https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:21:40 INFO - Running post-action listener: _resource_record_post_action 22:21:40 INFO - Running post-action listener: set_extra_try_arguments 22:21:40 INFO - [mozharness: 2016-08-30 05:21:40.934241Z] Finished download-and-extract step (success) 22:21:40 INFO - [mozharness: 2016-08-30 05:21:40.934468Z] Running populate-webroot step. 22:21:40 INFO - Running pre-action listener: _resource_record_pre_action 22:21:40 INFO - Running main action method: populate_webroot 22:21:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:21:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:21:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:21:40 INFO - '--tppagecycles', 22:21:40 INFO - '7'], 22:21:40 INFO - 'tests': ['tsvgm']}}, 22:21:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:21:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:21:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:21:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:21:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:40 INFO - 'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:40 INFO - 'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['damp', 'tps']}, 22:21:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:40 INFO - 'tests': ['damp', 'tps']}, 22:21:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['dromaeo_dom']}, 22:21:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:21:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['basic_compositor_video']}, 22:21:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:21:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['a11yr', 22:21:40 INFO - 'ts_paint', 22:21:40 INFO - 'tpaint', 22:21:40 INFO - 'sessionrestore', 22:21:40 INFO - 'sessionrestore_no_auto_restore', 22:21:40 INFO - 'tabpaint']}, 22:21:40 INFO - 'other-e10s': {'tests': ['a11yr', 22:21:40 INFO - 'ts_paint', 22:21:40 INFO - 'tpaint', 22:21:40 INFO - 'sessionrestore', 22:21:40 INFO - 'sessionrestore_no_auto_restore', 22:21:40 INFO - 'tabpaint']}, 22:21:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:21:40 INFO - 'ts_paint', 22:21:40 INFO - 'tpaint', 22:21:40 INFO - 'sessionrestore', 22:21:40 INFO - 'sessionrestore_no_auto_restore', 22:21:40 INFO - 'tabpaint']}, 22:21:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:21:40 INFO - 'ts_paint', 22:21:40 INFO - 'tpaint', 22:21:40 INFO - 'sessionrestore', 22:21:40 INFO - 'sessionrestore_no_auto_restore', 22:21:40 INFO - 'tabpaint']}, 22:21:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['a11yr', 22:21:40 INFO - 'ts_paint', 22:21:40 INFO - 'tpaint', 22:21:40 INFO - 'sessionrestore', 22:21:40 INFO - 'sessionrestore_no_auto_restore', 22:21:40 INFO - 'tabpaint']}, 22:21:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['a11yr', 22:21:40 INFO - 'ts_paint', 22:21:40 INFO - 'tpaint', 22:21:40 INFO - 'sessionrestore', 22:21:40 INFO - 'sessionrestore_no_auto_restore', 22:21:40 INFO - 'tabpaint']}, 22:21:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['tsvgx', 22:21:40 INFO - 'tsvgr_opacity', 22:21:40 INFO - 'tart', 22:21:40 INFO - 'tscrollx', 22:21:40 INFO - 'cart']}, 22:21:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:21:40 INFO - 'tsvgr_opacity', 22:21:40 INFO - 'tart', 22:21:40 INFO - 'tscrollx', 22:21:40 INFO - 'cart']}, 22:21:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:40 INFO - 'talos_options': ['--disable-e10s'], 22:21:40 INFO - 'tests': ['tp5o']}, 22:21:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:40 INFO - 'tests': ['tp5o']}, 22:21:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:40 INFO - 'talos_options': ['--disable-e10s', 22:21:40 INFO - '--xperf_path', 22:21:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:40 INFO - 'tests': ['tp5n']}, 22:21:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:40 INFO - 'talos_options': ['--xperf_path', 22:21:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:40 INFO - 'tests': ['tp5n']}}, 22:21:40 INFO - 'talos.zip': {'path': '', 22:21:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:21:40 INFO - Running post-action listener: _resource_record_post_action 22:21:40 INFO - [mozharness: 2016-08-30 05:21:40.956938Z] Finished populate-webroot step (success) 22:21:40 INFO - [mozharness: 2016-08-30 05:21:40.957029Z] Running create-virtualenv step. 22:21:40 INFO - Running pre-action listener: _resource_record_pre_action 22:21:40 INFO - Running main action method: create_virtualenv 22:21:40 INFO - Creating virtualenv /builds/slave/test/build/venv 22:21:40 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 22:21:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:21:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:21:41 INFO - Using real prefix '/usr' 22:21:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:21:41 INFO - Installing distribute.............................................................................................................................................................................................done. 22:21:43 INFO - Installing pip.................done. 22:21:43 INFO - Return code: 0 22:21:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:21:43 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')]} 22:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:43 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')]} 22:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:43 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 0x7f342252df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b3850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', '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 22:21:43 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 22:21:43 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 22:21:43 INFO - Using env: {'DISPLAY': ':0', 22:21:43 INFO - 'HOME': '/home/cltbld', 22:21:43 INFO - 'LANG': 'en_US.UTF-8', 22:21:43 INFO - 'LANGUAGE': 'en_US:en', 22:21:43 INFO - 'LOGNAME': 'cltbld', 22:21:43 INFO - 'MAIL': '/var/mail/cltbld', 22:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:43 INFO - 'MOZ_NO_REMOTE': '1', 22:21:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:43 INFO - 'NO_EM_RESTART': '1', 22:21:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:43 INFO - 'PWD': '/builds/slave/test', 22:21:43 INFO - 'SHELL': '/bin/bash', 22:21:43 INFO - 'SHLVL': '1', 22:21:43 INFO - 'TERM': 'linux', 22:21:43 INFO - 'TMOUT': '86400', 22:21:43 INFO - 'USER': 'cltbld', 22:21:43 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:43 INFO - '_': '/tools/buildbot/bin/python'} 22:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:43 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 22:21:43 INFO - Cleaning up... 22:21:43 INFO - Return code: 0 22:21:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:21:43 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')]} 22:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:43 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')]} 22:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:43 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 0x7f342252df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b3850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', '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 22:21:43 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 22:21:43 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 22:21:43 INFO - Using env: {'DISPLAY': ':0', 22:21:43 INFO - 'HOME': '/home/cltbld', 22:21:43 INFO - 'LANG': 'en_US.UTF-8', 22:21:43 INFO - 'LANGUAGE': 'en_US:en', 22:21:43 INFO - 'LOGNAME': 'cltbld', 22:21:43 INFO - 'MAIL': '/var/mail/cltbld', 22:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:43 INFO - 'MOZ_NO_REMOTE': '1', 22:21:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:43 INFO - 'NO_EM_RESTART': '1', 22:21:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:43 INFO - 'PWD': '/builds/slave/test', 22:21:43 INFO - 'SHELL': '/bin/bash', 22:21:43 INFO - 'SHLVL': '1', 22:21:43 INFO - 'TERM': 'linux', 22:21:43 INFO - 'TMOUT': '86400', 22:21:43 INFO - 'USER': 'cltbld', 22:21:43 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:43 INFO - '_': '/tools/buildbot/bin/python'} 22:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:43 INFO - Downloading/unpacking psutil>=3.1.1 22:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:21:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:46 INFO - Installing collected packages: psutil 22:21:46 INFO - Running setup.py install for psutil 22:21:47 INFO - building 'psutil._psutil_linux' extension 22:21:47 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 22:21:47 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 22:21:47 INFO - building 'psutil._psutil_posix' extension 22:21:47 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 22:21:47 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 22:21:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:47 INFO - Successfully installed psutil 22:21:47 INFO - Cleaning up... 22:21:47 INFO - Return code: 0 22:21:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:47 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 0x7f342252df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b3850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', '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 22:21:47 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 22:21:47 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 22:21:47 INFO - Using env: {'DISPLAY': ':0', 22:21:47 INFO - 'HOME': '/home/cltbld', 22:21:47 INFO - 'LANG': 'en_US.UTF-8', 22:21:47 INFO - 'LANGUAGE': 'en_US:en', 22:21:47 INFO - 'LOGNAME': 'cltbld', 22:21:47 INFO - 'MAIL': '/var/mail/cltbld', 22:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:47 INFO - 'MOZ_NO_REMOTE': '1', 22:21:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:47 INFO - 'NO_EM_RESTART': '1', 22:21:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:47 INFO - 'PWD': '/builds/slave/test', 22:21:47 INFO - 'SHELL': '/bin/bash', 22:21:47 INFO - 'SHLVL': '1', 22:21:47 INFO - 'TERM': 'linux', 22:21:47 INFO - 'TMOUT': '86400', 22:21:47 INFO - 'USER': 'cltbld', 22:21:47 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:47 INFO - '_': '/tools/buildbot/bin/python'} 22:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:21:47 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 22:21:47 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 22:21:47 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 22:21:47 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 22:21:47 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 22:21:47 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 22:21:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:21:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:21:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:21:49 INFO - Installing collected packages: mozsystemmonitor 22:21:49 INFO - Running setup.py install for mozsystemmonitor 22:21:49 INFO - Successfully installed mozsystemmonitor 22:21:49 INFO - Cleaning up... 22:21:50 INFO - Return code: 0 22:21:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:50 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 0x7f342252df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b3850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', '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 22:21:50 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 22:21:50 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 22:21:50 INFO - Using env: {'DISPLAY': ':0', 22:21:50 INFO - 'HOME': '/home/cltbld', 22:21:50 INFO - 'LANG': 'en_US.UTF-8', 22:21:50 INFO - 'LANGUAGE': 'en_US:en', 22:21:50 INFO - 'LOGNAME': 'cltbld', 22:21:50 INFO - 'MAIL': '/var/mail/cltbld', 22:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:50 INFO - 'MOZ_NO_REMOTE': '1', 22:21:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:50 INFO - 'NO_EM_RESTART': '1', 22:21:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:50 INFO - 'PWD': '/builds/slave/test', 22:21:50 INFO - 'SHELL': '/bin/bash', 22:21:50 INFO - 'SHLVL': '1', 22:21:50 INFO - 'TERM': 'linux', 22:21:50 INFO - 'TMOUT': '86400', 22:21:50 INFO - 'USER': 'cltbld', 22:21:50 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:50 INFO - '_': '/tools/buildbot/bin/python'} 22:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:50 INFO - Downloading/unpacking jsonschema==2.5.1 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 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 22:21:50 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 22:21:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:21:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:21:52 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 22:21:52 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 22:21:52 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 22:21:52 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 22:21:52 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 22:21:52 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 22:21:52 INFO - Downloading functools32-3.2.3-2.tar.gz 22:21:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:21:52 INFO - warning: no files found matching '*.txt' 22:21:52 INFO - no previously-included directories found matching 'build' 22:21:52 INFO - no previously-included directories found matching 'dist' 22:21:52 INFO - no previously-included directories found matching '.git*' 22:21:52 INFO - Installing collected packages: jsonschema, functools32 22:21:52 INFO - Running setup.py install for functools32 22:21:52 INFO - warning: no files found matching '*.txt' 22:21:52 INFO - no previously-included directories found matching 'build' 22:21:52 INFO - no previously-included directories found matching 'dist' 22:21:52 INFO - no previously-included directories found matching '.git*' 22:21:52 INFO - Successfully installed jsonschema functools32 22:21:52 INFO - Cleaning up... 22:21:52 INFO - Return code: 0 22:21:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f342252df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b3850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', '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 22:21:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:21:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:21:52 INFO - Using env: {'DISPLAY': ':0', 22:21:52 INFO - 'HOME': '/home/cltbld', 22:21:52 INFO - 'LANG': 'en_US.UTF-8', 22:21:52 INFO - 'LANGUAGE': 'en_US:en', 22:21:52 INFO - 'LOGNAME': 'cltbld', 22:21:52 INFO - 'MAIL': '/var/mail/cltbld', 22:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:52 INFO - 'MOZ_NO_REMOTE': '1', 22:21:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:52 INFO - 'NO_EM_RESTART': '1', 22:21:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:52 INFO - 'PWD': '/builds/slave/test', 22:21:52 INFO - 'SHELL': '/bin/bash', 22:21:52 INFO - 'SHLVL': '1', 22:21:52 INFO - 'TERM': 'linux', 22:21:52 INFO - 'TMOUT': '86400', 22:21:52 INFO - 'USER': 'cltbld', 22:21:52 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:52 INFO - '_': '/tools/buildbot/bin/python'} 22:21:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:21:52 INFO - Cleaning up... 22:21:52 INFO - Return code: 0 22:21:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f342252df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b3850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', '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 22:21:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:21:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:21:52 INFO - Using env: {'DISPLAY': ':0', 22:21:52 INFO - 'HOME': '/home/cltbld', 22:21:52 INFO - 'LANG': 'en_US.UTF-8', 22:21:52 INFO - 'LANGUAGE': 'en_US:en', 22:21:52 INFO - 'LOGNAME': 'cltbld', 22:21:52 INFO - 'MAIL': '/var/mail/cltbld', 22:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:52 INFO - 'MOZ_NO_REMOTE': '1', 22:21:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:52 INFO - 'NO_EM_RESTART': '1', 22:21:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:52 INFO - 'PWD': '/builds/slave/test', 22:21:52 INFO - 'SHELL': '/bin/bash', 22:21:52 INFO - 'SHLVL': '1', 22:21:52 INFO - 'TERM': 'linux', 22:21:52 INFO - 'TMOUT': '86400', 22:21:52 INFO - 'USER': 'cltbld', 22:21:52 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:52 INFO - '_': '/tools/buildbot/bin/python'} 22:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:53 INFO - Downloading/unpacking blobuploader==1.2.4 22:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:54 INFO - Downloading blobuploader-1.2.4.tar.gz 22:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:21:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:21:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:55 INFO - Downloading docopt-0.6.1.tar.gz 22:21:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:21:55 INFO - Installing collected packages: blobuploader, requests, docopt 22:21:55 INFO - Running setup.py install for blobuploader 22:21:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:21:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:21:55 INFO - Running setup.py install for requests 22:21:56 INFO - Running setup.py install for docopt 22:21:56 INFO - Successfully installed blobuploader requests docopt 22:21:56 INFO - Cleaning up... 22:21:56 INFO - Return code: 0 22:21:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:56 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 0x7f342252df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b3850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', '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 22:21:56 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 22:21:56 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 22:21:56 INFO - Using env: {'DISPLAY': ':0', 22:21:56 INFO - 'HOME': '/home/cltbld', 22:21:56 INFO - 'LANG': 'en_US.UTF-8', 22:21:56 INFO - 'LANGUAGE': 'en_US:en', 22:21:56 INFO - 'LOGNAME': 'cltbld', 22:21:56 INFO - 'MAIL': '/var/mail/cltbld', 22:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:56 INFO - 'MOZ_NO_REMOTE': '1', 22:21:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:56 INFO - 'NO_EM_RESTART': '1', 22:21:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:56 INFO - 'PWD': '/builds/slave/test', 22:21:56 INFO - 'SHELL': '/bin/bash', 22:21:56 INFO - 'SHLVL': '1', 22:21:56 INFO - 'TERM': 'linux', 22:21:56 INFO - 'TMOUT': '86400', 22:21:56 INFO - 'USER': 'cltbld', 22:21:56 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:56 INFO - '_': '/tools/buildbot/bin/python'} 22:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:56 INFO - Running setup.py (path:/tmp/pip-ZYbX03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:56 INFO - Running setup.py (path:/tmp/pip-W_xMDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:56 INFO - Running setup.py (path:/tmp/pip-aKbsyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:56 INFO - Running setup.py (path:/tmp/pip-yjUyja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:56 INFO - Running setup.py (path:/tmp/pip-x7664O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:56 INFO - Running setup.py (path:/tmp/pip-zHvWKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:56 INFO - Running setup.py (path:/tmp/pip-N0KQDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:56 INFO - Running setup.py (path:/tmp/pip-S17xEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:57 INFO - Running setup.py (path:/tmp/pip-3wDlQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:57 INFO - Running setup.py (path:/tmp/pip-pOyYpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:57 INFO - Running setup.py (path:/tmp/pip-IZKFTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:57 INFO - Running setup.py (path:/tmp/pip-V19xSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:57 INFO - Running setup.py (path:/tmp/pip-6uLLHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:57 INFO - Running setup.py (path:/tmp/pip-DIcOX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:57 INFO - Running setup.py (path:/tmp/pip-3luVox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:57 INFO - Running setup.py (path:/tmp/pip-Rsr74i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:57 INFO - Running setup.py (path:/tmp/pip-4xbrS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:21:57 INFO - Running setup.py install for manifestparser 22:21:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:21:57 INFO - Running setup.py install for mozcrash 22:21:57 INFO - Running setup.py install for mozdebug 22:21:57 INFO - Running setup.py install for mozdevice 22:21:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:21:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:21:58 INFO - Running setup.py install for mozfile 22:21:58 INFO - Running setup.py install for mozhttpd 22:21:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:21:58 INFO - Running setup.py install for mozinfo 22:21:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:21:58 INFO - Running setup.py install for mozInstall 22:21:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:21:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:21:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:21:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:21:58 INFO - Running setup.py install for mozleak 22:21:58 INFO - Running setup.py install for mozlog 22:21:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:21:58 INFO - Running setup.py install for moznetwork 22:21:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:21:58 INFO - Running setup.py install for mozprocess 22:21:58 INFO - Running setup.py install for mozprofile 22:21:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:21:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:21:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:21:59 INFO - Running setup.py install for mozrunner 22:21:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:21:59 INFO - Running setup.py install for mozscreenshot 22:21:59 INFO - Running setup.py install for moztest 22:21:59 INFO - Running setup.py install for mozversion 22:21:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:21:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:21:59 INFO - Cleaning up... 22:21:59 INFO - Return code: 0 22:21:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:59 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 0x7f342252df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b3850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', '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 22:21:59 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 22:21:59 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 22:21:59 INFO - Using env: {'DISPLAY': ':0', 22:21:59 INFO - 'HOME': '/home/cltbld', 22:21:59 INFO - 'LANG': 'en_US.UTF-8', 22:21:59 INFO - 'LANGUAGE': 'en_US:en', 22:21:59 INFO - 'LOGNAME': 'cltbld', 22:21:59 INFO - 'MAIL': '/var/mail/cltbld', 22:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:59 INFO - 'MOZ_NO_REMOTE': '1', 22:21:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:59 INFO - 'NO_EM_RESTART': '1', 22:21:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:59 INFO - 'PWD': '/builds/slave/test', 22:21:59 INFO - 'SHELL': '/bin/bash', 22:21:59 INFO - 'SHLVL': '1', 22:21:59 INFO - 'TERM': 'linux', 22:21:59 INFO - 'TMOUT': '86400', 22:21:59 INFO - 'USER': 'cltbld', 22:21:59 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:59 INFO - '_': '/tools/buildbot/bin/python'} 22:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:59 INFO - Running setup.py (path:/tmp/pip-hdHbya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:59 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)) 22:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:59 INFO - Running setup.py (path:/tmp/pip-hluo7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:59 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)) 22:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:59 INFO - Running setup.py (path:/tmp/pip-QNDFCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:59 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)) 22:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:59 INFO - Running setup.py (path:/tmp/pip-rlXjUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:59 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)) 22:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:59 INFO - Running setup.py (path:/tmp/pip-9aBKkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:22:00 INFO - Running setup.py (path:/tmp/pip-Rw3I_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:22:00 INFO - Running setup.py (path:/tmp/pip-fdaNBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:22:00 INFO - Running setup.py (path:/tmp/pip-02tN3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:22:00 INFO - Running setup.py (path:/tmp/pip-DnMYK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:22:00 INFO - Running setup.py (path:/tmp/pip-e8NEOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:22:00 INFO - Running setup.py (path:/tmp/pip-HSVgbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:22:00 INFO - Running setup.py (path:/tmp/pip-3iOtdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:22:00 INFO - Running setup.py (path:/tmp/pip-OJvfea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:22:00 INFO - Running setup.py (path:/tmp/pip-1d21SR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:22:00 INFO - Running setup.py (path:/tmp/pip-8e8O2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:22:00 INFO - Running setup.py (path:/tmp/pip-NtT2Gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:22:00 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)) 22:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:22:00 INFO - Running setup.py (path:/tmp/pip-45X5_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:22:00 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)) 22:22:00 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)) 22:22:00 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)) 22:22:00 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)) 22:22:00 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)) 22:22:00 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)) 22:22:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:22:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:22:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:22:02 INFO - Downloading blessings-1.6.tar.gz 22:22:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:22:02 INFO - Installing collected packages: blessings 22:22:02 INFO - Running setup.py install for blessings 22:22:02 INFO - Successfully installed blessings 22:22:02 INFO - Cleaning up... 22:22:03 INFO - Return code: 0 22:22:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:22:03 INFO - Reading from file tmpfile_stdout 22:22:03 INFO - Current package versions: 22:22:03 INFO - argparse == 1.2.1 22:22:03 INFO - blessings == 1.6 22:22:03 INFO - blobuploader == 1.2.4 22:22:03 INFO - docopt == 0.6.1 22:22:03 INFO - functools32 == 3.2.3-2 22:22:03 INFO - jsonschema == 2.5.1 22:22:03 INFO - manifestparser == 1.1 22:22:03 INFO - mozInstall == 1.12 22:22:03 INFO - mozcrash == 0.17 22:22:03 INFO - mozdebug == 0.1 22:22:03 INFO - mozdevice == 0.48 22:22:03 INFO - mozfile == 1.2 22:22:03 INFO - mozhttpd == 0.7 22:22:03 INFO - mozinfo == 0.9 22:22:03 INFO - mozleak == 0.1 22:22:03 INFO - mozlog == 3.3 22:22:03 INFO - moznetwork == 0.27 22:22:03 INFO - mozprocess == 0.23 22:22:03 INFO - mozprofile == 0.28 22:22:03 INFO - mozrunner == 6.12 22:22:03 INFO - mozscreenshot == 0.1 22:22:03 INFO - mozsystemmonitor == 0.3 22:22:03 INFO - moztest == 0.7 22:22:03 INFO - mozversion == 1.4 22:22:03 INFO - psutil == 3.1.1 22:22:03 INFO - requests == 1.2.3 22:22:03 INFO - wsgiref == 0.1.2 22:22:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:22:03 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')]} 22:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:03 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')]} 22:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:03 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 0x7f342252df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b3850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', '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 22:22:03 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 22:22:03 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 22:22:03 INFO - Using env: {'DISPLAY': ':0', 22:22:03 INFO - 'HOME': '/home/cltbld', 22:22:03 INFO - 'LANG': 'en_US.UTF-8', 22:22:03 INFO - 'LANGUAGE': 'en_US:en', 22:22:03 INFO - 'LOGNAME': 'cltbld', 22:22:03 INFO - 'MAIL': '/var/mail/cltbld', 22:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:03 INFO - 'MOZ_NO_REMOTE': '1', 22:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:03 INFO - 'NO_EM_RESTART': '1', 22:22:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:03 INFO - 'PWD': '/builds/slave/test', 22:22:03 INFO - 'SHELL': '/bin/bash', 22:22:03 INFO - 'SHLVL': '1', 22:22:03 INFO - 'TERM': 'linux', 22:22:03 INFO - 'TMOUT': '86400', 22:22:03 INFO - 'USER': 'cltbld', 22:22:03 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:03 INFO - '_': '/tools/buildbot/bin/python'} 22:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:03 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)) 22:22:03 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)) 22:22:03 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)) 22:22:03 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)) 22:22:03 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)) 22:22:03 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)) 22:22:03 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)) 22:22:03 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)) 22:22:03 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)) 22:22:03 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)) 22:22:03 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)) 22:22:03 INFO - Cleaning up... 22:22:03 INFO - Return code: 0 22:22:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:22:03 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')]} 22:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:03 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')]} 22:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:03 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 0x7f342252df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b3850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', '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 22:22:03 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 22:22:03 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 22:22:03 INFO - Using env: {'DISPLAY': ':0', 22:22:03 INFO - 'HOME': '/home/cltbld', 22:22:03 INFO - 'LANG': 'en_US.UTF-8', 22:22:03 INFO - 'LANGUAGE': 'en_US:en', 22:22:03 INFO - 'LOGNAME': 'cltbld', 22:22:03 INFO - 'MAIL': '/var/mail/cltbld', 22:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:03 INFO - 'MOZ_NO_REMOTE': '1', 22:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:03 INFO - 'NO_EM_RESTART': '1', 22:22:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:03 INFO - 'PWD': '/builds/slave/test', 22:22:03 INFO - 'SHELL': '/bin/bash', 22:22:03 INFO - 'SHLVL': '1', 22:22:03 INFO - 'TERM': 'linux', 22:22:03 INFO - 'TMOUT': '86400', 22:22:03 INFO - 'USER': 'cltbld', 22:22:03 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:03 INFO - '_': '/tools/buildbot/bin/python'} 22:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:22:03 INFO - Cleaning up... 22:22:03 INFO - Return code: 0 22:22:03 INFO - Running post-action listener: _resource_record_post_action 22:22:03 INFO - Running post-action listener: _start_resource_monitoring 22:22:03 INFO - Starting resource monitoring. 22:22:03 INFO - [mozharness: 2016-08-30 05:22:03.765068Z] Finished create-virtualenv step (success) 22:22:03 INFO - [mozharness: 2016-08-30 05:22:03.765653Z] Running install step. 22:22:03 INFO - Running pre-action listener: _resource_record_pre_action 22:22:03 INFO - Running main action method: install 22:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:22:03 INFO - Reading from file tmpfile_stdout 22:22:03 INFO - Detecting whether we're running mozinstall >=1.0... 22:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:22:04 INFO - Reading from file tmpfile_stdout 22:22:04 INFO - Output received: 22:22:04 INFO - Usage: mozinstall [options] installer 22:22:04 INFO - Options: 22:22:04 INFO - -h, --help show this help message and exit 22:22:04 INFO - -d DEST, --destination=DEST 22:22:04 INFO - Directory to install application into. [default: 22:22:04 INFO - "/builds/slave/test"] 22:22:04 INFO - --app=APP Application being installed. [default: firefox] 22:22:04 INFO - mkdir: /builds/slave/test/build/application 22:22:04 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'] 22:22:04 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 22:22:21 INFO - Reading from file tmpfile_stdout 22:22:21 INFO - Output received: 22:22:21 INFO - /builds/slave/test/build/application/firefox/firefox 22:22:21 INFO - Running post-action listener: _resource_record_post_action 22:22:21 INFO - [mozharness: 2016-08-30 05:22:21.072928Z] Finished install step (success) 22:22:21 INFO - [mozharness: 2016-08-30 05:22:21.073290Z] Running run-tests step. 22:22:21 INFO - Running pre-action listener: _resource_record_pre_action 22:22:21 INFO - Running main action method: run_tests 22:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:22:21 INFO - Python 2.7.3 22:22:21 INFO - Return code: 0 22:22:21 INFO - grabbing minidump binary from tooltool 22:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:22:21 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 0x16ae370>, '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 0x16b18f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b1d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:22:21 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 22:22:21 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 22:22:21 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 22:22:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:22:21 INFO - Return code: 0 22:22:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:22:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:22:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:22:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:22:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:22:21 INFO - Using env: {'DISPLAY': ':0', 22:22:21 INFO - 'HOME': '/home/cltbld', 22:22:21 INFO - 'LANG': 'en_US.UTF-8', 22:22:21 INFO - 'LANGUAGE': 'en_US:en', 22:22:21 INFO - 'LOGNAME': 'cltbld', 22:22:21 INFO - 'MAIL': '/var/mail/cltbld', 22:22:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:22:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:21 INFO - 'MOZ_NO_REMOTE': '1', 22:22:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:21 INFO - 'NO_EM_RESTART': '1', 22:22:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:21 INFO - 'PWD': '/builds/slave/test', 22:22:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:22:21 INFO - 'SHELL': '/bin/bash', 22:22:21 INFO - 'SHLVL': '1', 22:22:21 INFO - 'TERM': 'linux', 22:22:21 INFO - 'TMOUT': '86400', 22:22:21 INFO - 'USER': 'cltbld', 22:22:21 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678', 22:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:21 INFO - '_': '/tools/buildbot/bin/python'} 22:22:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:22:21 INFO - mozversion application_buildid: 20160829193030 22:22:21 INFO - mozversion application_changeset: fecb1018cdcbf931db0892b6fba2e8348286f1d8 22:22:21 INFO - mozversion application_display_name: Nightly 22:22:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:22:21 INFO - mozversion application_name: Firefox 22:22:21 INFO - mozversion application_remotingname: firefox 22:22:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:22:21 INFO - mozversion application_vendor: Mozilla 22:22:21 INFO - mozversion application_version: 51.0a1 22:22:21 INFO - mozversion platform_buildid: 20160829193030 22:22:21 INFO - mozversion platform_changeset: fecb1018cdcbf931db0892b6fba2e8348286f1d8 22:22:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:22:21 INFO - mozversion platform_version: 51.0a1 22:22:21 INFO - using testdate: 1472534541 22:22:21 INFO - actual date: 1472534541 22:22:21 INFO - starting webserver on 'localhost:59011' 22:22:21 INFO - SUITE-START | Running 2 tests 22:22:21 INFO - TEST-START | dromaeo_css 22:22:21 INFO - operating with platform_type : linux_ 22:22:21 INFO - Initialising browser for dromaeo_css test... 22:22:21 INFO - TEST-INFO | started process 27026 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbPb8Fe/profile http://localhost:59011/getInfo.html) 22:22:25 INFO - PROCESS | 27026 | __metrics Screen width/height:1600/1200 22:22:25 INFO - PROCESS | 27026 | colorDepth:24 22:22:25 INFO - PROCESS | 27026 | Browser inner width/height: 1024/697 22:22:25 INFO - PROCESS | 27026 | __metrics 22:22:26 INFO - PROCESS | 27026 | 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" 22:22:26 INFO - PROCESS | 27026 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:22:26 INFO - PROCESS | 27026 | console.error: PushService: 22:22:26 INFO - PROCESS | 27026 | stateChangeProcessEnqueue: Error transitioning state 22:22:26 INFO - PROCESS | 27026 | UnknownError 22:22:27 INFO - TEST-INFO | 27026: exit 0 22:22:27 INFO - Browser initialized. 22:22:27 INFO - Running cycle 1/1 for dromaeo_css test... 22:22:27 INFO - TEST-INFO | started process 27108 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbPb8Fe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:22:39 INFO - PROCESS | 27108 | RSS: Main: 179953664 22:22:39 INFO - PROCESS | 27108 | 22:23:30 INFO - PROCESS | 27108 | 1472534610698 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] 22:25:40 INFO - PROCESS | 27108 | Cycle 1(1): loaded http://localhost:59011/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59011/tests/dromaeo/cssquery-ext.html) 22:25:41 INFO - PROCESS | 27108 | RSS: Main: 243466240 22:25:41 INFO - PROCESS | 27108 | 22:28:44 INFO - PROCESS | 27108 | Cycle 1(1): loaded http://localhost:59011/tests/dromaeo/cssquery-ext.html (next: http://localhost:59011/tests/dromaeo/cssquery-jquery.html) 22:28:44 INFO - PROCESS | 27108 | RSS: Main: 268992512 22:28:44 INFO - PROCESS | 27108 | 22:31:46 INFO - PROCESS | 27108 | Cycle 1(1): loaded http://localhost:59011/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59011/tests/dromaeo/cssquery-mootools.html) 22:31:47 INFO - PROCESS | 27108 | RSS: Main: 266715136 22:31:47 INFO - PROCESS | 27108 | 22:34:49 INFO - PROCESS | 27108 | Cycle 1(1): loaded http://localhost:59011/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59011/tests/dromaeo/cssquery-prototype.html) 22:34:50 INFO - PROCESS | 27108 | RSS: Main: 245694464 22:34:50 INFO - PROCESS | 27108 | 22:37:52 INFO - PROCESS | 27108 | Cycle 1(1): loaded http://localhost:59011/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59011/tests/dromaeo/cssquery-yui.html) 22:37:53 INFO - PROCESS | 27108 | RSS: Main: 215044096 22:37:53 INFO - PROCESS | 27108 | 22:40:56 INFO - PROCESS | 27108 | Cycle 1(1): loaded http://localhost:59011/tests/dromaeo/cssquery-yui.html (next: http://localhost:59011/tests/dromaeo/cssquery-dojo.html) 22:40:56 INFO - PROCESS | 27108 | RSS: Main: 307572736 22:40:56 INFO - PROCESS | 27108 | 22:40:56 INFO - PROCESS | 27108 | __start_tp_report 22:40:56 INFO - PROCESS | 27108 | _x_x_mozilla_page_load 22:40:56 INFO - PROCESS | 27108 | _x_x_mozilla_page_load_details 22:40:56 INFO - PROCESS | 27108 | |i|pagename|runs| 22:40:56 INFO - PROCESS | 27108 | |0;dojo.html;2995.370972095859;2997.2057404787734;3011.5783790269365;3044.786864919454;3066.5553494743262;5120.180771076629;5164.870878228042;5166.225066240066;5278.524932756051;5292.338457692788;5228.79084836606;5250.238715386266;5263.86840328992;5277.551408130304;5399.595030372718;4804.327394164802;4835.081334546425;4866.391701037371;4891.339669144655;4897;4849.296851956464;4865.781039853197;4901.06879692859;4964.503549645043;4980.302757613937;4919.212925931852;4924.630652701044;4926.950730492685;4969.055879382906;4984.352034235562;4684.273937539687;4792.850937073353;4799.664023518355;4830.516948305177;4847.4667786543805;4788.353572267734;4908.410990681141;4921.138800709861;4935.0376676548085;4956.132676781549;5263.157894736823;5299.364076310829;5318.973405132988;5351.3043304370185;5355.223492593591;5650.886982260373;5658.632188907708;5662.546996240291;5683.374828768832;5728.054870946289;5481.205225242357;5554.527865131428;5575.721213939287;5641.71791410432;5712.200291959129;5208.088584497736;5243.161094224944;5254.816081437208;5258.053550360972;5265.104932161542;5744.770209191669;5755.3381361143165;5756.136579513023;5761.625494342854;5761.971190144022;5403.594730395236;5494.972525137428;5545.556355491551;5549.223108764776;5615.241942337814;5606.831795046155;5613.438656134354;5616.241807355955;5618.353889302701;5624.437556244425;5603.3836278009385;5611.579131565149;5611.719414029282;5612.522935550524;5612.831615051555;5453.345598528202;5507.173923911445;5532.114861622121;5582.86042848932;5588.189712491666;4349.3475978603465;4391.8682439526865;4396.824127034883;4402.757848318373;4404.889877753082;3860.864869729546;3874.0121269076203;3880.5149356330458;3887.96112038876;3924.7252692311263;5212.843614691566;5238.5023422774775;5260.368755749335;5268.947310526923;5269.1569348903995;5742.54059675225;5792.8551786204835;5812.360640329561;5813.011787996051;5864.824055278348;5461.1808228766;5473.261109750212;5477.1510415885605;5477.808276710376;5478.835634930959;4650.906981860411;4822.638302127354;4830.227163653799;4830.855074347775;4890.828820991248;5626.493615574618;5826.038703613941;5908.615939963888;5979.790707325223;5988.580799344004;5657.660540367427;5730.312362516524;5730.598858079894;5738.68437235956;5797.072468405035;5911.645301281937;5913.379095195115;5920.851978700567;5922.407759224043;5923.407659234214;5424.647397919122;5471.042567550742;5475.561955043508;5536.169574563848;5577.27495425592;5613.719314034363;5615.494605485363;5675.432456754292;5684.090545512699;5748.051571490824;5519.9448005521035;5529.170624406372;5536.667799932017;5539.8892022160135;5540.307461567304;5458.399576046711;5462.317210348707;5487.5884308677805;5514.6691198528215;5580.916286255629;5325.9201111982575;5462.617616766788;5496.697681627549;5526.088195447706;5575.721213939368;5580.693061881473;5589.916151257816;5597.524210442158;5603.915941260802;5606.467385598362;5325.573954083741;5369.463053694755;5488.780448782004;5522.97238513821;5560.165975103767 22:40:56 INFO - PROCESS | 27108 | |1;ext.html;596.3023262782544;606.0364021206283;626.2797782550068;636.2968919344124;644.8387903024244;32198;42651;43943.56056439435;44009.339859902124;44016;43896.3415548767;44240.55759442405;44314.77842610791;44434.777826110825;44439.33340999888;8003.559804210766;8761.518116503607;8901;8912.197902188802;10457.686269411892;10357.430341331225;10537.631182908599;10545.78908421835;10551.84172237417;10553.683389498288;10616.416097114656;10646.25476216665;10652.041316281533;10655.573777048909;10658.774240962272;10604.78790424191;10644;10648.254622176451;10656.307340022873;10661.626843060532;10583.364998100138;10656.627018054409;10659.520321585547;10661.84007239892;10669.573217071307;42275;42678.146437071395;42713;42789;42803.57196428027;3591.28174365128;3681.2637472505608;4014.8996275093064;4183.284853210169;4253.617174454283;32466.513002304986;32538.837305813555;32626.836865815756;32679.019629411156;32739.83630081858;4304.698671093025;4332.133573285356;4414.381986521889;4611.5619016193405;4612.76936153191;235532;236998;238593;238620;239408;56517.43482565204;56608.71695641495;56852.43147568554;57011;57013.42986570164;58436;58666.706666466394;58876.11685824716;58913;58935.70532147312;16220.756688649444;16274.104924229045;16330.591735206715;16380.590485237964;16386.6722665546;12175.269483830998;12967.481300747864;13029.413676384585;13234.669133271746;13348.933255333855;16280.837191628169;16358.591035224215;16367.345306187619;16383.836161638232;16522;5737.770489180386;5929.584929054925;5942.435468630473;5944.346121926585;5945.672987985702;964.7636329099415;1052.221356196409;1083.534080345459;1123.6011216018196;1156.0866915136949;32278.354432911212;32343.838280808446;32399;32474.675253247166;32644.83677581597;1116.564539829451;1147.7647082348099;1158.7682463507163;1181.7636472705494;1184.437392238697;15967;16940.830591693924;17012.659746804995;17131.400400985905;17345.65308693819;153370.23314883577;154211.22894385457;154224;154427;155435;57814.7109264451;57854.71072644694;58001.41998580044;58069.41930580725;58091.4190858086;1252.480220708414;1318.967025824362;1376.6420730609914;1378.2419669182111;1399.6221020324456;31394.21514462157;32481.51277730883;32905.506417404235;33419.21052894287;33916.660833391354;49028;49490.75254623848;49683;50021.74989125177;50103.24845127253;394842;395934;397942;400677;402015;26874.59688104719;28159;28188;29635;29719.405611888073;46678;47186.76406617945;47435.12190732393;48424.65171381275;48793.512064878894;38375.61624383832;39619.80190099031;39674.801625991684;40436.59563404328;40906.79546602248;39900.401493977035;40700;41397;41431.7928410356;41502;39305.803470982464;39688.80155599204;39748;39891.60108398879;40757.388639171026;34231.65768342285;36518.45222321614;36735.816320918224;37211;37400.438993415664;27278.181654550393;27662.585061223694;27813.16560503188;27996.160115196577;28057.298567535974 22:40:56 INFO - PROCESS | 27108 | |2;jquery.html;372.1422122008772;400.7975972134072;404.20775280450334;404.3732215066646;424.4058318354304;15812.130332831704;19223.23107075719;19276.421707348767;19485.805141948593;19490.122944467497;18820;19116.713249301272;19611.21555137793;20034.599308013832;20266.797332026712;5391.81128660497;5544.029794785916;5576.86057848555;5802.854928626797;5808.332041815203;5743.339515955656;5795.507381872525;5832.591718579701;5836.474717275444;5839.211706419643;5559.666420014792;5712.628679135863;5747.224124743168;5754.251947246873;5805.3323867755325;5732.398098199692;5757.366689664133;5766.654000759926;5774.047282198432;5778.162166485857;4953.331300274453;5128.205128205134;5169.612279079084;5176.50823171798;5178.275041494194;23000;23551.528969420688;23569.646455303184;23583.882080589487;23717.644235336484;2194.4294483434264;2340.426595484117;2362.244081893795;2516.2719913546475;3103.3793241351595;10463.424511651856;10639;10698.946505267502;10728.088112510446;10836.187285953507;1894.981050189508;1968.2127149140315;1974.1511150205406;1980.9896952554302;1984.0674882805058;62005.6899715503;72867;85044;86334.56832715859;87611.12388876158;10979.945100274606;11120.888791112147;11174.21780475375;11174.776504470028;11507.827382589268;16044.919775401282;16055.190341690157;16070.116143612213;16101;16104.597385065466;7956.403269754792;8153.5923203839575;8174.100848906615;8197.426180167331;8355.95822020886;7554.924450755422;7739.574323412154;7753.767386978399;7890.092639346434;7929.960350198212;4829.106615276185;4859.70841749489;4882.926756098661;4936.950630493721;4988.775505102279;1997.9400617981485;2011.095007246745;2011.3262057210998;2037.847161462896;2069.803368679973;845.3575282785005;972.9590624752486;999.1856636840952;1017.2014968250036;1026.9178465722725;18876.622467550573;19435.611287774165;19452.902735486514;19487.8051219486;19732.802671973383;959.2374062620233;964.9228061755043;1012.7518757904213;1017.3133135133755;1032.3031953431555;9683.41899486033;10057.748556286151;10197.592096316064;10698.090662293636;10805.243632945776;487790;488467;496886;497071;499318;89148;89539.55230223807;91211.54394228119;91931;92079;987.3631507677508;1040.6305761454726;1058.962936297226;1060.4962642744636;1062.4581463453694;16470.258838352063;16818.663626727153;17224.31102755926;17261.65476690484;17383.130843457522;40639.59360406358;41801.58198417977;42381.788091059345;42571.3614295792;43450.56549434465;56872.431275686715;57578;57769.13346299725;57944.420555793906;58713.41286587079;8240.175982401952;8380.24577788002;8498.32013438911;8579.184977427134;8587.22714955657;11120.721981950515;12428.875711243134;12548.686282843002;12558.43487043067;13095.869041309465;10442.477876106012;10611.151107911193;10958.342499450058;11012.724681883017;11399.430028498708;10831.891681083089;10945.124390048617;11001.944990274997;11034.84134165901;11245;9927.801443970935;11016.228863979764;11055;11222.66332010041;11345.376004319842;8319.376046796635;8329.12544182852;8386.37102217324;8552.102029287083;8642.049374568918;10480.737981550523;10755.784884302113;11028.5037173329;11274.830877536679;11449.771004580029 22:40:56 INFO - PROCESS | 27108 | |3;mootools.html;1366.815479910212;1416.4759039155508;1469.88975826813;1577.589826645072;1804.350433843816;8310.501369917802;8709.69516066937;8798.604062817181;9057.547122643875;9066.773330666736;8874.733757987271;8955.507447090407;8995.685151019716;9004.324675649319;9006.909930900689;2106.0522764755847;2290.2213247495847;2426.1629737740495;2468.5926822074425;2471.1351027140536;2379.9643005354847;2401.8318717689767;2404.89177986991;2407.16952651335;2407.9157229496973;2364.5389149115845;2367.218817790134;2374.9525009499885;2377.1917548033666;2400;2255.391044418014;2287.63397856343;2295.3343530376337;2297.827662925279;2333.9533209335887;2316.7567405422355;2336.4626135988647;2336.4742932840145;2338.4504641409253;2338.5206032763244;9033.774155646095;9094.954525227398;9096.818063638757;9097.772555686095;9110.997790243067;5048.974755126201;5192.065428222918;5218.895622087575;5221.138512145491;5223.425423203445;3131.561581378609;3131.6825549093733;3279.737620990315;3322.1528510229978;3332.683395077464;1031.2492268104975;1041.0786453988198;1058.2222066780987;1060.4379678770188;1075.4300220882944;12241.143119981605;12244.571439999647;12351.135420520568;12356.691082723004;12373.010159187334;4196.433481479961;4205.116925445691;4307.569243075667;4328.913421731548;4483.103379324149;9700.320977531646;9701.88428330751;9703.223742100616;9706.563204655808;9715.27135464843;5194.92207616886;5195.948040519546;5196.818111366084;5202.531772140525;5206.817761378334;4257.680673949466;4607.216773148572;4821.2045012573235;4868.294097355863;4871.098846713369;3709.684676802502;3754.6245375462236;3834.3481608126685;3855.8264878080554;3866.941995870064;1141.69174322934;1161.3960740414936;1164.2141554450711;1173.8884805943421;1182.5092586576475;821.6015232612178;872.4154816272985;950.3205208276116;955.0640372434914;974.449436068619;5974.9402505974385;6196.938030619636;6273;6286.49708023357;6382.712777925005;907.1744712311764;923.9353245272766;938.863864739609;944.9574769135405;1023.4013102335068;3913.6673382762215;4106.117184805281;4140.109876376593;4150.252954468165;4325.394444777734;10860.348379097279;11206.663800086011;11233.32600044;11243;11253.437328133561;11139.443027848574;11142.888571114347;11191.888081119247;11232.719182020352;11237.775244495228;8365.665373385118;8392.370572207108;8492.447990880573;8503.489790612583;8509.27671147947;5647;5917.37867523921;5990.191324171181;6025.728842202024;6073.210482637229;9527.38072025316;10117;10212.642557510451;10223.744406389602;10238.53926573291;10060.39637621745;10198.59205631796;10307.639232626821;10318.793624127326;10332.845007325046;6859.862802744017;7363.668634911334;7853.960730196541;8029;8067.717629882926;8138.877916831134;8588.398812083095;8598.398112132341;8607.82784344297;8632.266257367943;7640.197779233101;7764.728234511765;7800.492967957065;7877.212278772077;7961.562114083781;7536.1333446654335;7647.082350118021;7707.152213256425;7715.498492597963;7719.421043421654;6936.237013928363;7014.894776578458;7062.223155452793;7071.292870712888;7076.787696369117;5152.252923326172;5216;5216.2436446714255;5257.106291930305;5532.3637781655625;5194.870128246673;5201.297824793604;5204.0892843751435;5258.290130832289;5463.781448742165 22:40:56 INFO - PROCESS | 27108 | |4;prototype.html;337.0317346695299;340.5487728759394;343.0600155573728;351.94416749750746;354.7587640404524;5131.435542090367;5214;5315.468453154687;5337.7598008089635;5360.490753378433;5215.58275337973;5237.45006774288;5255.658382205154;5277.419483856773;5304.575633949285;4186.39297301891;4191.161767646476;4193.412922190896;4229.788510574459;4275.380069889864;4009.5188577370755;4190.287651099306;4247.915041699164;4251.383549385338;4263.955330943923;4125;4129.988152902543;4138.379243113527;4144.399062135988;4160.500739911215;4116.526599441057;4138.751674899516;4181.348367394892;4188.643965262956;4207.684423668237;3706.425504046877;3707.1102935295335;3710.9072273193115;3714.1271801126713;3721.5348081489815;5070.6957582545165;5112.207607820794;5135.075686376451;5146.639735218536;5186.325777648919;4720.693154944917;4721.905561888743;4734.171519983989;4748.38271024768;4752.904941901143;5442.782288708447;5459.816073570603;5462;5465.863353416156;5540.556755459554;3724.571674257468;3877.224555088965;3877.5734669186368;3897.629725176104;3910.6871450284193;5672.631278966854;5689.971550142264;5691.886162276773;5705.686187259699;5717.313922329306;4083.1833633273463;4101.507819061731;4116.711830171919;4121.278776214211;4121.649659778953;3899.805009749558;4116.4648595682365;4154.646855017297;4156.231097247019;4176.477940257468;4686.95313046872;4688.413948256468;4713.457952335457;4714.679889630909;4748.1956856394345;5530.640508366943;5535.667859928417;5538.556915446755;5575.749091290902;5579.497845193952;1343.7917122845975;1350.3180893648841;1388.298909050923;1407.240090351199;1407.9859201407855;952.3809523809441;962.8988956159502;975.1418751498636;991.5686676295834;1001.7695929936156;5290.206469029677;5302.390225124083;5302.602304827154;5317.760700768475;5318.521333080042;5574.219609254707;5607.158926161109;5609.579281553818;5617.38208797032;5628.493435590816;5118.10433174193;5187.754938814657;5212.374515058217;5313.920291195635;5346.866328341823;2606.89572417105;2613.7908967282574;2618.3977685132145;2621.501914636213;2652.602109683563;5069.493050694901;5088.363954505687;5091.541761241506;5107.591392688576;5115.2582875483595;5055.9241611375855;5085.618578606619;5321.802594416303;5701.657900525982;5735.426457354231;5809.157672137517;5819.185314056035;5823.70881455934;5825.766969321265;5872.412758724094;5551.555875529867;5672.688002159921;5719.8570035749435;5725.398833122462;5731.369549349652;5606.215129881738;5627.606067575231;5648.23748793924;5648.887022259614;5657.971710141423;5841.414489625793;5844.853878653068;5891;5910.320313164041;5926.911096333644;4053;4066.2477441673386;4083.3466645336603;4127.9566614526;4189.49726032878;5383.27735124772;5640.266765320482;5641.661500309994;5649.576281778801;5677.914831277451;5365.6244062916785;5515.310586176728;5536.833894983157;5547.611667183259;5551.529569341605;5522.475364840334;5532.2254884315425;5542.944570554243;5583.850217666147;5585.497305242548;5218.373795144607;5411.648242864201;5426.449568702704;5476.233327334096;5480.3971563127225;5504.4770746778995;5507.614466987273;5513.724313784375;5516.310461192351;5537.224788529528;5388.488093631099;5408.972955135199;5430.402655707948;5459.317585301837;5463.7814487420055 22:40:56 INFO - PROCESS | 27108 | |5;yui.html;755.879059350504;768.7501561992353;782.4522834016186;799.6921185343642;825.3397282174261;13548.525801596947;14064.71870562588;15993.999999999985;17026.06356650384;17164.656706865855;13088.47646094155;16248.83751162488;16257.51227463175;16269.674606507891;16356.427525036626;659.9901001484982;791.3392317414952;812.3744716568239;815.351795322717;815.902091748988;793.5833687314177;809.247399918076;809.8096947217399;810.5825499867573;812.8293058457731;861.4228466927143;863.0032312678866;863.3266052479077;863.8704194370863;863.8876945997011;857.7126662568047;858.5792961448876;858.9914100858974;859.3297228161981;860.6428332242143;625.6590158363657;625.8372823066036;626.1140486212005;626.2047200055904;626.261012005833;15540.844591554052;15544.300506477235;15566.455174068853;15572.766408503881;15576.61058473536;14342.498012569615;14653.6336591585;15476.148811815345;15480.5355839325;15760.448384306608;11949.581764638282;12363.567275145328;12419.689507762378;12547.435365408488;12978.805317920238;3846.8461261549505;3851.634094761021;3864.0339915021245;3983.4224037514687;3988.561258261589;85108.57445712732;86265;86392.56803716005;87577.12422875817;88224.5588772052;11514.76970460586;11869.465874035517;11916.702082447835;12099.516019359127;12156.696082598006;22608.434789130075;22753.658695119586;22788.08847646109;22793.658095128587;22814.543709126057;7542.924570754222;7629.351505122127;8104.756857294173;8133.8373232536205;8537.530435825965;4998.325226094504;5063.569596584258;5096.362954630671;5123.436421993578;5330.466953304638;3596.4245720684567;3707.7589956652732;3712.7215458840697;3715.0898029982286;3718.7768733876055;621.4375989729302;627.1783963008473;634.9174607301113;639.5331408072134;640.2381166411955;591.9970400147972;715.4097869257884;740.2190688823235;773.7640019794017;797.8763291689797;9418.952905235567;9885.209183265322;10267.589296428208;10331.380117192992;10500.947495262628;613.1385403508078;613.6348872420903;674.7672053141658;699.937005669482;764.144158542436;4962.751862406865;5075.162598171334;5094.920982009785;5127.615428842777;5132.178851383761;97465;100067;110309;125457;129079.51045874368;88008;91688;92285.53857230672;92372;93085.53457232671;69349.65325173443;84264.57867710623;86190;87446.56276718575;88008;10132.594696212069;10407.843882341922;10552.78894422092;10693.144548436261;10718.624848130277;49764.75117624389;49903.500964989886;50768;51876.74061629668;52523;69860.30139698742;77057.61471192609;78191.21808782067;78560.60719696365;80043.19956800358;13287.53493627757;13470.326483675974;13478.40095169637;14057.718845623234;14173.22047287409;15373.461928832437;15613.297401616728;15894.682106357577;15946.60133496672;16602.08688522143;13802.861971380158;14114.576562703136;14219.857801421853;15208.315625797088;15779.605509862346;13816.585502434944;14547.640815677561;15092.320845562195;15511.767323490383;15941.442049528674;13367.732645347232;14011.859881401055;14027.649308767363;14289.714205716036;14328.355224014736;11420;11460.656180314603;11731.941340293244;12075.27548347102;12248.87751122513;11417.771644567229;11655.88344116548;11700.531978720755;11934.462949167135;11963.521459141537 22:40:56 INFO - PROCESS | 27108 | __end_tp_report 22:40:56 INFO - PROCESS | 27108 | __start_cc_report 22:40:56 INFO - PROCESS | 27108 | _x_x_mozilla_cycle_collect,1356 22:40:56 INFO - PROCESS | 27108 | __end_cc_report 22:40:56 INFO - PROCESS | 27108 | __startTimestamp1472535656570__endTimestamp 22:40:56 INFO - PROCESS | 27108 | 22:40:56 INFO - PROCESS | 27108 | ------- Summary: start ------- 22:40:56 INFO - PROCESS | 27108 | Number of tests: 6 22:40:56 INFO - PROCESS | 27108 | 22:40:56 INFO - PROCESS | 27108 | [#0] dojo.html Cycles:165 Average:5261.77 Median:5475.56 stddev:592.72 (10.8%) stddev-sans-first:567.25 22:40:56 INFO - PROCESS | 27108 | Values: 2995.4 2997.2 3011.6 3044.8 3066.6 5120.2 5164.9 5166.2 5278.5 5292.3 5228.8 5250.2 5263.9 5277.6 5399.6 4804.3 4835.1 4866.4 4891.3 4897.0 4849.3 4865.8 4901.1 4964.5 4980.3 4919.2 4924.6 4927.0 4969.1 4984.4 4684.3 4792.9 4799.7 4830.5 4847.5 4788.4 4908.4 4921.1 4935.0 4956.1 5263.2 5299.4 5319.0 5351.3 5355.2 5650.9 5658.6 5662.5 5683.4 5728.1 5481.2 5554.5 5575.7 5641.7 5712.2 5208.1 5243.2 5254.8 5258.1 5265.1 5744.8 5755.3 5756.1 5761.6 5762.0 5403.6 5495.0 5545.6 5549.2 5615.2 5606.8 5613.4 5616.2 5618.4 5624.4 5603.4 5611.6 5611.7 5612.5 5612.8 5453.3 5507.2 5532.1 5582.9 5588.2 4349.3 4391.9 4396.8 4402.8 4404.9 3860.9 3874.0 3880.5 3888.0 3924.7 5212.8 5238.5 5260.4 5268.9 5269.2 5742.5 5792.9 5812.4 5813.0 5864.8 5461.2 5473.3 5477.2 5477.8 5478.8 4650.9 4822.6 4830.2 4830.9 4890.8 5626.5 5826.0 5908.6 5979.8 5988.6 5657.7 5730.3 5730.6 5738.7 5797.1 5911.6 5913.4 5920.9 5922.4 5923.4 5424.6 5471.0 5475.6 5536.2 5577.3 5613.7 5615.5 5675.4 5684.1 5748.1 5519.9 5529.2 5536.7 5539.9 5540.3 5458.4 5462.3 5487.6 5514.7 5580.9 5325.9 5462.6 5496.7 5526.1 5575.7 5580.7 5589.9 5597.5 5603.9 5606.5 5325.6 5369.5 5488.8 5523.0 5560.2 22:40:56 INFO - PROCESS | 27108 | 22:40:56 INFO - PROCESS | 27108 | [#1] ext.html Cycles:180 Average:45370.47 Median:34074.16 stddev:74324.79 (218.1%) stddev-sans-first:74457.26 22:40:56 INFO - PROCESS | 27108 | Values: 596.3 606.0 626.3 636.3 644.8 32198.0 42651.0 43943.6 44009.3 44016.0 43896.3 44240.6 44314.8 44434.8 44439.3 8003.6 8761.5 8901.0 8912.2 10457.7 10357.4 10537.6 10545.8 10551.8 10553.7 10616.4 10646.3 10652.0 10655.6 10658.8 10604.8 10644.0 10648.3 10656.3 10661.6 10583.4 10656.6 10659.5 10661.8 10669.6 42275.0 42678.1 42713.0 42789.0 42803.6 3591.3 3681.3 4014.9 4183.3 4253.6 32466.5 32538.8 32626.8 32679.0 32739.8 4304.7 4332.1 4414.4 4611.6 4612.8 235532.0 236998.0 238593.0 238620.0 239408.0 56517.4 56608.7 56852.4 57011.0 57013.4 58436.0 58666.7 58876.1 58913.0 58935.7 16220.8 16274.1 16330.6 16380.6 16386.7 12175.3 12967.5 13029.4 13234.7 13348.9 16280.8 16358.6 16367.3 16383.8 16522.0 5737.8 5929.6 5942.4 5944.3 5945.7 964.8 1052.2 1083.5 1123.6 1156.1 32278.4 32343.8 32399.0 32474.7 32644.8 1116.6 1147.8 1158.8 1181.8 1184.4 15967.0 16940.8 17012.7 17131.4 17345.7 153370.2 154211.2 154224.0 154427.0 155435.0 57814.7 57854.7 58001.4 58069.4 58091.4 1252.5 1319.0 1376.6 1378.2 1399.6 31394.2 32481.5 32905.5 33419.2 33916.7 49028.0 49490.8 49683.0 50021.7 50103.2 394842.0 395934.0 397942.0 400677.0 402015.0 26874.6 28159.0 28188.0 29635.0 29719.4 46678.0 47186.8 47435.1 48424.7 48793.5 38375.6 39619.8 39674.8 40436.6 40906.8 39900.4 40700.0 41397.0 41431.8 41502.0 39305.8 39688.8 39748.0 39891.6 40757.4 34231.7 36518.5 36735.8 37211.0 37400.4 27278.2 27662.6 27813.2 27996.2 28057.3 22:40:56 INFO - PROCESS | 27108 | 22:40:56 INFO - PROCESS | 27108 | [#2] jquery.html Cycles:180 Average:28933.64 Median:2809.83 stddev:81389.81 (2896.6%) stddev-sans-first:81589.88 22:40:56 INFO - PROCESS | 27108 | Values: 372.1 400.8 404.2 404.4 424.4 15812.1 19223.2 19276.4 19485.8 19490.1 18820.0 19116.7 19611.2 20034.6 20266.8 5391.8 5544.0 5576.9 5802.9 5808.3 5743.3 5795.5 5832.6 5836.5 5839.2 5559.7 5712.6 5747.2 5754.3 5805.3 5732.4 5757.4 5766.7 5774.0 5778.2 4953.3 5128.2 5169.6 5176.5 5178.3 23000.0 23551.5 23569.6 23583.9 23717.6 2194.4 2340.4 2362.2 2516.3 3103.4 10463.4 10639.0 10698.9 10728.1 10836.2 1895.0 1968.2 1974.2 1981.0 1984.1 62005.7 72867.0 85044.0 86334.6 87611.1 10979.9 11120.9 11174.2 11174.8 11507.8 16044.9 16055.2 16070.1 16101.0 16104.6 7956.4 8153.6 8174.1 8197.4 8356.0 7554.9 7739.6 7753.8 7890.1 7930.0 4829.1 4859.7 4882.9 4937.0 4988.8 1997.9 2011.1 2011.3 2037.8 2069.8 845.4 973.0 999.2 1017.2 1026.9 18876.6 19435.6 19452.9 19487.8 19732.8 959.2 964.9 1012.8 1017.3 1032.3 9683.4 10057.7 10197.6 10698.1 10805.2 487790.0 488467.0 496886.0 497071.0 499318.0 89148.0 89539.6 91211.5 91931.0 92079.0 987.4 1040.6 1059.0 1060.5 1062.5 16470.3 16818.7 17224.3 17261.7 17383.1 40639.6 41801.6 42381.8 42571.4 43450.6 56872.4 57578.0 57769.1 57944.4 58713.4 8240.2 8380.2 8498.3 8579.2 8587.2 11120.7 12428.9 12548.7 12558.4 13095.9 10442.5 10611.2 10958.3 11012.7 11399.4 10831.9 10945.1 11001.9 11034.8 11245.0 9927.8 11016.2 11055.0 11222.7 11345.4 8319.4 8329.1 8386.4 8552.1 8642.0 10480.7 10755.8 11028.5 11274.8 11449.8 22:40:56 INFO - PROCESS | 27108 | 22:40:56 INFO - PROCESS | 27108 | [#3] mootools.html Cycles:180 Average:5811.73 Median:5100.61 stddev:3334.62 (65.4%) stddev-sans-first:3327.25 22:40:56 INFO - PROCESS | 27108 | Values: 1366.8 1416.5 1469.9 1577.6 1804.4 8310.5 8709.7 8798.6 9057.5 9066.8 8874.7 8955.5 8995.7 9004.3 9006.9 2106.1 2290.2 2426.2 2468.6 2471.1 2380.0 2401.8 2404.9 2407.2 2407.9 2364.5 2367.2 2375.0 2377.2 2400.0 2255.4 2287.6 2295.3 2297.8 2334.0 2316.8 2336.5 2336.5 2338.5 2338.5 9033.8 9095.0 9096.8 9097.8 9111.0 5049.0 5192.1 5218.9 5221.1 5223.4 3131.6 3131.7 3279.7 3322.2 3332.7 1031.2 1041.1 1058.2 1060.4 1075.4 12241.1 12244.6 12351.1 12356.7 12373.0 4196.4 4205.1 4307.6 4328.9 4483.1 9700.3 9701.9 9703.2 9706.6 9715.3 5194.9 5195.9 5196.8 5202.5 5206.8 4257.7 4607.2 4821.2 4868.3 4871.1 3709.7 3754.6 3834.3 3855.8 3866.9 1141.7 1161.4 1164.2 1173.9 1182.5 821.6 872.4 950.3 955.1 974.4 5974.9 6196.9 6273.0 6286.5 6382.7 907.2 923.9 938.9 945.0 1023.4 3913.7 4106.1 4140.1 4150.3 4325.4 10860.3 11206.7 11233.3 11243.0 11253.4 11139.4 11142.9 11191.9 11232.7 11237.8 8365.7 8392.4 8492.4 8503.5 8509.3 5647.0 5917.4 5990.2 6025.7 6073.2 9527.4 10117.0 10212.6 10223.7 10238.5 10060.4 10198.6 10307.6 10318.8 10332.8 6859.9 7363.7 7854.0 8029.0 8067.7 8138.9 8588.4 8598.4 8607.8 8632.3 7640.2 7764.7 7800.5 7877.2 7961.6 7536.1 7647.1 7707.2 7715.5 7719.4 6936.2 7014.9 7062.2 7071.3 7076.8 5152.3 5216.0 5216.2 5257.1 5532.4 5194.9 5201.3 5204.1 5258.3 5463.8 22:40:56 INFO - PROCESS | 27108 | 22:40:56 INFO - PROCESS | 27108 | [#4] prototype.html Cycles:180 Average:4622.45 Median:5246.55 stddev:1353.53 (25.8%) stddev-sans-first:1318.55 22:40:56 INFO - PROCESS | 27108 | Values: 337.0 340.5 343.1 351.9 354.8 5131.4 5214.0 5315.5 5337.8 5360.5 5215.6 5237.5 5255.7 5277.4 5304.6 4186.4 4191.2 4193.4 4229.8 4275.4 4009.5 4190.3 4247.9 4251.4 4264.0 4125.0 4130.0 4138.4 4144.4 4160.5 4116.5 4138.8 4181.3 4188.6 4207.7 3706.4 3707.1 3710.9 3714.1 3721.5 5070.7 5112.2 5135.1 5146.6 5186.3 4720.7 4721.9 4734.2 4748.4 4752.9 5442.8 5459.8 5462.0 5465.9 5540.6 3724.6 3877.2 3877.6 3897.6 3910.7 5672.6 5690.0 5691.9 5705.7 5717.3 4083.2 4101.5 4116.7 4121.3 4121.6 3899.8 4116.5 4154.6 4156.2 4176.5 4687.0 4688.4 4713.5 4714.7 4748.2 5530.6 5535.7 5538.6 5575.7 5579.5 1343.8 1350.3 1388.3 1407.2 1408.0 952.4 962.9 975.1 991.6 1001.8 5290.2 5302.4 5302.6 5317.8 5318.5 5574.2 5607.2 5609.6 5617.4 5628.5 5118.1 5187.8 5212.4 5313.9 5346.9 2606.9 2613.8 2618.4 2621.5 2652.6 5069.5 5088.4 5091.5 5107.6 5115.3 5055.9 5085.6 5321.8 5701.7 5735.4 5809.2 5819.2 5823.7 5825.8 5872.4 5551.6 5672.7 5719.9 5725.4 5731.4 5606.2 5627.6 5648.2 5648.9 5658.0 5841.4 5844.9 5891.0 5910.3 5926.9 4053.0 4066.2 4083.3 4128.0 4189.5 5383.3 5640.3 5641.7 5649.6 5677.9 5365.6 5515.3 5536.8 5547.6 5551.5 5522.5 5532.2 5542.9 5583.9 5585.5 5218.4 5411.6 5426.4 5476.2 5480.4 5504.5 5507.6 5513.7 5516.3 5537.2 5388.5 5409.0 5430.4 5459.3 5463.8 22:40:56 INFO - PROCESS | 27108 | 22:40:56 INFO - PROCESS | 27108 | [#5] yui.html Cycles:180 Average:21119.26 Median:3985.99 stddev:29706.38 (745.3%) stddev-sans-first:29750.36 22:40:56 INFO - PROCESS | 27108 | Values: 755.9 768.8 782.5 799.7 825.3 13548.5 14064.7 15994.0 17026.1 17164.7 13088.5 16248.8 16257.5 16269.7 16356.4 660.0 791.3 812.4 815.4 815.9 793.6 809.2 809.8 810.6 812.8 861.4 863.0 863.3 863.9 863.9 857.7 858.6 859.0 859.3 860.6 625.7 625.8 626.1 626.2 626.3 15540.8 15544.3 15566.5 15572.8 15576.6 14342.5 14653.6 15476.1 15480.5 15760.4 11949.6 12363.6 12419.7 12547.4 12978.8 3846.8 3851.6 3864.0 3983.4 3988.6 85108.6 86265.0 86392.6 87577.1 88224.6 11514.8 11869.5 11916.7 12099.5 12156.7 22608.4 22753.7 22788.1 22793.7 22814.5 7542.9 7629.4 8104.8 8133.8 8537.5 4998.3 5063.6 5096.4 5123.4 5330.5 3596.4 3707.8 3712.7 3715.1 3718.8 621.4 627.2 634.9 639.5 640.2 592.0 715.4 740.2 773.8 797.9 9419.0 9885.2 10267.6 10331.4 10500.9 613.1 613.6 674.8 699.9 764.1 4962.8 5075.2 5094.9 5127.6 5132.2 97465.0 100067.0 110309.0 125457.0 129079.5 88008.0 91688.0 92285.5 92372.0 93085.5 69349.7 84264.6 86190.0 87446.6 88008.0 10132.6 10407.8 10552.8 10693.1 10718.6 49764.8 49903.5 50768.0 51876.7 52523.0 69860.3 77057.6 78191.2 78560.6 80043.2 13287.5 13470.3 13478.4 14057.7 14173.2 15373.5 15613.3 15894.7 15946.6 16602.1 13802.9 14114.6 14219.9 15208.3 15779.6 13816.6 14547.6 15092.3 15511.8 15941.4 13367.7 14011.9 14027.6 14289.7 14328.4 11420.0 11460.7 11731.9 12075.3 12248.9 11417.8 11655.9 11700.5 11934.5 11963.5 22:40:56 INFO - PROCESS | 27108 | -------- Summary: end -------- 22:40:56 INFO - PROCESS | 27108 | 22:40:57 INFO - TEST-INFO | 27108: exit 0 22:40:57 INFO - TEST-OK | dromaeo_css | took 1116215ms 22:40:57 INFO - TEST-START | kraken 22:40:57 INFO - operating with platform_type : linux_ 22:40:57 INFO - Initialising browser for kraken test... 22:40:57 INFO - TEST-INFO | started process 27410 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFwqnkx/profile http://localhost:59011/getInfo.html) 22:41:01 INFO - PROCESS | 27410 | __metrics Screen width/height:1600/1200 22:41:01 INFO - PROCESS | 27410 | colorDepth:24 22:41:01 INFO - PROCESS | 27410 | Browser inner width/height: 1024/697 22:41:01 INFO - PROCESS | 27410 | __metrics 22:41:02 INFO - PROCESS | 27410 | 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" 22:41:02 INFO - PROCESS | 27410 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:41:02 INFO - PROCESS | 27410 | console.error: PushService: 22:41:02 INFO - PROCESS | 27410 | stateChangeProcessEnqueue: Error transitioning state 22:41:02 INFO - PROCESS | 27410 | UnknownError 22:41:03 INFO - TEST-INFO | 27410: exit 0 22:41:03 INFO - Browser initialized. 22:41:03 INFO - Running cycle 1/1 for kraken test... 22:41:03 INFO - TEST-INFO | started process 27495 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFwqnkx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:41:15 INFO - PROCESS | 27495 | RSS: Main: 180187136 22:41:15 INFO - PROCESS | 27495 | 22:41:17 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59011/tests/kraken/driver.html?testName=audio-beat-detection) 22:41:18 INFO - PROCESS | 27495 | RSS: Main: 234147840 22:41:18 INFO - PROCESS | 27495 | 22:41:21 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59011/tests/kraken/driver.html?testName=audio-dft) 22:41:21 INFO - PROCESS | 27495 | RSS: Main: 224378880 22:41:21 INFO - PROCESS | 27495 | 22:41:25 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59011/tests/kraken/driver.html?testName=audio-fft) 22:41:25 INFO - PROCESS | 27495 | RSS: Main: 301203456 22:41:25 INFO - PROCESS | 27495 | 22:41:27 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59011/tests/kraken/driver.html?testName=audio-oscillator) 22:41:28 INFO - PROCESS | 27495 | RSS: Main: 211861504 22:41:28 INFO - PROCESS | 27495 | 22:41:30 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59011/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:41:31 INFO - PROCESS | 27495 | RSS: Main: 214405120 22:41:31 INFO - PROCESS | 27495 | 22:41:34 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59011/tests/kraken/driver.html?testName=imaging-darkroom) 22:41:34 INFO - PROCESS | 27495 | RSS: Main: 252801024 22:41:34 INFO - PROCESS | 27495 | 22:41:37 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59011/tests/kraken/driver.html?testName=imaging-desaturate) 22:41:38 INFO - PROCESS | 27495 | RSS: Main: 251564032 22:41:38 INFO - PROCESS | 27495 | 22:41:41 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59011/tests/kraken/driver.html?testName=json-parse-financial) 22:41:42 INFO - PROCESS | 27495 | RSS: Main: 250912768 22:41:42 INFO - PROCESS | 27495 | 22:41:43 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59011/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:41:44 INFO - PROCESS | 27495 | RSS: Main: 213688320 22:41:44 INFO - PROCESS | 27495 | 22:41:45 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59011/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:41:46 INFO - PROCESS | 27495 | RSS: Main: 220983296 22:41:46 INFO - PROCESS | 27495 | 22:41:48 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59011/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:41:49 INFO - PROCESS | 27495 | RSS: Main: 222457856 22:41:49 INFO - PROCESS | 27495 | 22:41:51 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59011/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:41:52 INFO - PROCESS | 27495 | RSS: Main: 223100928 22:41:52 INFO - PROCESS | 27495 | 22:41:55 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59011/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:41:55 INFO - PROCESS | 27495 | RSS: Main: 221286400 22:41:55 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | Cycle 1(1): loaded http://localhost:59011/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59011/tests/kraken/driver.html?testName=ai-astar) 22:41:57 INFO - PROCESS | 27495 | RSS: Main: 272449536 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | __start_tp_report 22:41:57 INFO - PROCESS | 27495 | _x_x_mozilla_page_load 22:41:57 INFO - PROCESS | 27495 | _x_x_mozilla_page_load_details 22:41:57 INFO - PROCESS | 27495 | |i|pagename|runs| 22:41:57 INFO - PROCESS | 27495 | |0;ai-astar;114;112;131;112;115;113;111;115;114;112 22:41:57 INFO - PROCESS | 27495 | |1;audio-beat-detection;147;120;145;171;120;142;145;120;186;121 22:41:57 INFO - PROCESS | 27495 | |2;audio-dft;162;159;161;161;162;163;162;162;160;163 22:41:57 INFO - PROCESS | 27495 | |3;audio-fft;74;76;74;188;76;76;75;75;164;76 22:41:57 INFO - PROCESS | 27495 | |4;audio-oscillator;82;82;137;82;82;82;147;81;81;147 22:41:57 INFO - PROCESS | 27495 | |5;imaging-gaussian-blur;117;116;116;117;117;117;116;116;117;117 22:41:57 INFO - PROCESS | 27495 | |6;imaging-darkroom;147;146;146;146;146;145;146;146;146;146 22:41:57 INFO - PROCESS | 27495 | |7;imaging-desaturate;95;95;94;95;95;94;95;94;95;95 22:41:57 INFO - PROCESS | 27495 | |8;json-parse-financial;51;51;52;51;52;52;51;52;52;52 22:41:57 INFO - PROCESS | 27495 | |9;json-stringify-tinderbox;61;62;61;61;63;62;61;61;62;63 22:41:57 INFO - PROCESS | 27495 | |10;stanford-crypto-aes;89;83;86;79;80;80;78;79;79;78 22:41:57 INFO - PROCESS | 27495 | |11;stanford-crypto-ccm;124;131;132;131;133;122;131;133;163;130 22:41:57 INFO - PROCESS | 27495 | |12;stanford-crypto-pbkdf2;167;165;169;162;166;169;168;175;160;176 22:41:57 INFO - PROCESS | 27495 | |13;stanford-crypto-sha256-iterative;65;65;66;63;65;65;64;66;65;67 22:41:57 INFO - PROCESS | 27495 | __end_tp_report 22:41:57 INFO - PROCESS | 27495 | __start_cc_report 22:41:57 INFO - PROCESS | 27495 | _x_x_mozilla_cycle_collect,1723 22:41:57 INFO - PROCESS | 27495 | __end_cc_report 22:41:57 INFO - PROCESS | 27495 | __startTimestamp1472535717713__endTimestamp 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | ------- Summary: start ------- 22:41:57 INFO - PROCESS | 27495 | Number of tests: 14 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#0] ai-astar Cycles:10 Average:114.90 Median:114.00 stddev:5.82 (5.1%) stddev-sans-first:6.16 22:41:57 INFO - PROCESS | 27495 | Values: 114.0 112.0 131.0 112.0 115.0 113.0 111.0 115.0 114.0 112.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:145.00 stddev:22.88 (15.8%) stddev-sans-first:24.19 22:41:57 INFO - PROCESS | 27495 | Values: 147.0 120.0 145.0 171.0 120.0 142.0 145.0 120.0 186.0 121.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:1.27 (0.8%) stddev-sans-first:1.33 22:41:57 INFO - PROCESS | 27495 | Values: 162.0 159.0 161.0 161.0 162.0 163.0 162.0 162.0 160.0 163.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#3] audio-fft Cycles:10 Average:95.40 Median:75.50 stddev:42.86 (56.8%) stddev-sans-first:44.76 22:41:57 INFO - PROCESS | 27495 | Values: 74.0 76.0 74.0 188.0 76.0 76.0 75.0 75.0 164.0 76.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#4] audio-oscillator Cycles:10 Average:100.30 Median:82.00 stddev:30.05 (36.6%) stddev-sans-first:31.14 22:41:57 INFO - PROCESS | 27495 | Values: 82.0 82.0 137.0 82.0 82.0 82.0 147.0 81.0 81.0 147.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:41:57 INFO - PROCESS | 27495 | Values: 117.0 116.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 22:41:57 INFO - PROCESS | 27495 | Values: 147.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 22:41:57 INFO - PROCESS | 27495 | Values: 95.0 95.0 94.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:52.00 stddev:0.52 (1.0%) stddev-sans-first:0.50 22:41:57 INFO - PROCESS | 27495 | Values: 51.0 51.0 52.0 51.0 52.0 52.0 51.0 52.0 52.0 52.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:0.82 (1.3%) stddev-sans-first:0.83 22:41:57 INFO - PROCESS | 27495 | Values: 61.0 62.0 61.0 61.0 63.0 62.0 61.0 61.0 62.0 63.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:80.00 stddev:3.73 (4.7%) stddev-sans-first:2.64 22:41:57 INFO - PROCESS | 27495 | Values: 89.0 83.0 86.0 79.0 80.0 80.0 78.0 79.0 79.0 78.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#11] stanford-crypto-ccm Cycles:10 Average:133.00 Median:131.50 stddev:11.18 (8.5%) stddev-sans-first:11.37 22:41:57 INFO - PROCESS | 27495 | Values: 124.0 131.0 132.0 131.0 133.0 122.0 131.0 133.0 163.0 130.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:168.50 stddev:5.03 (3.0%) stddev-sans-first:5.33 22:41:57 INFO - PROCESS | 27495 | Values: 167.0 165.0 169.0 162.0 166.0 169.0 168.0 175.0 160.0 176.0 22:41:57 INFO - PROCESS | 27495 | 22:41:57 INFO - PROCESS | 27495 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:1.10 (1.7%) stddev-sans-first:1.17 22:41:57 INFO - PROCESS | 27495 | Values: 65.0 65.0 66.0 63.0 65.0 65.0 64.0 66.0 65.0 67.0 22:41:57 INFO - PROCESS | 27495 | -------- Summary: end -------- 22:41:57 INFO - PROCESS | 27495 | 22:41:58 INFO - TEST-INFO | 27495: exit 0 22:41:58 INFO - TEST-OK | kraken | took 61275ms 22:41:58 INFO - SUITE-END | took 1177s 22:41:58 INFO - Completed test suite (00:19:37) 22:41:58 INFO - javascript benchmark 22:41:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2995.370972095859, 2997.2057404787734, 3011.5783790269365, 3044.786864919454, 3066.5553494743262, 5120.180771076629, 5164.870878228042, 5166.225066240066, 5278.524932756051, 5292.338457692788, 5228.79084836606, 5250.238715386266, 5263.86840328992, 5277.551408130304, 5399.595030372718, 4804.327394164802, 4835.081334546425, 4866.391701037371, 4891.339669144655, 4897.0, 4849.296851956464, 4865.781039853197, 4901.06879692859, 4964.503549645043, 4980.302757613937, 4919.212925931852, 4924.630652701044, 4926.950730492685, 4969.055879382906, 4984.352034235562, 4684.273937539687, 4792.850937073353, 4799.664023518355, 4830.516948305177, 4847.4667786543805, 4788.353572267734, 4908.410990681141, 4921.138800709861, 4935.0376676548085, 4956.132676781549, 5263.157894736823, 5299.364076310829, 5318.973405132988, 5351.3043304370185, 5355.223492593591, 5650.886982260373, 5658.632188907708, 5662.546996240291, 5683.374828768832, 5728.054870946289, 5481.205225242357, 5554.527865131428, 5575.721213939287, 5641.71791410432, 5712.200291959129, 5208.088584497736, 5243.161094224944, 5254.816081437208, 5258.053550360972, 5265.104932161542, 5744.770209191669, 5755.3381361143165, 5756.136579513023, 5761.625494342854, 5761.971190144022, 5403.594730395236, 5494.972525137428, 5545.556355491551, 5549.223108764776, 5615.241942337814, 5606.831795046155, 5613.438656134354, 5616.241807355955, 5618.353889302701, 5624.437556244425, 5603.3836278009385, 5611.579131565149, 5611.719414029282, 5612.522935550524, 5612.831615051555, 5453.345598528202, 5507.173923911445, 5532.114861622121, 5582.86042848932, 5588.189712491666, 4349.3475978603465, 4391.8682439526865, 4396.824127034883, 4402.757848318373, 4404.889877753082, 3860.864869729546, 3874.0121269076203, 3880.5149356330458, 3887.96112038876, 3924.7252692311263, 5212.843614691566, 5238.5023422774775, 5260.368755749335, 5268.947310526923, 5269.1569348903995, 5742.54059675225, 5792.8551786204835, 5812.360640329561, 5813.011787996051, 5864.824055278348, 5461.1808228766, 5473.261109750212, 5477.1510415885605, 5477.808276710376, 5478.835634930959, 4650.906981860411, 4822.638302127354, 4830.227163653799, 4830.855074347775, 4890.828820991248, 5626.493615574618, 5826.038703613941, 5908.615939963888, 5979.790707325223, 5988.580799344004, 5657.660540367427, 5730.312362516524, 5730.598858079894, 5738.68437235956, 5797.072468405035, 5911.645301281937, 5913.379095195115, 5920.851978700567, 5922.407759224043, 5923.407659234214, 5424.647397919122, 5471.042567550742, 5475.561955043508, 5536.169574563848, 5577.27495425592, 5613.719314034363, 5615.494605485363, 5675.432456754292, 5684.090545512699, 5748.051571490824, 5519.9448005521035, 5529.170624406372, 5536.667799932017, 5539.8892022160135, 5540.307461567304, 5458.399576046711, 5462.317210348707, 5487.5884308677805, 5514.6691198528215, 5580.916286255629, 5325.9201111982575, 5462.617616766788, 5496.697681627549, 5526.088195447706, 5575.721213939368, 5580.693061881473, 5589.916151257816, 5597.524210442158, 5603.915941260802, 5606.467385598362, 5325.573954083741, 5369.463053694755, 5488.780448782004, 5522.97238513821, 5560.165975103767], "value": 5221.743494123883, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.3023262782544, 606.0364021206283, 626.2797782550068, 636.2968919344124, 644.8387903024244, 32198.0, 42651.0, 43943.56056439435, 44009.339859902124, 44016.0, 43896.3415548767, 44240.55759442405, 44314.77842610791, 44434.777826110825, 44439.33340999888, 8003.559804210766, 8761.518116503607, 8901.0, 8912.197902188802, 10457.686269411892, 10357.430341331225, 10537.631182908599, 10545.78908421835, 10551.84172237417, 10553.683389498288, 10616.416097114656, 10646.25476216665, 10652.041316281533, 10655.573777048909, 10658.774240962272, 10604.78790424191, 10644.0, 10648.254622176451, 10656.307340022873, 10661.626843060532, 10583.364998100138, 10656.627018054409, 10659.520321585547, 10661.84007239892, 10669.573217071307, 42275.0, 42678.146437071395, 42713.0, 42789.0, 42803.57196428027, 3591.28174365128, 3681.2637472505608, 4014.8996275093064, 4183.284853210169, 4253.617174454283, 32466.513002304986, 32538.837305813555, 32626.836865815756, 32679.019629411156, 32739.83630081858, 4304.698671093025, 4332.133573285356, 4414.381986521889, 4611.5619016193405, 4612.76936153191, 235532.0, 236998.0, 238593.0, 238620.0, 239408.0, 56517.43482565204, 56608.71695641495, 56852.43147568554, 57011.0, 57013.42986570164, 58436.0, 58666.706666466394, 58876.11685824716, 58913.0, 58935.70532147312, 16220.756688649444, 16274.104924229045, 16330.591735206715, 16380.590485237964, 16386.6722665546, 12175.269483830998, 12967.481300747864, 13029.413676384585, 13234.669133271746, 13348.933255333855, 16280.837191628169, 16358.591035224215, 16367.345306187619, 16383.836161638232, 16522.0, 5737.770489180386, 5929.584929054925, 5942.435468630473, 5944.346121926585, 5945.672987985702, 964.7636329099415, 1052.221356196409, 1083.534080345459, 1123.6011216018196, 1156.0866915136949, 32278.354432911212, 32343.838280808446, 32399.0, 32474.675253247166, 32644.83677581597, 1116.564539829451, 1147.7647082348099, 1158.7682463507163, 1181.7636472705494, 1184.437392238697, 15967.0, 16940.830591693924, 17012.659746804995, 17131.400400985905, 17345.65308693819, 153370.23314883577, 154211.22894385457, 154224.0, 154427.0, 155435.0, 57814.7109264451, 57854.71072644694, 58001.41998580044, 58069.41930580725, 58091.4190858086, 1252.480220708414, 1318.967025824362, 1376.6420730609914, 1378.2419669182111, 1399.6221020324456, 31394.21514462157, 32481.51277730883, 32905.506417404235, 33419.21052894287, 33916.660833391354, 49028.0, 49490.75254623848, 49683.0, 50021.74989125177, 50103.24845127253, 394842.0, 395934.0, 397942.0, 400677.0, 402015.0, 26874.59688104719, 28159.0, 28188.0, 29635.0, 29719.405611888073, 46678.0, 47186.76406617945, 47435.12190732393, 48424.65171381275, 48793.512064878894, 38375.61624383832, 39619.80190099031, 39674.801625991684, 40436.59563404328, 40906.79546602248, 39900.401493977035, 40700.0, 41397.0, 41431.7928410356, 41502.0, 39305.803470982464, 39688.80155599204, 39748.0, 39891.60108398879, 40757.388639171026, 34231.65768342285, 36518.45222321614, 36735.816320918224, 37211.0, 37400.438993415664, 27278.181654550393, 27662.585061223694, 27813.16560503188, 27996.160115196577, 28057.298567535974], "value": 19439.019308952094, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.1422122008772, 400.7975972134072, 404.20775280450334, 404.3732215066646, 424.4058318354304, 15812.130332831704, 19223.23107075719, 19276.421707348767, 19485.805141948593, 19490.122944467497, 18820.0, 19116.713249301272, 19611.21555137793, 20034.599308013832, 20266.797332026712, 5391.81128660497, 5544.029794785916, 5576.86057848555, 5802.854928626797, 5808.332041815203, 5743.339515955656, 5795.507381872525, 5832.591718579701, 5836.474717275444, 5839.211706419643, 5559.666420014792, 5712.628679135863, 5747.224124743168, 5754.251947246873, 5805.3323867755325, 5732.398098199692, 5757.366689664133, 5766.654000759926, 5774.047282198432, 5778.162166485857, 4953.331300274453, 5128.205128205134, 5169.612279079084, 5176.50823171798, 5178.275041494194, 23000.0, 23551.528969420688, 23569.646455303184, 23583.882080589487, 23717.644235336484, 2194.4294483434264, 2340.426595484117, 2362.244081893795, 2516.2719913546475, 3103.3793241351595, 10463.424511651856, 10639.0, 10698.946505267502, 10728.088112510446, 10836.187285953507, 1894.981050189508, 1968.2127149140315, 1974.1511150205406, 1980.9896952554302, 1984.0674882805058, 62005.6899715503, 72867.0, 85044.0, 86334.56832715859, 87611.12388876158, 10979.945100274606, 11120.888791112147, 11174.21780475375, 11174.776504470028, 11507.827382589268, 16044.919775401282, 16055.190341690157, 16070.116143612213, 16101.0, 16104.597385065466, 7956.403269754792, 8153.5923203839575, 8174.100848906615, 8197.426180167331, 8355.95822020886, 7554.924450755422, 7739.574323412154, 7753.767386978399, 7890.092639346434, 7929.960350198212, 4829.106615276185, 4859.70841749489, 4882.926756098661, 4936.950630493721, 4988.775505102279, 1997.9400617981485, 2011.095007246745, 2011.3262057210998, 2037.847161462896, 2069.803368679973, 845.3575282785005, 972.9590624752486, 999.1856636840952, 1017.2014968250036, 1026.9178465722725, 18876.622467550573, 19435.611287774165, 19452.902735486514, 19487.8051219486, 19732.802671973383, 959.2374062620233, 964.9228061755043, 1012.7518757904213, 1017.3133135133755, 1032.3031953431555, 9683.41899486033, 10057.748556286151, 10197.592096316064, 10698.090662293636, 10805.243632945776, 487790.0, 488467.0, 496886.0, 497071.0, 499318.0, 89148.0, 89539.55230223807, 91211.54394228119, 91931.0, 92079.0, 987.3631507677508, 1040.6305761454726, 1058.962936297226, 1060.4962642744636, 1062.4581463453694, 16470.258838352063, 16818.663626727153, 17224.31102755926, 17261.65476690484, 17383.130843457522, 40639.59360406358, 41801.58198417977, 42381.788091059345, 42571.3614295792, 43450.56549434465, 56872.431275686715, 57578.0, 57769.13346299725, 57944.420555793906, 58713.41286587079, 8240.175982401952, 8380.24577788002, 8498.32013438911, 8579.184977427134, 8587.22714955657, 11120.721981950515, 12428.875711243134, 12548.686282843002, 12558.43487043067, 13095.869041309465, 10442.477876106012, 10611.151107911193, 10958.342499450058, 11012.724681883017, 11399.430028498708, 10831.891681083089, 10945.124390048617, 11001.944990274997, 11034.84134165901, 11245.0, 9927.801443970935, 11016.228863979764, 11055.0, 11222.66332010041, 11345.376004319842, 8319.376046796635, 8329.12544182852, 8386.37102217324, 8552.102029287083, 8642.049374568918, 10480.737981550523, 10755.784884302113, 11028.5037173329, 11274.830877536679, 11449.771004580029], "value": 9159.85796873093, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.815479910212, 1416.4759039155508, 1469.88975826813, 1577.589826645072, 1804.350433843816, 8310.501369917802, 8709.69516066937, 8798.604062817181, 9057.547122643875, 9066.773330666736, 8874.733757987271, 8955.507447090407, 8995.685151019716, 9004.324675649319, 9006.909930900689, 2106.0522764755847, 2290.2213247495847, 2426.1629737740495, 2468.5926822074425, 2471.1351027140536, 2379.9643005354847, 2401.8318717689767, 2404.89177986991, 2407.16952651335, 2407.9157229496973, 2364.5389149115845, 2367.218817790134, 2374.9525009499885, 2377.1917548033666, 2400.0, 2255.391044418014, 2287.63397856343, 2295.3343530376337, 2297.827662925279, 2333.9533209335887, 2316.7567405422355, 2336.4626135988647, 2336.4742932840145, 2338.4504641409253, 2338.5206032763244, 9033.774155646095, 9094.954525227398, 9096.818063638757, 9097.772555686095, 9110.997790243067, 5048.974755126201, 5192.065428222918, 5218.895622087575, 5221.138512145491, 5223.425423203445, 3131.561581378609, 3131.6825549093733, 3279.737620990315, 3322.1528510229978, 3332.683395077464, 1031.2492268104975, 1041.0786453988198, 1058.2222066780987, 1060.4379678770188, 1075.4300220882944, 12241.143119981605, 12244.571439999647, 12351.135420520568, 12356.691082723004, 12373.010159187334, 4196.433481479961, 4205.116925445691, 4307.569243075667, 4328.913421731548, 4483.103379324149, 9700.320977531646, 9701.88428330751, 9703.223742100616, 9706.563204655808, 9715.27135464843, 5194.92207616886, 5195.948040519546, 5196.818111366084, 5202.531772140525, 5206.817761378334, 4257.680673949466, 4607.216773148572, 4821.2045012573235, 4868.294097355863, 4871.098846713369, 3709.684676802502, 3754.6245375462236, 3834.3481608126685, 3855.8264878080554, 3866.941995870064, 1141.69174322934, 1161.3960740414936, 1164.2141554450711, 1173.8884805943421, 1182.5092586576475, 821.6015232612178, 872.4154816272985, 950.3205208276116, 955.0640372434914, 974.449436068619, 5974.9402505974385, 6196.938030619636, 6273.0, 6286.49708023357, 6382.712777925005, 907.1744712311764, 923.9353245272766, 938.863864739609, 944.9574769135405, 1023.4013102335068, 3913.6673382762215, 4106.117184805281, 4140.109876376593, 4150.252954468165, 4325.394444777734, 10860.348379097279, 11206.663800086011, 11233.32600044, 11243.0, 11253.437328133561, 11139.443027848574, 11142.888571114347, 11191.888081119247, 11232.719182020352, 11237.775244495228, 8365.665373385118, 8392.370572207108, 8492.447990880573, 8503.489790612583, 8509.27671147947, 5647.0, 5917.37867523921, 5990.191324171181, 6025.728842202024, 6073.210482637229, 9527.38072025316, 10117.0, 10212.642557510451, 10223.744406389602, 10238.53926573291, 10060.39637621745, 10198.59205631796, 10307.639232626821, 10318.793624127326, 10332.845007325046, 6859.862802744017, 7363.668634911334, 7853.960730196541, 8029.0, 8067.717629882926, 8138.877916831134, 8588.398812083095, 8598.398112132341, 8607.82784344297, 8632.266257367943, 7640.197779233101, 7764.728234511765, 7800.492967957065, 7877.212278772077, 7961.562114083781, 7536.1333446654335, 7647.082350118021, 7707.152213256425, 7715.498492597963, 7719.421043421654, 6936.237013928363, 7014.894776578458, 7062.223155452793, 7071.292870712888, 7076.787696369117, 5152.252923326172, 5216.0, 5216.2436446714255, 5257.106291930305, 5532.3637781655625, 5194.870128246673, 5201.297824793604, 5204.0892843751435, 5258.290130832289, 5463.781448742165], "value": 4619.785473598476, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.0317346695299, 340.5487728759394, 343.0600155573728, 351.94416749750746, 354.7587640404524, 5131.435542090367, 5214.0, 5315.468453154687, 5337.7598008089635, 5360.490753378433, 5215.58275337973, 5237.45006774288, 5255.658382205154, 5277.419483856773, 5304.575633949285, 4186.39297301891, 4191.161767646476, 4193.412922190896, 4229.788510574459, 4275.380069889864, 4009.5188577370755, 4190.287651099306, 4247.915041699164, 4251.383549385338, 4263.955330943923, 4125.0, 4129.988152902543, 4138.379243113527, 4144.399062135988, 4160.500739911215, 4116.526599441057, 4138.751674899516, 4181.348367394892, 4188.643965262956, 4207.684423668237, 3706.425504046877, 3707.1102935295335, 3710.9072273193115, 3714.1271801126713, 3721.5348081489815, 5070.6957582545165, 5112.207607820794, 5135.075686376451, 5146.639735218536, 5186.325777648919, 4720.693154944917, 4721.905561888743, 4734.171519983989, 4748.38271024768, 4752.904941901143, 5442.782288708447, 5459.816073570603, 5462.0, 5465.863353416156, 5540.556755459554, 3724.571674257468, 3877.224555088965, 3877.5734669186368, 3897.629725176104, 3910.6871450284193, 5672.631278966854, 5689.971550142264, 5691.886162276773, 5705.686187259699, 5717.313922329306, 4083.1833633273463, 4101.507819061731, 4116.711830171919, 4121.278776214211, 4121.649659778953, 3899.805009749558, 4116.4648595682365, 4154.646855017297, 4156.231097247019, 4176.477940257468, 4686.95313046872, 4688.413948256468, 4713.457952335457, 4714.679889630909, 4748.1956856394345, 5530.640508366943, 5535.667859928417, 5538.556915446755, 5575.749091290902, 5579.497845193952, 1343.7917122845975, 1350.3180893648841, 1388.298909050923, 1407.240090351199, 1407.9859201407855, 952.3809523809441, 962.8988956159502, 975.1418751498636, 991.5686676295834, 1001.7695929936156, 5290.206469029677, 5302.390225124083, 5302.602304827154, 5317.760700768475, 5318.521333080042, 5574.219609254707, 5607.158926161109, 5609.579281553818, 5617.38208797032, 5628.493435590816, 5118.10433174193, 5187.754938814657, 5212.374515058217, 5313.920291195635, 5346.866328341823, 2606.89572417105, 2613.7908967282574, 2618.3977685132145, 2621.501914636213, 2652.602109683563, 5069.493050694901, 5088.363954505687, 5091.541761241506, 5107.591392688576, 5115.2582875483595, 5055.9241611375855, 5085.618578606619, 5321.802594416303, 5701.657900525982, 5735.426457354231, 5809.157672137517, 5819.185314056035, 5823.70881455934, 5825.766969321265, 5872.412758724094, 5551.555875529867, 5672.688002159921, 5719.8570035749435, 5725.398833122462, 5731.369549349652, 5606.215129881738, 5627.606067575231, 5648.23748793924, 5648.887022259614, 5657.971710141423, 5841.414489625793, 5844.853878653068, 5891.0, 5910.320313164041, 5926.911096333644, 4053.0, 4066.2477441673386, 4083.3466645336603, 4127.9566614526, 4189.49726032878, 5383.27735124772, 5640.266765320482, 5641.661500309994, 5649.576281778801, 5677.914831277451, 5365.6244062916785, 5515.310586176728, 5536.833894983157, 5547.611667183259, 5551.529569341605, 5522.475364840334, 5532.2254884315425, 5542.944570554243, 5583.850217666147, 5585.497305242548, 5218.373795144607, 5411.648242864201, 5426.449568702704, 5476.233327334096, 5480.3971563127225, 5504.4770746778995, 5507.614466987273, 5513.724313784375, 5516.310461192351, 5537.224788529528, 5388.488093631099, 5408.972955135199, 5430.402655707948, 5459.317585301837, 5463.7814487420055], "value": 4195.657495610763, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.879059350504, 768.7501561992353, 782.4522834016186, 799.6921185343642, 825.3397282174261, 13548.525801596947, 14064.71870562588, 15993.999999999985, 17026.06356650384, 17164.656706865855, 13088.47646094155, 16248.83751162488, 16257.51227463175, 16269.674606507891, 16356.427525036626, 659.9901001484982, 791.3392317414952, 812.3744716568239, 815.351795322717, 815.902091748988, 793.5833687314177, 809.247399918076, 809.8096947217399, 810.5825499867573, 812.8293058457731, 861.4228466927143, 863.0032312678866, 863.3266052479077, 863.8704194370863, 863.8876945997011, 857.7126662568047, 858.5792961448876, 858.9914100858974, 859.3297228161981, 860.6428332242143, 625.6590158363657, 625.8372823066036, 626.1140486212005, 626.2047200055904, 626.261012005833, 15540.844591554052, 15544.300506477235, 15566.455174068853, 15572.766408503881, 15576.61058473536, 14342.498012569615, 14653.6336591585, 15476.148811815345, 15480.5355839325, 15760.448384306608, 11949.581764638282, 12363.567275145328, 12419.689507762378, 12547.435365408488, 12978.805317920238, 3846.8461261549505, 3851.634094761021, 3864.0339915021245, 3983.4224037514687, 3988.561258261589, 85108.57445712732, 86265.0, 86392.56803716005, 87577.12422875817, 88224.5588772052, 11514.76970460586, 11869.465874035517, 11916.702082447835, 12099.516019359127, 12156.696082598006, 22608.434789130075, 22753.658695119586, 22788.08847646109, 22793.658095128587, 22814.543709126057, 7542.924570754222, 7629.351505122127, 8104.756857294173, 8133.8373232536205, 8537.530435825965, 4998.325226094504, 5063.569596584258, 5096.362954630671, 5123.436421993578, 5330.466953304638, 3596.4245720684567, 3707.7589956652732, 3712.7215458840697, 3715.0898029982286, 3718.7768733876055, 621.4375989729302, 627.1783963008473, 634.9174607301113, 639.5331408072134, 640.2381166411955, 591.9970400147972, 715.4097869257884, 740.2190688823235, 773.7640019794017, 797.8763291689797, 9418.952905235567, 9885.209183265322, 10267.589296428208, 10331.380117192992, 10500.947495262628, 613.1385403508078, 613.6348872420903, 674.7672053141658, 699.937005669482, 764.144158542436, 4962.751862406865, 5075.162598171334, 5094.920982009785, 5127.615428842777, 5132.178851383761, 97465.0, 100067.0, 110309.0, 125457.0, 129079.51045874368, 88008.0, 91688.0, 92285.53857230672, 92372.0, 93085.53457232671, 69349.65325173443, 84264.57867710623, 86190.0, 87446.56276718575, 88008.0, 10132.594696212069, 10407.843882341922, 10552.78894422092, 10693.144548436261, 10718.624848130277, 49764.75117624389, 49903.500964989886, 50768.0, 51876.74061629668, 52523.0, 69860.30139698742, 77057.61471192609, 78191.21808782067, 78560.60719696365, 80043.19956800358, 13287.53493627757, 13470.326483675974, 13478.40095169637, 14057.718845623234, 14173.22047287409, 15373.461928832437, 15613.297401616728, 15894.682106357577, 15946.60133496672, 16602.08688522143, 13802.861971380158, 14114.576562703136, 14219.857801421853, 15208.315625797088, 15779.605509862346, 13816.585502434944, 14547.640815677561, 15092.320845562195, 15511.767323490383, 15941.442049528674, 13367.732645347232, 14011.859881401055, 14027.649308767363, 14289.714205716036, 14328.355224014736, 11420.0, 11460.656180314603, 11731.941340293244, 12075.27548347102, 12248.87751122513, 11417.771644567229, 11655.88344116548, 11700.531978720755, 11934.462949167135, 11963.521459141537], "value": 7777.225277556768, "unit": "score"}], "name": "dromaeo_css", "value": 7207.394800494968, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 131.0, 112.0, 115.0, 113.0, 111.0, 115.0, 114.0, 112.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 120.0, 145.0, 171.0, 120.0, 142.0, 145.0, 120.0, 186.0, 121.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 161.0, 161.0, 162.0, 163.0, 162.0, 162.0, 160.0, 163.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 188.0, 76.0, 76.0, 75.0, 75.0, 164.0, 76.0], "value": 95.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 137.0, 82.0, 82.0, 82.0, 147.0, 81.0, 81.0, 147.0], "value": 100.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 51.0, 52.0, 52.0, 51.0, 52.0, 52.0, 52.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 61.0, 63.0, 62.0, 61.0, 61.0, 62.0, 63.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 86.0, 79.0, 80.0, 80.0, 78.0, 79.0, 79.0, 78.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 131.0, 132.0, 131.0, 133.0, 122.0, 131.0, 133.0, 163.0, 130.0], "value": 133.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 169.0, 162.0, 166.0, 169.0, 168.0, 175.0, 160.0, 176.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 63.0, 65.0, 65.0, 64.0, 66.0, 65.0, 67.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1531.3, "alertThreshold": 2.0}]} 22:41:58 INFO - Return code: 0 22:41:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:41:59 INFO - # TBPL SUCCESS # 22:41:59 INFO - Running post-action listener: _resource_record_post_action 22:41:59 INFO - [mozharness: 2016-08-30 05:41:59.023938Z] Finished run-tests step (success) 22:41:59 INFO - Running post-run listener: _resource_record_post_run 22:41:59 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406913024; Read time: 12; Write time: 258832 22:41:59 INFO - TinderboxPrint: CPU usage
13.1% 22:41:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 22:41:59 INFO - TinderboxPrint: I/O write bytes / time
406,913,024 / 258,832 22:41:59 INFO - TinderboxPrint: CPU idle
8,288.7 (86.9%) 22:41:59 INFO - TinderboxPrint: CPU user
1,197.6 (12.6%) 22:41:59 INFO - TinderboxPrint: Swap in / out
0 / 0 22:41:59 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118370304; Read time: 0; Write time: 81812 22:41:59 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 288542720; Read time: 0; Write time: 177020 22:41:59 INFO - Running post-run listener: _upload_blobber_files 22:41:59 INFO - Blob upload gear active. 22:41:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:41:59 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 22:41:59 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:41:59 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:42:00 INFO - (blobuploader) - INFO - Open directory for files ... 22:42:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:42:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:42:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:42:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:42:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:42:04 INFO - (blobuploader) - INFO - Done attempting. 22:42:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:42:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:42:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:42:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:42:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:42:05 INFO - (blobuploader) - INFO - Done attempting. 22:42:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:42:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:42:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:42:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:42:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:42:06 INFO - (blobuploader) - INFO - Done attempting. 22:42:06 INFO - (blobuploader) - INFO - Iteration through files over. 22:42:06 INFO - Return code: 0 22:42:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:42:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:42:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/223d157f25395f922f7c9457bab4618330c5261cc43ad361b273c155d192871b94f3bffa03021d5c60840c60f8b0c47012d2972e97e2bcffbae113c5bed7d3a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/660079a80e483b5e0ab97d23994b26f1c36098726b688a21a411d1b297606e6db394bb2255d5395c44230b469bc7fc456fefdc4a6fdcb2b9d3849d27543d6ccd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e191ed91b5cf6360444c251bee81c93e956adc176e4017580f6c96bcaa8b093671095ac7cf06bbf58b8e3515a5586b6831e90a6fb29bffa8b56cfef801a7c9e8"} 22:42:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:42:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:42:06 INFO - Contents: 22:42:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/223d157f25395f922f7c9457bab4618330c5261cc43ad361b273c155d192871b94f3bffa03021d5c60840c60f8b0c47012d2972e97e2bcffbae113c5bed7d3a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/660079a80e483b5e0ab97d23994b26f1c36098726b688a21a411d1b297606e6db394bb2255d5395c44230b469bc7fc456fefdc4a6fdcb2b9d3849d27543d6ccd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e191ed91b5cf6360444c251bee81c93e956adc176e4017580f6c96bcaa8b093671095ac7cf06bbf58b8e3515a5586b6831e90a6fb29bffa8b56cfef801a7c9e8"} 22:42:06 INFO - Running post-run listener: copy_logs_to_upload_dir 22:42:06 INFO - Copying logs to upload dir... 22:42:06 INFO - mkdir: /builds/slave/test/build/upload/logs 22:42:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.844711 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-29 22:42:06.058929) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 22:42:06.062550) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/223d157f25395f922f7c9457bab4618330c5261cc43ad361b273c155d192871b94f3bffa03021d5c60840c60f8b0c47012d2972e97e2bcffbae113c5bed7d3a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/660079a80e483b5e0ab97d23994b26f1c36098726b688a21a411d1b297606e6db394bb2255d5395c44230b469bc7fc456fefdc4a6fdcb2b9d3849d27543d6ccd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e191ed91b5cf6360444c251bee81c93e956adc176e4017580f6c96bcaa8b093671095ac7cf06bbf58b8e3515a5586b6831e90a6fb29bffa8b56cfef801a7c9e8"} build_url:https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011375 build_url: 'https://queue.taskcluster.net/v1/task/dqqTQ2HFS_GGXO2itbIiDA/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/Firefox/sha512/223d157f25395f922f7c9457bab4618330c5261cc43ad361b273c155d192871b94f3bffa03021d5c60840c60f8b0c47012d2972e97e2bcffbae113c5bed7d3a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/660079a80e483b5e0ab97d23994b26f1c36098726b688a21a411d1b297606e6db394bb2255d5395c44230b469bc7fc456fefdc4a6fdcb2b9d3849d27543d6ccd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e191ed91b5cf6360444c251bee81c93e956adc176e4017580f6c96bcaa8b093671095ac7cf06bbf58b8e3515a5586b6831e90a6fb29bffa8b56cfef801a7c9e8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 22:42:06.092277) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:42:06.092785) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1472532071.643877-2035529678 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004410 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:42:06.173834) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 22:42:06.174799) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 22:42:06.175357) ========= ========= Total master_lag: 0.18 =========