builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-064 starttime: 1467768963.05 results: success (0) buildid: 20160705171423 builduid: a1cc2c7a45834e1db8084688e98aa01c revision: bfcca87598b2ca8e31094d587b7b5cd033ef9577 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:03.050145) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:03.050773) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:03.051056) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:03.108061) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:03.108441) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-05 18:36:03-- 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% 229M=0s 2016-07-05 18:36:04 (229 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.334369 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:03.458705) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:03.459066) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019860 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:03.510847) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:03.511309) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bfcca87598b2ca8e31094d587b7b5cd033ef9577 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev bfcca87598b2ca8e31094d587b7b5cd033ef9577 --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=a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-05 18:36:04,202 truncating revision to first 12 chars 2016-07-05 18:36:04,202 Setting DEBUG logging. 2016-07-05 18:36:04,203 attempt 1/10 2016-07-05 18:36:04,203 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bfcca87598b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 18:36:04,522 unpacking tar archive at: fx-team-bfcca87598b2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.682908 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:04.209074) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:04.209397) ========= script_repo_revision: bfcca87598b2ca8e31094d587b7b5cd033ef9577 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:04.209840) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:04.210121) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 18:36:04.221706) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-07-05 18:36:04.222100) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:36:04 INFO - MultiFileLogger online at 20160705 18:36:04 in /builds/slave/test 18:36:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 18:36:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:36:04 INFO - {'append_to_log': False, 18:36:04 INFO - 'base_work_dir': '/builds/slave/test', 18:36:04 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 18:36:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:36:04 INFO - 'branch': 'Fx-Team-Non-PGO', 18:36:04 INFO - 'buildbot_json_path': 'buildprops.json', 18:36:04 INFO - 'config_files': ('talos/linux_config.py',), 18:36:04 INFO - 'default_actions': ('clobber', 18:36:04 INFO - 'read-buildbot-config', 18:36:04 INFO - 'download-and-extract', 18:36:04 INFO - 'populate-webroot', 18:36:04 INFO - 'create-virtualenv', 18:36:04 INFO - 'install', 18:36:04 INFO - 'run-tests'), 18:36:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:36:04 INFO - 'download_minidump_stackwalk': True, 18:36:04 INFO - 'download_symbols': 'ondemand', 18:36:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:36:04 INFO - 'tooltool.py': '/tools/tooltool.py', 18:36:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:36:04 INFO - '/tools/misc-python/virtualenv.py')}, 18:36:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:36:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:36:04 INFO - 'installer_path': 'installer.exe', 18:36:04 INFO - 'log_level': 'info', 18:36:04 INFO - 'log_name': 'talos', 18:36:04 INFO - 'log_to_console': True, 18:36:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:36:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:36:04 INFO - 'opt_config_files': (), 18:36:04 INFO - 'pip_index': False, 18:36:04 INFO - 'sps_profile': False, 18:36:04 INFO - 'sps_profile_interval': 0, 18:36:04 INFO - 'suite': 'dromaeojs', 18:36:04 INFO - 'system_bits': '32', 18:36:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:36:04 INFO - 'title': 'talos-linux64-ix-064', 18:36:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:36:04 INFO - 'use_talos_json': True, 18:36:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:36:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:36:04 INFO - 'work_dir': 'build'} 18:36:04 INFO - ##### 18:36:04 INFO - ##### Running clobber step. 18:36:04 INFO - ##### 18:36:04 INFO - Running pre-action listener: _resource_record_pre_action 18:36:04 INFO - Running main action method: clobber 18:36:04 INFO - rmtree: /builds/slave/test/build 18:36:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:36:05 INFO - Running post-action listener: _resource_record_post_action 18:36:05 INFO - ##### 18:36:05 INFO - ##### Finished clobber step (success) 18:36:05 INFO - ##### 18:36:05 INFO - ##### 18:36:05 INFO - ##### Running read-buildbot-config step. 18:36:05 INFO - ##### 18:36:05 INFO - Running pre-action listener: _resource_record_pre_action 18:36:05 INFO - Running main action method: read_buildbot_config 18:36:05 INFO - Using buildbot properties: 18:36:05 INFO - { 18:36:05 INFO - "project": "", 18:36:05 INFO - "product": "firefox", 18:36:05 INFO - "script_repo_revision": "production", 18:36:05 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:36:05 INFO - "repository": "", 18:36:05 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 18:36:05 INFO - "buildid": "20160705171423", 18:36:05 INFO - "pgo_build": "False", 18:36:05 INFO - "basedir": "/builds/slave/test", 18:36:05 INFO - "buildnumber": 1935, 18:36:05 INFO - "slavename": "talos-linux64-ix-064", 18:36:05 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:36:05 INFO - "platform": "ubuntu64_hw", 18:36:05 INFO - "scheduler": "tests-fx-team-linux64-talos", 18:36:05 INFO - "branch": "fx-team", 18:36:05 INFO - "repo_path": "integration/fx-team", 18:36:05 INFO - "revision": "bfcca87598b2ca8e31094d587b7b5cd033ef9577", 18:36:05 INFO - "stage_platform": "linux64", 18:36:05 INFO - "builduid": "a1cc2c7a45834e1db8084688e98aa01c", 18:36:05 INFO - "slavebuilddir": "test" 18:36:05 INFO - } 18:36:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:36:05 INFO - Running post-action listener: _resource_record_post_action 18:36:05 INFO - ##### 18:36:05 INFO - ##### Finished read-buildbot-config step (success) 18:36:05 INFO - ##### 18:36:05 INFO - ##### 18:36:05 INFO - ##### Running download-and-extract step. 18:36:05 INFO - ##### 18:36:05 INFO - Running pre-action listener: _resource_record_pre_action 18:36:05 INFO - Running main action method: download_and_extract 18:36:05 INFO - mkdir: /builds/slave/test/build/tests 18:36:05 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:05 INFO - https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:36:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:36:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:36:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:36:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:36:12 INFO - Downloaded 1361 bytes. 18:36:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:36:12 INFO - Using the following test package requirements: 18:36:12 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:36:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:36:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:36:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:12 INFO - u'jsshell-linux-x86_64.zip'], 18:36:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:36:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:36:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:36:12 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:36:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:36:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:36:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:12 INFO - https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:36:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:36:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:36:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:36:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:36:15 INFO - Downloaded 22730934 bytes. 18:36:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:36:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:36:17 INFO - Return code: 0 18:36:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:36:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:17 INFO - https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:36:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:36:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:36:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:36:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:36:26 INFO - Downloaded 22730934 bytes. 18:36:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:36:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:36:27 INFO - Return code: 0 18:36: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:27 INFO - https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:36:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:36:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:36:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:36:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:36:28 INFO - Downloaded 14403333 bytes. 18:36:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:36:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:36:28 INFO - Return code: 0 18:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:28 INFO - https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:36:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:36:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:36:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:36:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:36:30 INFO - Downloaded 56473763 bytes. 18:36:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:36:30 INFO - mkdir: /builds/slave/test/properties 18:36:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:36:30 INFO - Writing to file /builds/slave/test/properties/build_url 18:36:30 INFO - Contents: 18:36:30 INFO - build_url:https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:36:30 INFO - Running post-action listener: _resource_record_post_action 18:36:30 INFO - Running post-action listener: set_extra_try_arguments 18:36:30 INFO - ##### 18:36:30 INFO - ##### Finished download-and-extract step (success) 18:36:30 INFO - ##### 18:36:30 INFO - ##### 18:36:30 INFO - ##### Running populate-webroot step. 18:36:30 INFO - ##### 18:36:30 INFO - Running pre-action listener: _resource_record_pre_action 18:36:30 INFO - Running main action method: populate_webroot 18:36:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:36:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:36:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:36:30 INFO - '--tppagecycles', 18:36:30 INFO - '7'], 18:36:30 INFO - 'tests': ['tsvgm']}}, 18:36:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:36:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:36:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:36:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:36:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:30 INFO - 'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:36:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:36:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:30 INFO - 'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['damp', 'tps']}, 18:36:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:30 INFO - 'tests': ['damp', 'tps']}, 18:36:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['dromaeo_dom']}, 18:36:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:36:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['basic_compositor_video']}, 18:36:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:36:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['a11yr', 18:36:30 INFO - 'ts_paint', 18:36:30 INFO - 'tpaint', 18:36:30 INFO - 'sessionrestore', 18:36:30 INFO - 'sessionrestore_no_auto_restore', 18:36:30 INFO - 'tabpaint']}, 18:36:30 INFO - 'other-e10s': {'tests': ['a11yr', 18:36:30 INFO - 'ts_paint', 18:36:30 INFO - 'tpaint', 18:36:30 INFO - 'sessionrestore', 18:36:30 INFO - 'sessionrestore_no_auto_restore', 18:36:30 INFO - 'tabpaint']}, 18:36:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:36:30 INFO - 'ts_paint', 18:36:30 INFO - 'tpaint', 18:36:30 INFO - 'sessionrestore', 18:36:30 INFO - 'sessionrestore_no_auto_restore', 18:36:30 INFO - 'tabpaint']}, 18:36:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:36:30 INFO - 'ts_paint', 18:36:30 INFO - 'tpaint', 18:36:30 INFO - 'sessionrestore', 18:36:30 INFO - 'sessionrestore_no_auto_restore', 18:36:30 INFO - 'tabpaint']}, 18:36:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['a11yr', 18:36:30 INFO - 'ts_paint', 18:36:30 INFO - 'tpaint', 18:36:30 INFO - 'sessionrestore', 18:36:30 INFO - 'sessionrestore_no_auto_restore', 18:36:30 INFO - 'tabpaint']}, 18:36:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['a11yr', 18:36:30 INFO - 'ts_paint', 18:36:30 INFO - 'tpaint', 18:36:30 INFO - 'sessionrestore', 18:36:30 INFO - 'sessionrestore_no_auto_restore', 18:36:30 INFO - 'tabpaint']}, 18:36:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['tsvgx', 18:36:30 INFO - 'tsvgr_opacity', 18:36:30 INFO - 'tart', 18:36:30 INFO - 'tscrollx', 18:36:30 INFO - 'cart']}, 18:36:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:36:30 INFO - 'tsvgr_opacity', 18:36:30 INFO - 'tart', 18:36:30 INFO - 'tscrollx', 18:36:30 INFO - 'cart']}, 18:36:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:30 INFO - 'talos_options': ['--disable-e10s'], 18:36:30 INFO - 'tests': ['tp5o']}, 18:36:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:30 INFO - 'tests': ['tp5o']}, 18:36:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:30 INFO - 'talos_options': ['--disable-e10s', 18:36:30 INFO - '--xperf_path', 18:36:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:36:30 INFO - 'tests': ['tp5n']}, 18:36:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:30 INFO - 'talos_options': ['--xperf_path', 18:36:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:36:30 INFO - 'tests': ['tp5n']}}, 18:36:30 INFO - 'talos.zip': {'path': '', 18:36:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:36:30 INFO - Running post-action listener: _resource_record_post_action 18:36:30 INFO - ##### 18:36:30 INFO - ##### Finished populate-webroot step (success) 18:36:30 INFO - ##### 18:36:30 INFO - ##### 18:36:30 INFO - ##### Running create-virtualenv step. 18:36:30 INFO - ##### 18:36:30 INFO - Running pre-action listener: _resource_record_pre_action 18:36:30 INFO - Running main action method: create_virtualenv 18:36:30 INFO - Creating virtualenv /builds/slave/test/build/venv 18:36:30 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 18:36:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:36:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:36:31 INFO - Using real prefix '/usr' 18:36:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:36:31 INFO - Installing distribute.............................................................................................................................................................................................done. 18:36:33 INFO - Installing pip.................done. 18:36:33 INFO - Return code: 0 18:36:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:33 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 0x27bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7b3b0>, '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 0x2a7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', '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 18:36:33 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 18:36:33 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 18:36:33 INFO - Using env: {'DISPLAY': ':0', 18:36:33 INFO - 'HOME': '/home/cltbld', 18:36:33 INFO - 'LANG': 'en_US.UTF-8', 18:36:33 INFO - 'LANGUAGE': 'en_US:en', 18:36:33 INFO - 'LOGNAME': 'cltbld', 18:36:33 INFO - 'MAIL': '/var/mail/cltbld', 18:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:33 INFO - 'MOZ_NO_REMOTE': '1', 18:36:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:33 INFO - 'NO_EM_RESTART': '1', 18:36:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:33 INFO - 'PWD': '/builds/slave/test', 18:36:33 INFO - 'SHELL': '/bin/bash', 18:36:33 INFO - 'SHLVL': '1', 18:36:33 INFO - 'TERM': 'linux', 18:36:33 INFO - 'TMOUT': '86400', 18:36:33 INFO - 'USER': 'cltbld', 18:36:33 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', 18:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:33 INFO - '_': '/tools/buildbot/bin/python'} 18:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:33 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 18:36:33 INFO - Cleaning up... 18:36:33 INFO - Return code: 0 18:36:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:33 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 0x27bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7b3b0>, '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 0x2a7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', '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 18:36:33 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 18:36:33 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 18:36:33 INFO - Using env: {'DISPLAY': ':0', 18:36:33 INFO - 'HOME': '/home/cltbld', 18:36:33 INFO - 'LANG': 'en_US.UTF-8', 18:36:33 INFO - 'LANGUAGE': 'en_US:en', 18:36:33 INFO - 'LOGNAME': 'cltbld', 18:36:33 INFO - 'MAIL': '/var/mail/cltbld', 18:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:33 INFO - 'MOZ_NO_REMOTE': '1', 18:36:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:33 INFO - 'NO_EM_RESTART': '1', 18:36:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:33 INFO - 'PWD': '/builds/slave/test', 18:36:33 INFO - 'SHELL': '/bin/bash', 18:36:33 INFO - 'SHLVL': '1', 18:36:33 INFO - 'TERM': 'linux', 18:36:33 INFO - 'TMOUT': '86400', 18:36:33 INFO - 'USER': 'cltbld', 18:36:33 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', 18:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:33 INFO - '_': '/tools/buildbot/bin/python'} 18:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:33 INFO - Downloading/unpacking psutil>=3.1.1 18:36:33 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 18:36:33 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 18:36:33 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 18:36:33 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 18:36:33 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 18:36:33 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 18:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:36:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:36:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:36:35 INFO - Installing collected packages: psutil 18:36:35 INFO - Running setup.py install for psutil 18:36:36 INFO - building 'psutil._psutil_linux' extension 18:36:36 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 18:36:36 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 18:36:36 INFO - building 'psutil._psutil_posix' extension 18:36:36 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 18:36:36 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 18:36:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:36:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:36:36 INFO - Successfully installed psutil 18:36:36 INFO - Cleaning up... 18:36:36 INFO - Return code: 0 18:36:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:36:36 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:36 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:36 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 0x27bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7b3b0>, '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 0x2a7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', '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 18:36:36 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 18:36:36 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 18:36:36 INFO - Using env: {'DISPLAY': ':0', 18:36:36 INFO - 'HOME': '/home/cltbld', 18:36:36 INFO - 'LANG': 'en_US.UTF-8', 18:36:36 INFO - 'LANGUAGE': 'en_US:en', 18:36:36 INFO - 'LOGNAME': 'cltbld', 18:36:36 INFO - 'MAIL': '/var/mail/cltbld', 18:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:36 INFO - 'MOZ_NO_REMOTE': '1', 18:36:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:36 INFO - 'NO_EM_RESTART': '1', 18:36:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:36 INFO - 'PWD': '/builds/slave/test', 18:36:36 INFO - 'SHELL': '/bin/bash', 18:36:36 INFO - 'SHLVL': '1', 18:36:36 INFO - 'TERM': 'linux', 18:36:36 INFO - 'TMOUT': '86400', 18:36:36 INFO - 'USER': 'cltbld', 18:36:36 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', 18:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:36 INFO - '_': '/tools/buildbot/bin/python'} 18:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:36:36 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 18:36:36 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 18:36:36 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 18:36:36 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 18:36:36 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 18:36:36 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 18:36:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:36:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:36:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:36:38 INFO - Installing collected packages: mozsystemmonitor 18:36:38 INFO - Running setup.py install for mozsystemmonitor 18:36:38 INFO - Successfully installed mozsystemmonitor 18:36:38 INFO - Cleaning up... 18:36:38 INFO - Return code: 0 18:36:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:36: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:38 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 0x27bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7b3b0>, '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 0x2a7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', '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 18:36:38 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 18:36:38 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 18:36:38 INFO - Using env: {'DISPLAY': ':0', 18:36:38 INFO - 'HOME': '/home/cltbld', 18:36:38 INFO - 'LANG': 'en_US.UTF-8', 18:36:38 INFO - 'LANGUAGE': 'en_US:en', 18:36:38 INFO - 'LOGNAME': 'cltbld', 18:36:38 INFO - 'MAIL': '/var/mail/cltbld', 18:36:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:38 INFO - 'MOZ_NO_REMOTE': '1', 18:36:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:38 INFO - 'NO_EM_RESTART': '1', 18:36:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:38 INFO - 'PWD': '/builds/slave/test', 18:36:38 INFO - 'SHELL': '/bin/bash', 18:36:38 INFO - 'SHLVL': '1', 18:36:38 INFO - 'TERM': 'linux', 18:36:38 INFO - 'TMOUT': '86400', 18:36:38 INFO - 'USER': 'cltbld', 18:36:38 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', 18:36:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:38 INFO - '_': '/tools/buildbot/bin/python'} 18:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:39 INFO - Downloading/unpacking jsonschema==2.5.1 18:36:39 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 18:36:39 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 18:36:39 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 18:36:39 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 18:36:39 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 18:36:39 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 18:36:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:36:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:36:40 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 18:36:40 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 18:36:40 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 18:36:40 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 18:36:40 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 18:36:40 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 18:36:41 INFO - Downloading functools32-3.2.3-2.tar.gz 18:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:36:41 INFO - warning: no files found matching '*.txt' 18:36:41 INFO - no previously-included directories found matching 'build' 18:36:41 INFO - no previously-included directories found matching 'dist' 18:36:41 INFO - no previously-included directories found matching '.git*' 18:36:41 INFO - Installing collected packages: jsonschema, functools32 18:36:41 INFO - Running setup.py install for functools32 18:36:41 INFO - warning: no files found matching '*.txt' 18:36:41 INFO - no previously-included directories found matching 'build' 18:36:41 INFO - no previously-included directories found matching 'dist' 18:36:41 INFO - no previously-included directories found matching '.git*' 18:36:41 INFO - Successfully installed jsonschema functools32 18:36:41 INFO - Cleaning up... 18:36:41 INFO - Return code: 0 18:36:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:36:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:41 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 0x27bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7b3b0>, '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 0x2a7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', '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 18:36:41 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 18:36:41 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 18:36:41 INFO - Using env: {'DISPLAY': ':0', 18:36:41 INFO - 'HOME': '/home/cltbld', 18:36:41 INFO - 'LANG': 'en_US.UTF-8', 18:36:41 INFO - 'LANGUAGE': 'en_US:en', 18:36:41 INFO - 'LOGNAME': 'cltbld', 18:36:41 INFO - 'MAIL': '/var/mail/cltbld', 18:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:41 INFO - 'MOZ_NO_REMOTE': '1', 18:36:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:41 INFO - 'NO_EM_RESTART': '1', 18:36:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:41 INFO - 'PWD': '/builds/slave/test', 18:36:41 INFO - 'SHELL': '/bin/bash', 18:36:41 INFO - 'SHLVL': '1', 18:36:41 INFO - 'TERM': 'linux', 18:36:41 INFO - 'TMOUT': '86400', 18:36:41 INFO - 'USER': 'cltbld', 18:36:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', 18:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:41 INFO - '_': '/tools/buildbot/bin/python'} 18:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:41 INFO - Downloading/unpacking blobuploader==1.2.4 18:36:41 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 18:36:41 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 18:36:41 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 18:36:41 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 18:36:41 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 18:36:41 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 18:36:43 INFO - Downloading blobuploader-1.2.4.tar.gz 18:36:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:36:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:36: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 18:36: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 18:36: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 18:36: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 18:36: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 18:36: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 18:36:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:36:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:36: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 18:36: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 18:36: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 18:36: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 18:36: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 18:36: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 18:36:44 INFO - Downloading docopt-0.6.1.tar.gz 18:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:36:44 INFO - Installing collected packages: blobuploader, requests, docopt 18:36:44 INFO - Running setup.py install for blobuploader 18:36:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:36:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:36:44 INFO - Running setup.py install for requests 18:36:44 INFO - Running setup.py install for docopt 18:36:44 INFO - Successfully installed blobuploader requests docopt 18:36:44 INFO - Cleaning up... 18:36:44 INFO - Return code: 0 18:36:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:36:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:44 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 0x27bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7b3b0>, '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 0x2a7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', '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 18:36:44 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 18:36:44 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 18:36:44 INFO - Using env: {'DISPLAY': ':0', 18:36:44 INFO - 'HOME': '/home/cltbld', 18:36:44 INFO - 'LANG': 'en_US.UTF-8', 18:36:44 INFO - 'LANGUAGE': 'en_US:en', 18:36:44 INFO - 'LOGNAME': 'cltbld', 18:36:44 INFO - 'MAIL': '/var/mail/cltbld', 18:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:44 INFO - 'MOZ_NO_REMOTE': '1', 18:36:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:44 INFO - 'NO_EM_RESTART': '1', 18:36:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:44 INFO - 'PWD': '/builds/slave/test', 18:36:44 INFO - 'SHELL': '/bin/bash', 18:36:44 INFO - 'SHLVL': '1', 18:36:44 INFO - 'TERM': 'linux', 18:36:44 INFO - 'TMOUT': '86400', 18:36:44 INFO - 'USER': 'cltbld', 18:36:44 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', 18:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:44 INFO - '_': '/tools/buildbot/bin/python'} 18:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:36:45 INFO - Running setup.py (path:/tmp/pip-FihdCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:36:45 INFO - Running setup.py (path:/tmp/pip-1crKHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:36:45 INFO - Running setup.py (path:/tmp/pip-0SzPqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:36:45 INFO - Running setup.py (path:/tmp/pip-I2yRZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:36:45 INFO - Running setup.py (path:/tmp/pip-mzxTMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:36:45 INFO - Running setup.py (path:/tmp/pip-_civDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:36:45 INFO - Running setup.py (path:/tmp/pip-8Th5Gu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:36:45 INFO - Running setup.py (path:/tmp/pip-QMfX8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:36:45 INFO - Running setup.py (path:/tmp/pip-c3pkGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:36:45 INFO - Running setup.py (path:/tmp/pip-6KOcfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:36:45 INFO - Running setup.py (path:/tmp/pip-6g7qXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:36:45 INFO - Running setup.py (path:/tmp/pip-Lm96FR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:36:45 INFO - Running setup.py (path:/tmp/pip-FLmPha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:36:46 INFO - Running setup.py (path:/tmp/pip-3_TblX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:46 INFO - Running setup.py (path:/tmp/pip-QMAnlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:36:46 INFO - Running setup.py (path:/tmp/pip-c6sHrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:36:46 INFO - Running setup.py (path:/tmp/pip-ZsZYP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:36:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:36:46 INFO - Running setup.py install for manifestparser 18:36:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:36:46 INFO - Running setup.py install for mozcrash 18:36:46 INFO - Running setup.py install for mozdebug 18:36:46 INFO - Running setup.py install for mozdevice 18:36:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:36:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:36:46 INFO - Running setup.py install for mozfile 18:36:46 INFO - Running setup.py install for mozhttpd 18:36:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:36:46 INFO - Running setup.py install for mozinfo 18:36:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Running setup.py install for mozInstall 18:36:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Running setup.py install for mozleak 18:36:47 INFO - Running setup.py install for mozlog 18:36:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Running setup.py install for moznetwork 18:36:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Running setup.py install for mozprocess 18:36:47 INFO - Running setup.py install for mozprofile 18:36:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Running setup.py install for mozrunner 18:36:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:36:47 INFO - Running setup.py install for mozscreenshot 18:36:47 INFO - Running setup.py install for moztest 18:36:48 INFO - Running setup.py install for mozversion 18:36:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:36:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:36:48 INFO - Cleaning up... 18:36:48 INFO - Return code: 0 18:36:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:36:48 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:48 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:48 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 0x27bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7b3b0>, '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 0x2a7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', '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 18:36:48 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 18:36:48 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 18:36:48 INFO - Using env: {'DISPLAY': ':0', 18:36:48 INFO - 'HOME': '/home/cltbld', 18:36:48 INFO - 'LANG': 'en_US.UTF-8', 18:36:48 INFO - 'LANGUAGE': 'en_US:en', 18:36:48 INFO - 'LOGNAME': 'cltbld', 18:36:48 INFO - 'MAIL': '/var/mail/cltbld', 18:36:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:48 INFO - 'MOZ_NO_REMOTE': '1', 18:36:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:48 INFO - 'NO_EM_RESTART': '1', 18:36:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:48 INFO - 'PWD': '/builds/slave/test', 18:36:48 INFO - 'SHELL': '/bin/bash', 18:36:48 INFO - 'SHLVL': '1', 18:36:48 INFO - 'TERM': 'linux', 18:36:48 INFO - 'TMOUT': '86400', 18:36:48 INFO - 'USER': 'cltbld', 18:36:48 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', 18:36:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:48 INFO - '_': '/tools/buildbot/bin/python'} 18:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:36:48 INFO - Running setup.py (path:/tmp/pip-NHJ8bS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:36:48 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)) 18:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:36:48 INFO - Running setup.py (path:/tmp/pip-WxvLyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:36:48 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)) 18:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:36:48 INFO - Running setup.py (path:/tmp/pip-o003dO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:36:48 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)) 18:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:36:48 INFO - Running setup.py (path:/tmp/pip-E7JVMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:36:48 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)) 18:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:36:48 INFO - Running setup.py (path:/tmp/pip-9nAJna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:36:48 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)) 18:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:36:48 INFO - Running setup.py (path:/tmp/pip-jIDaNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:36:48 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)) 18:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:36:48 INFO - Running setup.py (path:/tmp/pip-HmitqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:36:48 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)) 18:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:36:48 INFO - Running setup.py (path:/tmp/pip-ep0uFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:36:48 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)) 18:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:36:48 INFO - Running setup.py (path:/tmp/pip-sNF7oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:36:48 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)) 18:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:36:49 INFO - Running setup.py (path:/tmp/pip-OLj2vS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 18:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:36:49 INFO - Running setup.py (path:/tmp/pip-YL2slW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:36:49 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)) 18:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:36:49 INFO - Running setup.py (path:/tmp/pip-3VpKQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 18:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:36:49 INFO - Running setup.py (path:/tmp/pip-1xwhyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:36:49 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)) 18:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:36:49 INFO - Running setup.py (path:/tmp/pip-2ZqBIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 18:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:49 INFO - Running setup.py (path:/tmp/pip-itK9sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:49 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)) 18:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:36:49 INFO - Running setup.py (path:/tmp/pip-v4ye03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:36:49 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)) 18:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:36:49 INFO - Running setup.py (path:/tmp/pip-8in4GY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:36:49 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)) 18:36:49 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)) 18:36:49 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)) 18:36:49 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)) 18:36:49 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)) 18:36:49 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)) 18:36:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:36:49 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 18:36:49 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 18:36:49 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 18:36:49 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 18:36:49 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 18:36:49 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 18:36:51 INFO - Downloading blessings-1.6.tar.gz 18:36:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:36:51 INFO - Installing collected packages: blessings 18:36:51 INFO - Running setup.py install for blessings 18:36:51 INFO - Successfully installed blessings 18:36:51 INFO - Cleaning up... 18:36:51 INFO - Return code: 0 18:36:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:36:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:36:51 INFO - Reading from file tmpfile_stdout 18:36:51 INFO - Current package versions: 18:36:51 INFO - argparse == 1.2.1 18:36:51 INFO - blessings == 1.6 18:36:51 INFO - blobuploader == 1.2.4 18:36:51 INFO - docopt == 0.6.1 18:36:51 INFO - functools32 == 3.2.3-2 18:36:51 INFO - jsonschema == 2.5.1 18:36:51 INFO - manifestparser == 1.1 18:36:51 INFO - mozInstall == 1.12 18:36:51 INFO - mozcrash == 0.17 18:36:51 INFO - mozdebug == 0.1 18:36:51 INFO - mozdevice == 0.48 18:36:51 INFO - mozfile == 1.2 18:36:51 INFO - mozhttpd == 0.7 18:36:51 INFO - mozinfo == 0.9 18:36:51 INFO - mozleak == 0.1 18:36:51 INFO - mozlog == 3.2 18:36:51 INFO - moznetwork == 0.27 18:36:51 INFO - mozprocess == 0.22 18:36:51 INFO - mozprofile == 0.28 18:36:51 INFO - mozrunner == 6.11 18:36:51 INFO - mozscreenshot == 0.1 18:36:51 INFO - mozsystemmonitor == 0.3 18:36:51 INFO - moztest == 0.7 18:36:51 INFO - mozversion == 1.4 18:36:51 INFO - psutil == 3.1.1 18:36:51 INFO - requests == 1.2.3 18:36:51 INFO - wsgiref == 0.1.2 18:36:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:51 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 0x27bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7b3b0>, '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 0x2a7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', '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 18:36:51 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 18:36:51 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 18:36:51 INFO - Using env: {'DISPLAY': ':0', 18:36:51 INFO - 'HOME': '/home/cltbld', 18:36:51 INFO - 'LANG': 'en_US.UTF-8', 18:36:51 INFO - 'LANGUAGE': 'en_US:en', 18:36:51 INFO - 'LOGNAME': 'cltbld', 18:36:51 INFO - 'MAIL': '/var/mail/cltbld', 18:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:51 INFO - 'MOZ_NO_REMOTE': '1', 18:36:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:51 INFO - 'NO_EM_RESTART': '1', 18:36:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:51 INFO - 'PWD': '/builds/slave/test', 18:36:51 INFO - 'SHELL': '/bin/bash', 18:36:51 INFO - 'SHLVL': '1', 18:36:51 INFO - 'TERM': 'linux', 18:36:51 INFO - 'TMOUT': '86400', 18:36:51 INFO - 'USER': 'cltbld', 18:36:51 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', 18:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:51 INFO - '_': '/tools/buildbot/bin/python'} 18:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:52 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)) 18:36:52 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)) 18:36:52 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)) 18:36:52 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)) 18:36:52 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)) 18:36:52 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)) 18:36:52 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)) 18:36:52 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)) 18:36:52 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)) 18:36:52 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)) 18:36:52 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)) 18:36:52 INFO - Cleaning up... 18:36:52 INFO - Return code: 0 18:36:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:36: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7b3b0>, '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 0x2a7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', '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 18:36: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', 'jsonschema'] in /builds/slave/test/build 18:36: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 jsonschema 18:36:52 INFO - Using env: {'DISPLAY': ':0', 18:36:52 INFO - 'HOME': '/home/cltbld', 18:36:52 INFO - 'LANG': 'en_US.UTF-8', 18:36:52 INFO - 'LANGUAGE': 'en_US:en', 18:36:52 INFO - 'LOGNAME': 'cltbld', 18:36:52 INFO - 'MAIL': '/var/mail/cltbld', 18:36:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:52 INFO - 'MOZ_NO_REMOTE': '1', 18:36:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:52 INFO - 'NO_EM_RESTART': '1', 18:36:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:52 INFO - 'PWD': '/builds/slave/test', 18:36:52 INFO - 'SHELL': '/bin/bash', 18:36:52 INFO - 'SHLVL': '1', 18:36:52 INFO - 'TERM': 'linux', 18:36:52 INFO - 'TMOUT': '86400', 18:36:52 INFO - 'USER': 'cltbld', 18:36:52 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', 18:36:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:52 INFO - '_': '/tools/buildbot/bin/python'} 18:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:36:52 INFO - Cleaning up... 18:36:52 INFO - Return code: 0 18:36:52 INFO - Running post-action listener: _resource_record_post_action 18:36:52 INFO - Running post-action listener: _start_resource_monitoring 18:36:52 INFO - Starting resource monitoring. 18:36:52 INFO - ##### 18:36:52 INFO - ##### Finished create-virtualenv step (success) 18:36:52 INFO - ##### 18:36:52 INFO - ##### 18:36:52 INFO - ##### Running install step. 18:36:52 INFO - ##### 18:36:52 INFO - Running pre-action listener: _resource_record_pre_action 18:36:52 INFO - Running main action method: install 18:36:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:36:52 INFO - Reading from file tmpfile_stdout 18:36:52 INFO - Detecting whether we're running mozinstall >=1.0... 18:36:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:36:52 INFO - Reading from file tmpfile_stdout 18:36:52 INFO - Output received: 18:36:52 INFO - Usage: mozinstall [options] installer 18:36:52 INFO - Options: 18:36:52 INFO - -h, --help show this help message and exit 18:36:52 INFO - -d DEST, --destination=DEST 18:36:52 INFO - Directory to install application into. [default: 18:36:52 INFO - "/builds/slave/test"] 18:36:52 INFO - --app=APP Application being installed. [default: firefox] 18:36:52 INFO - mkdir: /builds/slave/test/build/application 18:36:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:37:08 INFO - Reading from file tmpfile_stdout 18:37:08 INFO - Output received: 18:37:08 INFO - /builds/slave/test/build/application/firefox/firefox 18:37:08 INFO - Running post-action listener: _resource_record_post_action 18:37:08 INFO - ##### 18:37:08 INFO - ##### Finished install step (success) 18:37:08 INFO - ##### 18:37:08 INFO - ##### 18:37:08 INFO - ##### Running run-tests step. 18:37:08 INFO - ##### 18:37:08 INFO - Running pre-action listener: _resource_record_pre_action 18:37:08 INFO - Running main action method: run_tests 18:37:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:37:08 INFO - Python 2.7.3 18:37:08 INFO - Return code: 0 18:37:08 INFO - grabbing minidump binary from tooltool 18:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:08 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 0x2a7b3b0>, '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 0x2a7c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:37:08 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 18:37:08 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 18:37:08 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 18:37:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:37:09 INFO - Return code: 0 18:37:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:37:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:37:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:37:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:37:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:37:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 18:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 18:37:09 INFO - Using env: {'DISPLAY': ':0', 18:37:09 INFO - 'HOME': '/home/cltbld', 18:37:09 INFO - 'LANG': 'en_US.UTF-8', 18:37:09 INFO - 'LANGUAGE': 'en_US:en', 18:37:09 INFO - 'LOGNAME': 'cltbld', 18:37:09 INFO - 'MAIL': '/var/mail/cltbld', 18:37:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:37:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:09 INFO - 'MOZ_NO_REMOTE': '1', 18:37:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:37:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:09 INFO - 'NO_EM_RESTART': '1', 18:37:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:09 INFO - 'PWD': '/builds/slave/test', 18:37:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:37:09 INFO - 'SHELL': '/bin/bash', 18:37:09 INFO - 'SHLVL': '1', 18:37:09 INFO - 'TERM': 'linux', 18:37:09 INFO - 'TMOUT': '86400', 18:37:09 INFO - 'USER': 'cltbld', 18:37:09 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916', 18:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:09 INFO - '_': '/tools/buildbot/bin/python'} 18:37:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 18:37:09 INFO - mozversion application_buildid: 20160705171423 18:37:09 INFO - mozversion application_changeset: bfcca87598b2ca8e31094d587b7b5cd033ef9577 18:37:09 INFO - mozversion application_display_name: Nightly 18:37:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:37:09 INFO - mozversion application_name: Firefox 18:37:09 INFO - mozversion application_remotingname: firefox 18:37:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:37:09 INFO - mozversion application_vendor: Mozilla 18:37:09 INFO - mozversion application_version: 50.0a1 18:37:09 INFO - mozversion platform_buildid: 20160705171423 18:37:09 INFO - mozversion platform_changeset: bfcca87598b2ca8e31094d587b7b5cd033ef9577 18:37:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:37:09 INFO - mozversion platform_version: 50.0a1 18:37:09 INFO - using testdate: 1467769029 18:37:09 INFO - actual date: 1467769029 18:37:09 INFO - starting webserver on 'localhost:54898' 18:37:09 INFO - SUITE-START | Running 2 tests 18:37:09 INFO - TEST-START | dromaeo_css 18:37:09 INFO - operating with platform_type : linux_ 18:37:09 INFO - Initialising browser for dromaeo_css test... 18:37:09 INFO - TEST-INFO | started process 29722 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPTVMrN/profile http://localhost:54898/getInfo.html) 18:37:13 INFO - PROCESS | 29722 | __metrics Screen width/height:1600/1200 18:37:13 INFO - PROCESS | 29722 | colorDepth:24 18:37:13 INFO - PROCESS | 29722 | Browser inner width/height: 1024/697 18:37:13 INFO - PROCESS | 29722 | __metrics 18:37:14 INFO - PROCESS | 29722 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:37:15 INFO - TEST-INFO | 29722: exit 0 18:37:15 INFO - Browser initialized. 18:37:15 INFO - Running cycle 1/1 for dromaeo_css test... 18:37:15 INFO - TEST-INFO | started process 29804 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPTVMrN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:37:27 INFO - PROCESS | 29804 | RSS: Main: 158253056 18:37:27 INFO - PROCESS | 29804 | 18:38:18 INFO - PROCESS | 29804 | 1467769098762 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] 18:40:31 INFO - PROCESS | 29804 | Cycle 1(1): loaded http://localhost:54898/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54898/tests/dromaeo/cssquery-ext.html) 18:40:33 INFO - PROCESS | 29804 | RSS: Main: 272830464 18:40:33 INFO - PROCESS | 29804 | 18:43:35 INFO - PROCESS | 29804 | Cycle 1(1): loaded http://localhost:54898/tests/dromaeo/cssquery-ext.html (next: http://localhost:54898/tests/dromaeo/cssquery-jquery.html) 18:43:36 INFO - PROCESS | 29804 | RSS: Main: 240021504 18:43:36 INFO - PROCESS | 29804 | 18:46:38 INFO - PROCESS | 29804 | Cycle 1(1): loaded http://localhost:54898/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54898/tests/dromaeo/cssquery-mootools.html) 18:46:38 INFO - PROCESS | 29804 | RSS: Main: 241913856 18:46:38 INFO - PROCESS | 29804 | 18:49:41 INFO - PROCESS | 29804 | Cycle 1(1): loaded http://localhost:54898/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54898/tests/dromaeo/cssquery-prototype.html) 18:49:41 INFO - PROCESS | 29804 | RSS: Main: 232980480 18:49:41 INFO - PROCESS | 29804 | 18:52:44 INFO - PROCESS | 29804 | Cycle 1(1): loaded http://localhost:54898/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54898/tests/dromaeo/cssquery-yui.html) 18:52:45 INFO - PROCESS | 29804 | RSS: Main: 211701760 18:52:45 INFO - PROCESS | 29804 | 18:55:48 INFO - PROCESS | 29804 | Cycle 1(1): loaded http://localhost:54898/tests/dromaeo/cssquery-yui.html (next: http://localhost:54898/tests/dromaeo/cssquery-dojo.html) 18:55:48 INFO - PROCESS | 29804 | RSS: Main: 304234496 18:55:48 INFO - PROCESS | 29804 | 18:55:48 INFO - PROCESS | 29804 | __start_tp_report 18:55:48 INFO - PROCESS | 29804 | _x_x_mozilla_page_load 18:55:48 INFO - PROCESS | 29804 | _x_x_mozilla_page_load_details 18:55:48 INFO - PROCESS | 29804 | |i|pagename|runs| 18:55:48 INFO - PROCESS | 29804 | |0;dojo.html;2618.620300056492;2633.9604905926403;2656.362473006478;2670.906518271861;2678.2634775436754;4665.533446655333;4701.67088303818;4725.673600446741;4784.497627749089;4827.927581086286;4676.4855865854815;4750.5724484796365;4758.952410475894;4768.284757286408;4769.8807529811775;4317.633001194894;4372.475302963649;4373.081652852905;4437.179121862466;4441.311596702516;4423.557644235583;4470.597646211841;4490.079533695601;4526.502084770673;4548.408706868103;4299.441072660566;4409.559044095597;4436.179306828231;4460.286354183331;4464.397306363664;4289.099289149282;4295.677824163169;4313.309870420718;4380.78096095194;4383.232934236496;4343.84796532123;4405.713628614129;4422.248217802981;4422.5356337584735;4463.575960283768;4644.164050470914;4784.64115191362;4810.855674329776;4818.277258411232;4841.128596852565;5034.144195486776;5080;5085.949140508585;5161.174212126042;5165;5118.6416950813455;5168.2764412982215;5186.740662966837;5197.402298735656;5205.687658740488;4648.233041548175;4690.085433340466;4842.878928026792;4843.418789745253;4844.878878028077;5197.688138711689;5234.659747116425;5250.4749525047955;5313.123334649817;5317.787288508416;5068.847934561969;5069.391672999263;5074.213496907985;5076.771545280471;5080.415752188472;4991.401031876126;5045.974770126126;5128.384593848762;5162.070827251056;5162.328897243335;5097.439281679012;5109.795608175631;5115.028144652504;5121.28302037715;5151.381834179922;5077.049058289258;5087.567556757643;5090.389153301628;5108.514691104339;5139.177731562932;4126.030382860025;4154.937675934863;4159.480064991876;4192.098698779777;4266.893327666771;3639.8544058237903;3659.7621154624862;3675.0077479081033;3698.223373091681;3717.9442308365396;4328.199283132631;4331.761753103547;4332;4342.392065110824;4372.781360931941;4984.2025275955675;5031.77357018935;5069.619778516626;5072.442031376546;5087.109755792721;4399.912001760011;4404.691671583022;4412.779361031936;4433.844815431445;4439.200943830142;4544.545545445429;4550.795214215368;4551.726896386227;4563.726176429424;4598.471175814757;4613.261878099488;4741.905161896674;4773;4782.354382158364;4829.492903245249;5063.265826455217;5071.822486212965;5092.185250359925;5095.77069031887;5139.460356662496;4792.640551958547;4802.159622066194;4808.507894306058;4826.613870890391;4836.177849765478;4878.292647566012;4986.102501549756;5023.497650234947;5037.672551284155;5038.193888977892;5076.36545431821;5110.61670374716;5112.488751125006;5115.590752739847;5149.0474262261605;4801.9039619208115;4926.187179115406;4944.505549445027;4950.133726597903;4950.8762280943265;4830.154722923545;4868.561829435368;4875;4882.804687812448;4898.069366820293;4800.903981920412;4813.78337974799;4857.53853383928;4864.318995340726;4867.1969125095375;4863.367762191034;4913.3121363008495;4924.335214745964;4926.261060840902;4926.58124059459;4850.272459131159;4895.143349913822;4911.582515486224;4961.900761984813;4963.329950456642 18:55:48 INFO - PROCESS | 29804 | |1;ext.html;548.8666046318295;573.635741304272;585.7452008376356;595.3243069116556;600.1298117729291;26442.471150576977;29075;29276.26809329768;29340.559891601642;29352.972645957394;29021.27446813831;40511;41046.58953410465;41203.38194927071;44737;7763.417743669219;7903.683852645916;7910.841783164333;7913.802154946144;7923.722669706562;7723.189065148163;7806.024246969129;7813;7813.882791758099;7814.140444551095;7818.100918394401;7834.882476762853;7843.254890785395;7852;7852.842943141134;7761.223877612194;7777.844443111135;7825.100113486965;7828.608569571556;7833.216678332178;7850.764477065697;7881.684732610689;7892.0529536456;7899.881501777478;7916.960415197945;38500;38859.41710874339;38955.61044389548;38969.220615587816;39113.21773564513;3109.9533506997413;3127.405792899342;3143.135637699628;3217.7264932480766;3584.2473080653094;28945.131646050653;29160;29160.70839291601;29406.85296573525;30315.696843031506;3326.2682209914024;3333.2000319923045;3334.233126380946;3337.2324365395853;3399.7960122392738;243187;245219;245843;245950.99999999822;246161;28215.294617634725;29159.854200729285;29226.707732922823;51421.74289128531;51897;32335.676643233735;32418;32455.837720811716;32470;32506.512402313983;12768.872311276955;12896.677583060311;12900.677483062998;12903.935480322538;12946.546870859474;9744.171745401578;9750.609975600897;9755.560999754885;9756.951215243878;9795.167410770164;7748.263914928073;7769.883451748228;7783.182765809508;7791.571463569559;7845.843083138306;3115.7818952673097;3137.30979184579;3139.576157218792;3151.306712523241;3187.442197615408;855.7432770168924;873.5588527793551;890.3217435134818;916.3722849847876;1037.1080870451406;27533.449331013268;27814.72185278162;28066.158015259574;28206;28295.71704282972;976.4043933200742;982.9668720306221;991.791723738023;1021.4126877045728;1031.200819364998;14728.779068313985;14789.334479948428;14944.850551494346;14986.175760333286;15049.322780474906;380304;381051;383467;384210;385283;47382;48025.75987120112;48044.519554804705;48057.5194248053;48101.27848082282;1056.693558867935;1151.2516864038441;1162.5466068233395;1195.6413076077142;1210.9091818113677;27779.305517362227;27993.720062799926;28904.47515401606;29086.7091329084;29219;40520.594794051685;40605.18789624132;40966.385504216865;41062.1787564253;41117.79441102775;301854;303892;303997;304111;305021;21787.673184902556;24027.51944961126;24187.274381768577;24924.750752492244;25044.248672539852;40983;41046.794766027175;41571.37642935419;41798.58201418068;42032.78983605062;35091.47362789611;35194.47208291929;35648.10879728028;35730.464043039894;36240;35544.11139721424;35616.46575301424;35646.64353356537;35781.28437431289;35896.820515897256;34580.481292780125;34929.30141397208;35136.64863351334;35407.64592354043;35600.28799424049;31099.378012440076;31866;31961.68038319587;32027.199320017186;32271.67728322687;23779.881100594386;25032.874835625706;25251;25322.873385632953;25350.644192338907 18:55:48 INFO - PROCESS | 29804 | |2;jquery.html;373.2926105030966;381.2051871847198;383.40763520361037;383.42677696843225;394.27256711367534;16350.264238109285;17133.485995420127;17232.56918577036;18056.27774889006;18072.54818629535;11062.1703372247;11162.441877906112;11184.38485883276;11284.379359135244;11331.150163737731;4424.734515929038;4725.125851717443;5364.758585863626;5394.056040192951;5397.62216644835;5201.115810312256;5238.895222095556;5271.762770675329;5279.551238144743;5285.6300058995885;4645.558671926162;4770.809167633291;4774.68964517307;4789.544993225655;5225.2162175673575;4789.760511974405;4796.232602783538;4824.083424149436;4841.8547443576745;4846.75766211688;4514.525974772667;4722.480527142012;4763.380760501147;4765.475797662254;4766.833160839388;13051.086423950232;13053.282069486175;15977.121258330788;21194.682079768816;21608.243711470022;1925.0374812593702;1962.9116689748996;2199;2360.962753691173;2438.085717855804;9484.573194206278;10006.49967501622;10088.596456141746;11784.646460606196;13803.102798318077;1746.362577659158;1774.7160454327245;1778.0665150795805;1807.204829874851;1912.0917564157053;71300.64349678218;73351.63324183345;74278.25721742821;74633.25366746317;75777.24222757758;10259.948700256451;10333.295006323873;10468.267221294589;10485.9475702621;10771.999999999844;14258.287085645676;14385;14396.352164152639;14408.42366305357;14409.567712968628;7001.824954376181;7171.247019063027;7173.246809085075;7186.748463803847;7237.819054523679;6576.309487503839;6613.5370524062855;6732.629705366154;6739.831504212434;6753.763618273337;4429.889252768707;4432.357308190294;4493.168763778712;4494.865154045384;4524.117797029548;1807.2590238002356;1817.13685999152;1821.6356728654057;1844.6587381334496;1882.4729075858781;868.0538213347481;878.3412440669498;888.4136469929815;907.3240435875315;927.174814415171;17297.221625026905;17740;17787;17984.640307194044;18068.277268909358;872.8734333521604;888.4624801994701;899.1458114790975;927.1933417926313;947.5735918836551;9723;10010.899891000996;10234.795304094025;10246.846297305547;10261.9486902565;368765;372181;373975;375317;376544;52840.471595284325;69424;71147.64426177836;76659;76962.61518692371;887.1749273176008;891.2157301574573;910.226307638502;910.6903652758094;925.2967744514083;12484;16524.75212871784;16630.251638676047;16762.826602138222;17023.829761702225;37940.62059379481;38442.231155376176;38585.80707096447;39432.40851387174;39844.80077599594;50341;50677.74661126671;51565.742171288905;51768.48231517636;52082;7625.847483050419;7660;7782.649780759766;7840.451168418156;7989.2010798921965;8525;10877.945610271898;11651.126165537788;11818.645440636794;12072.275663460221;10604.098651614391;10690.679279621623;10818.296810707594;10967.670969870916;11127.888721112686;10195.337303075275;10386.740331491774;10601.893981060399;10666.253362264877;10792.136629069813;10429.374237545771;10447.843282350923;10617;10918.890811092106;10921.126309895348;7346.96326518364;7383.187849336676;7472.29013243741;7517;7667.769966901002;10288.794224115327;10550.841737373792;10604;10663.520141593803;10836.512356943798 18:55:48 INFO - PROCESS | 29804 | |3;mootools.html;1405.606430199544;1431.062653961655;1554.1374537131887;1715.4339068107527;1755.8244175582442;7663.961680191598;8126.512409255449;8175.877361839577;8184.959075204623;8196.508209507434;6877.41541968932;7084.64576771162;7630.923690763091;7849.058113026431;8040.075391329999;2210.613142700029;2243.3270018994317;2245.236619549353;2245.977540224593;2251.1783199132365;1912.9426117216435;2190.233418303597;2198.714167158267;2226.6437370020717;2240.2271216430304;2156.568686262746;2235.3740952533235;2237.8209743220505;2247.067467001201;2248.9662655060106;2218.267971569378;2236.642137258039;2243.046705150313;2249.3926639807323;2259.819214462839;2230.5538892221457;2248.9437764055865;2255.548890221946;2255.6278214094655;2255.842091053627;8092.473989190684;8100.068492123422;8115.472494287852;8122.959385203095;8139.633716482773;4564.178447879381;4658.790354434059;4713.882152946204;4781.521847815191;4819.397575303087;2856.571514272876;2940.323725543116;2975.9702402976127;2984.9701502985126;3000.699930007004;890.3100097424548;896.3859756067114;910.1444642036531;916.4547094478775;921.2491819167424;10930.344179349264;10936.617218397318;10940.343579385182;10953.068989135852;10975.121990240761;4106.548279689232;4234.830606775756;4314.913701725948;4321.870343889688;4389.648828093745;9185;9307.953460232655;9322.906770932339;9324.906750932403;9351.906480935104;4809.062232864558;4812.5668689817385;4812.590929770939;4817.301491283744;4821.686590371614;4321.049369138785;4388.451443569554;4388.5611438856495;4397.362382454527;4432.091421258634;3726.5900750917376;3741.1395379062424;3773;3801.8099095045136;3851.5378154621626;1067.727729429006;1162.581470670558;1186.6143503361443;1190.7023244188954;1202.9819552706717;669.3427094435085;795.9084705258854;796.8246985663145;820.2371794231422;834.8163404051099;5063.974680126576;5115.335006449138;5152.330197074431;5156.92264616031;5223.843284701465;792.7066985215507;805.3637626275294;809.1706001348665;832.8709050097124;853.8591132463074;3353.0680024498247;3445.793252404864;3453.153977275533;3493.3537295600395;3537.858485660596;9412.105849944244;10014.048665376778;10022.949885250528;10033.046860548237;10035.548400322003;8759.518226497605;9787.265955053399;9856.704298871045;9867;9872.901270987342;7167.677454514559;7479.700811967569;7487.925120748723;7671.808204794813;7677.769666910001;5283.1546952487415;5449.863753406196;5485.396606373216;5539.058360078877;5601.467860553241;9026.277897768032;9094.772630684285;9154.496502692416;9427.952860235655;9463.763405914908;9307;9361.906380936103;9422.387544809566;9475.715728528156;9512.191463725385;6467.5472716911245;6547;6579;6599.571027883172;6601.9009714853355;7412.6664300107705;7426.071741032371;7427.368673662583;7428.182899880901;7428.851422971402;5273.841784746463;6715.160604924385;6839.89740153908;6915.135608048994;7021.122359705037;6823.55646882951;6926.168859736864;6936.861262774817;7005.649717514206;7128.964355178191;6038.7282572283475;6052.334243233153;6098.786542470993;6103.389661033861;6104.236970378703;4453.0871171409135;4466.977665111654;4502.3921770561865;4564.132814765184;4629.953700463087;4502.529510961188;4528.320751887111;4539.954600453953;4541.772911354486;4544.954550454453 18:55:48 INFO - PROCESS | 29804 | |4;prototype.html;300.2164351043776;303.89819410497483;305.26279036127755;309.44609149622175;309.67948173640275;4821.30091136785;4870.9269360959615;4877.365942427479;4879.902401951958;4887.287555406748;4754.405699287589;4762.7856746446405;4811.615070794337;4822.180229361008;4823.662343635947;3604.5674519057748;3746.232022435408;3747.306748251582;3807.714421418391;3853.9401664542197;3793.412021136728;3844.788536630484;3851.268259030789;3855.2867719471988;3874.438206460062;3804.9239015219678;3819.3698039823535;3833.884983450487;3835;3848.5766565677754;3637.108908317466;3659.432787917877;3819.656230939229;3830.693544516432;3847.1343947611845;3419.9316013679836;3421.760476766627;3424.366492198926;3425.469052296898;3431.811250381228;4752.120857641347;4752.500987396343;4765.428148622187;4769.666123371366;4771.737554434505;4233.23801715691;4371.191329604034;4376.737395756265;4385.7149285296355;4406.6694997874965;5003.974185292006;5014.674046186986;5028.396592408934;5038;5054.620903432257;3715.5912849586525;3717.1822199116423;3747.2880152770886;3748.137928276484;3748.175401411685;5162.406323272834;5274.182501712241;5327.706976116312;5339.745159887423;5357.491038351351;3808.6762625176616;3821.1784466339313;3840.9039774005873;3845.423186522044;3845.653891149754;3902.35611124167;3902.687784977195;3904.1020565269578;3906.9451248163405;3909.4331321958725;4388.210122177975;4389.626881715026;4392.2752745797225;4393.011572396185;4397.6042156206095;4962.379702537183;5011.373578302712;5017.8243761468175;5018.322526458955;5026.547610715053;1315.5592876386327;1326.8872145867517;1333.533263357817;1337.4650139943906;1342.0940864916142;876.4171825735941;882.9426087304305;906.3338446242007;907.4736652741394;911.5396724654007;4390.078083602415;4402.8679139625865;4421.602055814992;4429;4435.911281774346;5052.141136006888;5077.669951453144;5077.974610126926;5077.974610126999;5101.795928162906;4297.763623000765;4406.744077937772;4414.558544145624;4431.62331201845;4432.556744325541;2694.528457519926;2708.8374697517816;2725.5639097744265;2733.658292713411;2737.712540183292;4847.515248475124;4848.369711937426;4855.36880205571;4877.853664390074;4884.65807393479;5007.524285192901;5194.597458686209;5200.81797137106;5222.21666749983;5225.320708307896;4794.137055330074;4828.348172996671;4833.927491087637;4838.685485443435;4849.4908034656555;5109.718965456936;5116.28372027909;5116.590672746097;5134.921666450087;5140.12617854973;4808.687435316693;5010.098182327216;5055.266986287042;5057.69653820772;5079.87300317495;4836.202026665561;4847.006363695505;4855.830045948375;4868.537488938545;4888.877778055577;3711.6473934976134;3714.442833574877;3743.596151443209;3747.4004159334377;3750.9062273443383;5045.268436076822;5100;5168.689878607295;5177.689338639693;5183.196604526304;4918.311436398835;4947.529984651453;4975.825846095369;4976.576990955809;5023.899522009612;4849.95150048495;4930.767308172957;4931.235658472942;4935.915053091818;4936.456989731055;4700.929486057644;4819.397575303087;4838.491958344464;4844.32179494864;4849.393825771778;4776.164171269945;4869.464358920587;4974.527419895249;5078.21287700407;5088.135017047185;4941.604671626334;4979.875503112451;4983.925241121314;4988.975055124701;5000.874978125576 18:55:48 INFO - PROCESS | 29804 | |5;yui.html;586.8101753480152;599.4814485470067;639.4309064932211;726.0452504955979;729.1541811498663;11839.644810655673;12959.805602915963;13008.674783130426;13021.414036368351;13070.215787052772;12001.759964800698;12075.335856527887;12125.211861229014;12127.090468214898;12127.332996685178;614.9508039356863;630.9558330916838;636;637.5919411576594;640.4780104215073;731.7877815433518;760.8706519891604;765.3265126688507;774.6049514747491;778.5056489129387;804.798800299925;810.9797255068612;813.8657121574963;813.8697808350665;814.0231721933674;650.7429565321671;811.7767613906193;812.070179644304;812.9309008734265;813.0243707550933;581.3024370755111;597.8714576366059;598.437468779346;601.0263373335181;601.5578549765919;12256.264624122581;12279.815802762965;12288.262704237684;12303.630891073282;12305.138640295094;11731.53073877044;12269.815952760715;12303.32331721755;12535.498580056787;12732.23606583608;11236;11361.034312083482;11422.257553259094;11425.771484570261;11495.942520287344;3990.700697447703;4010.83956641734;4022.8994275143064;4055;4086.59134086589;58945;59012.70493647547;60399.094013589835;60487.69756151235;60683.69658151681;10051.949740251252;10073.59705611782;10135.087842094246;10192.388456692623;10297.227707921937;18478.630427391377;18602.627947441248;18647.90676046611;18704.625907482045;18709.43871683852;6723.159605049369;6746.089277947414;6795.660216989131;6861.142357205349;6861.558606459892;3641;3734.1038150844147;3750.5311836020496;3776.055986003499;3827.138893748884;2769.736874996872;2862.1985843963726;2866.3407416294367;2868.125221807327;2875;466.37039996005797;476.8974670445871;482.99054975102206;488.5627363509639;506.25327641728893;521.5957632834584;537.7230726176023;569.0809342911251;579.0734824281117;583.3379114704841;7347;7359.337659610661;7902.762917112495;8082.717104901418;8303.894848555774;481.6917173009277;534.3561008984242;545.7107732901569;551.7213807027425;553.7065355361665;3719.5536535615356;3783.2244389900006;3809.5999920008553;3866.516685414323;3885.261800257942;51668;52177;52317.47682523126;52374;52391.21413178805;83375;86637;86974.56512717396;87102;87563.56218218867;75123.24876751272;75140.62429687816;75176.24823751692;75329.6233518829;77785;7211.891821622575;7344.706211751683;7435.8884616731875;7487.700491980478;7518.774436766906;30135.849320753256;30166;30741;34845.47731784076;43203.35194972141;61623.383766161765;63862.3613763875;64424;65840;66177.66911165413;10733.785324293627;10802;11402.027027027092;11779.764404711686;11886.465109069939;13435.664108397368;13465.259410732504;13467.124636898961;13559;14273.57179284623;11939.164258501822;12539.623811285675;12544.749105017667;12701.682457938256;12870.485180593047;12310.138290319592;12526.749465010467;12858.074374940832;12868.485260589847;12998.220106793622;11775.646730598095;12369.876301237233;12589.496420143458;12856.035797315426;12868.871311287141;10338.948305258426;10517.842232366675;10748.838767418338;10795.514201861091;11096.223264371416;9025.864612030693;9365.39124956903;9675.322727409013;9692.612295508101;10028.197744180303 18:55:48 INFO - PROCESS | 29804 | __end_tp_report 18:55:48 INFO - PROCESS | 29804 | __start_cc_report 18:55:48 INFO - PROCESS | 29804 | _x_x_mozilla_cycle_collect,1085 18:55:48 INFO - PROCESS | 29804 | __end_cc_report 18:55:48 INFO - PROCESS | 29804 | __startTimestamp1467770148294__endTimestamp 18:55:48 INFO - PROCESS | 29804 | 18:55:48 INFO - PROCESS | 29804 | ------- Summary: start ------- 18:55:48 INFO - PROCESS | 29804 | Number of tests: 6 18:55:48 INFO - PROCESS | 29804 | 18:55:48 INFO - PROCESS | 29804 | [#0] dojo.html Cycles:165 Average:4700.17 Median:4829.49 stddev:503.97 (10.4%) stddev-sans-first:478.33 18:55:48 INFO - PROCESS | 29804 | Values: 2618.6 2634.0 2656.4 2670.9 2678.3 4665.5 4701.7 4725.7 4784.5 4827.9 4676.5 4750.6 4759.0 4768.3 4769.9 4317.6 4372.5 4373.1 4437.2 4441.3 4423.6 4470.6 4490.1 4526.5 4548.4 4299.4 4409.6 4436.2 4460.3 4464.4 4289.1 4295.7 4313.3 4380.8 4383.2 4343.8 4405.7 4422.2 4422.5 4463.6 4644.2 4784.6 4810.9 4818.3 4841.1 5034.1 5080.0 5085.9 5161.2 5165.0 5118.6 5168.3 5186.7 5197.4 5205.7 4648.2 4690.1 4842.9 4843.4 4844.9 5197.7 5234.7 5250.5 5313.1 5317.8 5068.8 5069.4 5074.2 5076.8 5080.4 4991.4 5046.0 5128.4 5162.1 5162.3 5097.4 5109.8 5115.0 5121.3 5151.4 5077.0 5087.6 5090.4 5108.5 5139.2 4126.0 4154.9 4159.5 4192.1 4266.9 3639.9 3659.8 3675.0 3698.2 3717.9 4328.2 4331.8 4332.0 4342.4 4372.8 4984.2 5031.8 5069.6 5072.4 5087.1 4399.9 4404.7 4412.8 4433.8 4439.2 4544.5 4550.8 4551.7 4563.7 4598.5 4613.3 4741.9 4773.0 4782.4 4829.5 5063.3 5071.8 5092.2 5095.8 5139.5 4792.6 4802.2 4808.5 4826.6 4836.2 4878.3 4986.1 5023.5 5037.7 5038.2 5076.4 5110.6 5112.5 5115.6 5149.0 4801.9 4926.2 4944.5 4950.1 4950.9 4830.2 4868.6 4875.0 4882.8 4898.1 4800.9 4813.8 4857.5 4864.3 4867.2 4863.4 4913.3 4924.3 4926.3 4926.6 4850.3 4895.1 4911.6 4961.9 4963.3 18:55:48 INFO - PROCESS | 29804 | 18:55:48 INFO - PROCESS | 29804 | [#1] ext.html Cycles:180 Average:44738.09 Median:35301.06 stddev:83320.02 (236.0%) stddev-sans-first:83487.69 18:55:48 INFO - PROCESS | 29804 | Values: 548.9 573.6 585.7 595.3 600.1 26442.5 29075.0 29276.3 29340.6 29353.0 29021.3 40511.0 41046.6 41203.4 44737.0 7763.4 7903.7 7910.8 7913.8 7923.7 7723.2 7806.0 7813.0 7813.9 7814.1 7818.1 7834.9 7843.3 7852.0 7852.8 7761.2 7777.8 7825.1 7828.6 7833.2 7850.8 7881.7 7892.1 7899.9 7917.0 38500.0 38859.4 38955.6 38969.2 39113.2 3110.0 3127.4 3143.1 3217.7 3584.2 28945.1 29160.0 29160.7 29406.9 30315.7 3326.3 3333.2 3334.2 3337.2 3399.8 243187.0 245219.0 245843.0 245951.0 246161.0 28215.3 29159.9 29226.7 51421.7 51897.0 32335.7 32418.0 32455.8 32470.0 32506.5 12768.9 12896.7 12900.7 12903.9 12946.5 9744.2 9750.6 9755.6 9757.0 9795.2 7748.3 7769.9 7783.2 7791.6 7845.8 3115.8 3137.3 3139.6 3151.3 3187.4 855.7 873.6 890.3 916.4 1037.1 27533.4 27814.7 28066.2 28206.0 28295.7 976.4 983.0 991.8 1021.4 1031.2 14728.8 14789.3 14944.9 14986.2 15049.3 380304.0 381051.0 383467.0 384210.0 385283.0 47382.0 48025.8 48044.5 48057.5 48101.3 1056.7 1151.3 1162.5 1195.6 1210.9 27779.3 27993.7 28904.5 29086.7 29219.0 40520.6 40605.2 40966.4 41062.2 41117.8 301854.0 303892.0 303997.0 304111.0 305021.0 21787.7 24027.5 24187.3 24924.8 25044.2 40983.0 41046.8 41571.4 41798.6 42032.8 35091.5 35194.5 35648.1 35730.5 36240.0 35544.1 35616.5 35646.6 35781.3 35896.8 34580.5 34929.3 35136.6 35407.6 35600.3 31099.4 31866.0 31961.7 32027.2 32271.7 23779.9 25032.9 25251.0 25322.9 25350.6 18:55:48 INFO - PROCESS | 29804 | 18:55:48 INFO - PROCESS | 29804 | [#2] jquery.html Cycles:180 Average:23686.56 Median:383.42 stddev:61745.48 (16104.0%) stddev-sans-first:61893.88 18:55:48 INFO - PROCESS | 29804 | Values: 373.3 381.2 383.4 383.4 394.3 16350.3 17133.5 17232.6 18056.3 18072.5 11062.2 11162.4 11184.4 11284.4 11331.2 4424.7 4725.1 5364.8 5394.1 5397.6 5201.1 5238.9 5271.8 5279.6 5285.6 4645.6 4770.8 4774.7 4789.5 5225.2 4789.8 4796.2 4824.1 4841.9 4846.8 4514.5 4722.5 4763.4 4765.5 4766.8 13051.1 13053.3 15977.1 21194.7 21608.2 1925.0 1962.9 2199.0 2361.0 2438.1 9484.6 10006.5 10088.6 11784.6 13803.1 1746.4 1774.7 1778.1 1807.2 1912.1 71300.6 73351.6 74278.3 74633.3 75777.2 10259.9 10333.3 10468.3 10485.9 10772.0 14258.3 14385.0 14396.4 14408.4 14409.6 7001.8 7171.2 7173.2 7186.7 7237.8 6576.3 6613.5 6732.6 6739.8 6753.8 4429.9 4432.4 4493.2 4494.9 4524.1 1807.3 1817.1 1821.6 1844.7 1882.5 868.1 878.3 888.4 907.3 927.2 17297.2 17740.0 17787.0 17984.6 18068.3 872.9 888.5 899.1 927.2 947.6 9723.0 10010.9 10234.8 10246.8 10261.9 368765.0 372181.0 373975.0 375317.0 376544.0 52840.5 69424.0 71147.6 76659.0 76962.6 887.2 891.2 910.2 910.7 925.3 12484.0 16524.8 16630.3 16762.8 17023.8 37940.6 38442.2 38585.8 39432.4 39844.8 50341.0 50677.7 51565.7 51768.5 52082.0 7625.8 7660.0 7782.6 7840.5 7989.2 8525.0 10877.9 11651.1 11818.6 12072.3 10604.1 10690.7 10818.3 10967.7 11127.9 10195.3 10386.7 10601.9 10666.3 10792.1 10429.4 10447.8 10617.0 10918.9 10921.1 7347.0 7383.2 7472.3 7517.0 7667.8 10288.8 10550.8 10604.0 10663.5 10836.5 18:55:48 INFO - PROCESS | 29804 | 18:55:48 INFO - PROCESS | 29804 | [#3] mootools.html Cycles:180 Average:5210.41 Median:5133.83 stddev:2944.73 (57.4%) stddev-sans-first:2939.11 18:55:48 INFO - PROCESS | 29804 | Values: 1405.6 1431.1 1554.1 1715.4 1755.8 7664.0 8126.5 8175.9 8185.0 8196.5 6877.4 7084.6 7630.9 7849.1 8040.1 2210.6 2243.3 2245.2 2246.0 2251.2 1912.9 2190.2 2198.7 2226.6 2240.2 2156.6 2235.4 2237.8 2247.1 2249.0 2218.3 2236.6 2243.0 2249.4 2259.8 2230.6 2248.9 2255.5 2255.6 2255.8 8092.5 8100.1 8115.5 8123.0 8139.6 4564.2 4658.8 4713.9 4781.5 4819.4 2856.6 2940.3 2976.0 2985.0 3000.7 890.3 896.4 910.1 916.5 921.2 10930.3 10936.6 10940.3 10953.1 10975.1 4106.5 4234.8 4314.9 4321.9 4389.6 9185.0 9308.0 9322.9 9324.9 9351.9 4809.1 4812.6 4812.6 4817.3 4821.7 4321.0 4388.5 4388.6 4397.4 4432.1 3726.6 3741.1 3773.0 3801.8 3851.5 1067.7 1162.6 1186.6 1190.7 1203.0 669.3 795.9 796.8 820.2 834.8 5064.0 5115.3 5152.3 5156.9 5223.8 792.7 805.4 809.2 832.9 853.9 3353.1 3445.8 3453.2 3493.4 3537.9 9412.1 10014.0 10022.9 10033.0 10035.5 8759.5 9787.3 9856.7 9867.0 9872.9 7167.7 7479.7 7487.9 7671.8 7677.8 5283.2 5449.9 5485.4 5539.1 5601.5 9026.3 9094.8 9154.5 9428.0 9463.8 9307.0 9361.9 9422.4 9475.7 9512.2 6467.5 6547.0 6579.0 6599.6 6601.9 7412.7 7426.1 7427.4 7428.2 7428.9 5273.8 6715.2 6839.9 6915.1 7021.1 6823.6 6926.2 6936.9 7005.6 7129.0 6038.7 6052.3 6098.8 6103.4 6104.2 4453.1 4467.0 4502.4 4564.1 4630.0 4502.5 4528.3 4540.0 4541.8 4545.0 18:55:48 INFO - PROCESS | 29804 | 18:55:48 INFO - PROCESS | 29804 | [#4] prototype.html Cycles:180 Average:4196.41 Median:4810.15 stddev:1185.18 (24.6%) stddev-sans-first:1151.87 18:55:48 INFO - PROCESS | 29804 | Values: 300.2 303.9 305.3 309.4 309.7 4821.3 4870.9 4877.4 4879.9 4887.3 4754.4 4762.8 4811.6 4822.2 4823.7 3604.6 3746.2 3747.3 3807.7 3853.9 3793.4 3844.8 3851.3 3855.3 3874.4 3804.9 3819.4 3833.9 3835.0 3848.6 3637.1 3659.4 3819.7 3830.7 3847.1 3419.9 3421.8 3424.4 3425.5 3431.8 4752.1 4752.5 4765.4 4769.7 4771.7 4233.2 4371.2 4376.7 4385.7 4406.7 5004.0 5014.7 5028.4 5038.0 5054.6 3715.6 3717.2 3747.3 3748.1 3748.2 5162.4 5274.2 5327.7 5339.7 5357.5 3808.7 3821.2 3840.9 3845.4 3845.7 3902.4 3902.7 3904.1 3906.9 3909.4 4388.2 4389.6 4392.3 4393.0 4397.6 4962.4 5011.4 5017.8 5018.3 5026.5 1315.6 1326.9 1333.5 1337.5 1342.1 876.4 882.9 906.3 907.5 911.5 4390.1 4402.9 4421.6 4429.0 4435.9 5052.1 5077.7 5078.0 5078.0 5101.8 4297.8 4406.7 4414.6 4431.6 4432.6 2694.5 2708.8 2725.6 2733.7 2737.7 4847.5 4848.4 4855.4 4877.9 4884.7 5007.5 5194.6 5200.8 5222.2 5225.3 4794.1 4828.3 4833.9 4838.7 4849.5 5109.7 5116.3 5116.6 5134.9 5140.1 4808.7 5010.1 5055.3 5057.7 5079.9 4836.2 4847.0 4855.8 4868.5 4888.9 3711.6 3714.4 3743.6 3747.4 3750.9 5045.3 5100.0 5168.7 5177.7 5183.2 4918.3 4947.5 4975.8 4976.6 5023.9 4850.0 4930.8 4931.2 4935.9 4936.5 4700.9 4819.4 4838.5 4844.3 4849.4 4776.2 4869.5 4974.5 5078.2 5088.1 4941.6 4979.9 4983.9 4989.0 5000.9 18:55:48 INFO - PROCESS | 29804 | 18:55:48 INFO - PROCESS | 29804 | [#5] yui.html Cycles:180 Average:16310.21 Median:21834.86 stddev:22202.40 (101.7%) stddev-sans-first:22233.29 18:55:48 INFO - PROCESS | 29804 | Values: 586.8 599.5 639.4 726.0 729.2 11839.6 12959.8 13008.7 13021.4 13070.2 12001.8 12075.3 12125.2 12127.1 12127.3 615.0 631.0 636.0 637.6 640.5 731.8 760.9 765.3 774.6 778.5 804.8 811.0 813.9 813.9 814.0 650.7 811.8 812.1 812.9 813.0 581.3 597.9 598.4 601.0 601.6 12256.3 12279.8 12288.3 12303.6 12305.1 11731.5 12269.8 12303.3 12535.5 12732.2 11236.0 11361.0 11422.3 11425.8 11495.9 3990.7 4010.8 4022.9 4055.0 4086.6 58945.0 59012.7 60399.1 60487.7 60683.7 10051.9 10073.6 10135.1 10192.4 10297.2 18478.6 18602.6 18647.9 18704.6 18709.4 6723.2 6746.1 6795.7 6861.1 6861.6 3641.0 3734.1 3750.5 3776.1 3827.1 2769.7 2862.2 2866.3 2868.1 2875.0 466.4 476.9 483.0 488.6 506.3 521.6 537.7 569.1 579.1 583.3 7347.0 7359.3 7902.8 8082.7 8303.9 481.7 534.4 545.7 551.7 553.7 3719.6 3783.2 3809.6 3866.5 3885.3 51668.0 52177.0 52317.5 52374.0 52391.2 83375.0 86637.0 86974.6 87102.0 87563.6 75123.2 75140.6 75176.2 75329.6 77785.0 7211.9 7344.7 7435.9 7487.7 7518.8 30135.8 30166.0 30741.0 34845.5 43203.4 61623.4 63862.4 64424.0 65840.0 66177.7 10733.8 10802.0 11402.0 11779.8 11886.5 13435.7 13465.3 13467.1 13559.0 14273.6 11939.2 12539.6 12544.7 12701.7 12870.5 12310.1 12526.7 12858.1 12868.5 12998.2 11775.6 12369.9 12589.5 12856.0 12868.9 10338.9 10517.8 10748.8 10795.5 11096.2 9025.9 9365.4 9675.3 9692.6 10028.2 18:55:48 INFO - PROCESS | 29804 | -------- Summary: end -------- 18:55:48 INFO - PROCESS | 29804 | 18:55:49 INFO - TEST-INFO | 29804: exit 0 18:55:49 INFO - TEST-OK | dromaeo_css | took 1119875ms 18:55:49 INFO - TEST-START | kraken 18:55:49 INFO - operating with platform_type : linux_ 18:55:49 INFO - Initialising browser for kraken test... 18:55:49 INFO - TEST-INFO | started process 29992 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2hdI2w/profile http://localhost:54898/getInfo.html) 18:55:53 INFO - PROCESS | 29992 | __metrics Screen width/height:1600/1200 18:55:53 INFO - PROCESS | 29992 | colorDepth:24 18:55:53 INFO - PROCESS | 29992 | Browser inner width/height: 1024/697 18:55:53 INFO - PROCESS | 29992 | __metrics 18:55:54 INFO - PROCESS | 29992 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:55:55 INFO - TEST-INFO | 29992: exit 0 18:55:55 INFO - Browser initialized. 18:55:55 INFO - Running cycle 1/1 for kraken test... 18:55:55 INFO - TEST-INFO | started process 30075 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2hdI2w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:56:07 INFO - PROCESS | 30075 | RSS: Main: 158978048 18:56:07 INFO - PROCESS | 30075 | 18:56:10 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54898/tests/kraken/driver.html?testName=audio-beat-detection) 18:56:11 INFO - PROCESS | 30075 | RSS: Main: 216694784 18:56:11 INFO - PROCESS | 30075 | 18:56:14 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54898/tests/kraken/driver.html?testName=audio-dft) 18:56:14 INFO - PROCESS | 30075 | RSS: Main: 203194368 18:56:14 INFO - PROCESS | 30075 | 18:56:17 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54898/tests/kraken/driver.html?testName=audio-fft) 18:56:18 INFO - PROCESS | 30075 | RSS: Main: 270589952 18:56:18 INFO - PROCESS | 30075 | 18:56:20 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54898/tests/kraken/driver.html?testName=audio-oscillator) 18:56:21 INFO - PROCESS | 30075 | RSS: Main: 181399552 18:56:21 INFO - PROCESS | 30075 | 18:56:23 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54898/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:56:24 INFO - PROCESS | 30075 | RSS: Main: 185315328 18:56:24 INFO - PROCESS | 30075 | 18:56:27 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54898/tests/kraken/driver.html?testName=imaging-darkroom) 18:56:28 INFO - PROCESS | 30075 | RSS: Main: 222089216 18:56:28 INFO - PROCESS | 30075 | 18:56:31 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54898/tests/kraken/driver.html?testName=imaging-desaturate) 18:56:32 INFO - PROCESS | 30075 | RSS: Main: 217497600 18:56:32 INFO - PROCESS | 30075 | 18:56:35 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54898/tests/kraken/driver.html?testName=json-parse-financial) 18:56:35 INFO - PROCESS | 30075 | RSS: Main: 219922432 18:56:35 INFO - PROCESS | 30075 | 18:56:37 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54898/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:56:37 INFO - PROCESS | 30075 | RSS: Main: 205238272 18:56:37 INFO - PROCESS | 30075 | 18:56:39 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54898/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:56:40 INFO - PROCESS | 30075 | RSS: Main: 210927616 18:56:40 INFO - PROCESS | 30075 | 18:56:42 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54898/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:56:43 INFO - PROCESS | 30075 | RSS: Main: 215191552 18:56:43 INFO - PROCESS | 30075 | 18:56:46 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54898/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:56:46 INFO - PROCESS | 30075 | RSS: Main: 225628160 18:56:46 INFO - PROCESS | 30075 | 18:56:49 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54898/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:56:50 INFO - PROCESS | 30075 | RSS: Main: 225091584 18:56:50 INFO - PROCESS | 30075 | 18:56:51 INFO - PROCESS | 30075 | Cycle 1(1): loaded http://localhost:54898/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54898/tests/kraken/driver.html?testName=ai-astar) 18:56:52 INFO - PROCESS | 30075 | RSS: Main: 267935744 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | __start_tp_report 18:56:52 INFO - PROCESS | 30075 | _x_x_mozilla_page_load 18:56:52 INFO - PROCESS | 30075 | _x_x_mozilla_page_load_details 18:56:52 INFO - PROCESS | 30075 | |i|pagename|runs| 18:56:52 INFO - PROCESS | 30075 | |0;ai-astar;103;113;113;113;113;112;113;112;113;113 18:56:52 INFO - PROCESS | 30075 | |1;audio-beat-detection;148;125;124;182;129;149;146;136;183;125 18:56:52 INFO - PROCESS | 30075 | |2;audio-dft;162;165;160;162;161;161;161;163;161;161 18:56:52 INFO - PROCESS | 30075 | |3;audio-fft;75;77;77;111;77;78;76;76;102;77 18:56:52 INFO - PROCESS | 30075 | |4;audio-oscillator;85;85;117;85;87;86;131;85;86;128 18:56:52 INFO - PROCESS | 30075 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;116;117 18:56:52 INFO - PROCESS | 30075 | |6;imaging-darkroom;146;146;147;146;146;146;146;146;146;146 18:56:52 INFO - PROCESS | 30075 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;94;95 18:56:52 INFO - PROCESS | 30075 | |8;json-parse-financial;62;62;64;62;63;64;64;64;63;62 18:56:52 INFO - PROCESS | 30075 | |9;json-stringify-tinderbox;64;64;63;64;64;64;65;65;65;65 18:56:52 INFO - PROCESS | 30075 | |10;stanford-crypto-aes;87;82;79;81;87;85;84;79;80;79 18:56:52 INFO - PROCESS | 30075 | |11;stanford-crypto-ccm;128;128;131;129;128;131;131;132;209;130 18:56:52 INFO - PROCESS | 30075 | |12;stanford-crypto-pbkdf2;169;166;166;167;164;166;163;168;169;164 18:56:52 INFO - PROCESS | 30075 | |13;stanford-crypto-sha256-iterative;68;66;70;68;67;70;67;67;67;67 18:56:52 INFO - PROCESS | 30075 | __end_tp_report 18:56:52 INFO - PROCESS | 30075 | __start_cc_report 18:56:52 INFO - PROCESS | 30075 | _x_x_mozilla_cycle_collect,1897 18:56:52 INFO - PROCESS | 30075 | __end_cc_report 18:56:52 INFO - PROCESS | 30075 | __startTimestamp1467770212152__endTimestamp 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | ------- Summary: start ------- 18:56:52 INFO - PROCESS | 30075 | Number of tests: 14 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.12 (2.8%) stddev-sans-first:0.44 18:56:52 INFO - PROCESS | 30075 | Values: 103.0 113.0 113.0 113.0 113.0 112.0 113.0 112.0 113.0 113.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:147.00 stddev:22.15 (15.1%) stddev-sans-first:23.46 18:56:52 INFO - PROCESS | 30075 | Values: 148.0 125.0 124.0 182.0 129.0 149.0 146.0 136.0 183.0 125.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#2] audio-dft Cycles:10 Average:161.70 Median:161.50 stddev:1.42 (0.9%) stddev-sans-first:1.50 18:56:52 INFO - PROCESS | 30075 | Values: 162.0 165.0 160.0 162.0 161.0 161.0 161.0 163.0 161.0 161.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#3] audio-fft Cycles:10 Average:82.60 Median:77.00 stddev:12.80 (16.6%) stddev-sans-first:13.28 18:56:52 INFO - PROCESS | 30075 | Values: 75.0 77.0 77.0 111.0 77.0 78.0 76.0 76.0 102.0 77.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:85.00 stddev:19.53 (23.0%) stddev-sans-first:20.18 18:56:52 INFO - PROCESS | 30075 | Values: 85.0 85.0 117.0 85.0 87.0 86.0 131.0 85.0 86.0 128.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:56:52 INFO - PROCESS | 30075 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 18:56:52 INFO - PROCESS | 30075 | Values: 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:56:52 INFO - PROCESS | 30075 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.50 stddev:0.94 (1.5%) stddev-sans-first:0.93 18:56:52 INFO - PROCESS | 30075 | Values: 62.0 62.0 64.0 62.0 63.0 64.0 64.0 64.0 63.0 62.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 18:56:52 INFO - PROCESS | 30075 | Values: 64.0 64.0 63.0 64.0 64.0 64.0 65.0 65.0 65.0 65.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:3.23 (3.9%) stddev-sans-first:2.95 18:56:52 INFO - PROCESS | 30075 | Values: 87.0 82.0 79.0 81.0 87.0 85.0 84.0 79.0 80.0 79.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#11] stanford-crypto-ccm Cycles:10 Average:137.70 Median:131.00 stddev:25.10 (19.2%) stddev-sans-first:26.37 18:56:52 INFO - PROCESS | 30075 | Values: 128.0 128.0 131.0 129.0 128.0 131.0 131.0 132.0 209.0 130.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.20 Median:166.50 stddev:2.10 (1.3%) stddev-sans-first:1.96 18:56:52 INFO - PROCESS | 30075 | Values: 169.0 166.0 166.0 167.0 164.0 166.0 163.0 168.0 169.0 164.0 18:56:52 INFO - PROCESS | 30075 | 18:56:52 INFO - PROCESS | 30075 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:67.50 stddev:1.34 (2.0%) stddev-sans-first:1.41 18:56:52 INFO - PROCESS | 30075 | Values: 68.0 66.0 70.0 68.0 67.0 70.0 67.0 67.0 67.0 67.0 18:56:52 INFO - PROCESS | 30075 | -------- Summary: end -------- 18:56:52 INFO - PROCESS | 30075 | 18:56:53 INFO - TEST-INFO | 30075: exit 0 18:56:53 INFO - TEST-OK | kraken | took 64056ms 18:56:53 INFO - SUITE-END | took 1183s 18:56:53 INFO - Completed test suite (00:19:44) 18:56:53 INFO - javascript benchmark 18:56:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2618.620300056492, 2633.9604905926403, 2656.362473006478, 2670.906518271861, 2678.2634775436754, 4665.533446655333, 4701.67088303818, 4725.673600446741, 4784.497627749089, 4827.927581086286, 4676.4855865854815, 4750.5724484796365, 4758.952410475894, 4768.284757286408, 4769.8807529811775, 4317.633001194894, 4372.475302963649, 4373.081652852905, 4437.179121862466, 4441.311596702516, 4423.557644235583, 4470.597646211841, 4490.079533695601, 4526.502084770673, 4548.408706868103, 4299.441072660566, 4409.559044095597, 4436.179306828231, 4460.286354183331, 4464.397306363664, 4289.099289149282, 4295.677824163169, 4313.309870420718, 4380.78096095194, 4383.232934236496, 4343.84796532123, 4405.713628614129, 4422.248217802981, 4422.5356337584735, 4463.575960283768, 4644.164050470914, 4784.64115191362, 4810.855674329776, 4818.277258411232, 4841.128596852565, 5034.144195486776, 5080.0, 5085.949140508585, 5161.174212126042, 5165.0, 5118.6416950813455, 5168.2764412982215, 5186.740662966837, 5197.402298735656, 5205.687658740488, 4648.233041548175, 4690.085433340466, 4842.878928026792, 4843.418789745253, 4844.878878028077, 5197.688138711689, 5234.659747116425, 5250.4749525047955, 5313.123334649817, 5317.787288508416, 5068.847934561969, 5069.391672999263, 5074.213496907985, 5076.771545280471, 5080.415752188472, 4991.401031876126, 5045.974770126126, 5128.384593848762, 5162.070827251056, 5162.328897243335, 5097.439281679012, 5109.795608175631, 5115.028144652504, 5121.28302037715, 5151.381834179922, 5077.049058289258, 5087.567556757643, 5090.389153301628, 5108.514691104339, 5139.177731562932, 4126.030382860025, 4154.937675934863, 4159.480064991876, 4192.098698779777, 4266.893327666771, 3639.8544058237903, 3659.7621154624862, 3675.0077479081033, 3698.223373091681, 3717.9442308365396, 4328.199283132631, 4331.761753103547, 4332.0, 4342.392065110824, 4372.781360931941, 4984.2025275955675, 5031.77357018935, 5069.619778516626, 5072.442031376546, 5087.109755792721, 4399.912001760011, 4404.691671583022, 4412.779361031936, 4433.844815431445, 4439.200943830142, 4544.545545445429, 4550.795214215368, 4551.726896386227, 4563.726176429424, 4598.471175814757, 4613.261878099488, 4741.905161896674, 4773.0, 4782.354382158364, 4829.492903245249, 5063.265826455217, 5071.822486212965, 5092.185250359925, 5095.77069031887, 5139.460356662496, 4792.640551958547, 4802.159622066194, 4808.507894306058, 4826.613870890391, 4836.177849765478, 4878.292647566012, 4986.102501549756, 5023.497650234947, 5037.672551284155, 5038.193888977892, 5076.36545431821, 5110.61670374716, 5112.488751125006, 5115.590752739847, 5149.0474262261605, 4801.9039619208115, 4926.187179115406, 4944.505549445027, 4950.133726597903, 4950.8762280943265, 4830.154722923545, 4868.561829435368, 4875.0, 4882.804687812448, 4898.069366820293, 4800.903981920412, 4813.78337974799, 4857.53853383928, 4864.318995340726, 4867.1969125095375, 4863.367762191034, 4913.3121363008495, 4924.335214745964, 4926.261060840902, 4926.58124059459, 4850.272459131159, 4895.143349913822, 4911.582515486224, 4961.900761984813, 4963.329950456642], "value": 4667.318967121267, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.8666046318295, 573.635741304272, 585.7452008376356, 595.3243069116556, 600.1298117729291, 26442.471150576977, 29075.0, 29276.26809329768, 29340.559891601642, 29352.972645957394, 29021.27446813831, 40511.0, 41046.58953410465, 41203.38194927071, 44737.0, 7763.417743669219, 7903.683852645916, 7910.841783164333, 7913.802154946144, 7923.722669706562, 7723.189065148163, 7806.024246969129, 7813.0, 7813.882791758099, 7814.140444551095, 7818.100918394401, 7834.882476762853, 7843.254890785395, 7852.0, 7852.842943141134, 7761.223877612194, 7777.844443111135, 7825.100113486965, 7828.608569571556, 7833.216678332178, 7850.764477065697, 7881.684732610689, 7892.0529536456, 7899.881501777478, 7916.960415197945, 38500.0, 38859.41710874339, 38955.61044389548, 38969.220615587816, 39113.21773564513, 3109.9533506997413, 3127.405792899342, 3143.135637699628, 3217.7264932480766, 3584.2473080653094, 28945.131646050653, 29160.0, 29160.70839291601, 29406.85296573525, 30315.696843031506, 3326.2682209914024, 3333.2000319923045, 3334.233126380946, 3337.2324365395853, 3399.7960122392738, 243187.0, 245219.0, 245843.0, 245950.99999999822, 246161.0, 28215.294617634725, 29159.854200729285, 29226.707732922823, 51421.74289128531, 51897.0, 32335.676643233735, 32418.0, 32455.837720811716, 32470.0, 32506.512402313983, 12768.872311276955, 12896.677583060311, 12900.677483062998, 12903.935480322538, 12946.546870859474, 9744.171745401578, 9750.609975600897, 9755.560999754885, 9756.951215243878, 9795.167410770164, 7748.263914928073, 7769.883451748228, 7783.182765809508, 7791.571463569559, 7845.843083138306, 3115.7818952673097, 3137.30979184579, 3139.576157218792, 3151.306712523241, 3187.442197615408, 855.7432770168924, 873.5588527793551, 890.3217435134818, 916.3722849847876, 1037.1080870451406, 27533.449331013268, 27814.72185278162, 28066.158015259574, 28206.0, 28295.71704282972, 976.4043933200742, 982.9668720306221, 991.791723738023, 1021.4126877045728, 1031.200819364998, 14728.779068313985, 14789.334479948428, 14944.850551494346, 14986.175760333286, 15049.322780474906, 380304.0, 381051.0, 383467.0, 384210.0, 385283.0, 47382.0, 48025.75987120112, 48044.519554804705, 48057.5194248053, 48101.27848082282, 1056.693558867935, 1151.2516864038441, 1162.5466068233395, 1195.6413076077142, 1210.9091818113677, 27779.305517362227, 27993.720062799926, 28904.47515401606, 29086.7091329084, 29219.0, 40520.594794051685, 40605.18789624132, 40966.385504216865, 41062.1787564253, 41117.79441102775, 301854.0, 303892.0, 303997.0, 304111.0, 305021.0, 21787.673184902556, 24027.51944961126, 24187.274381768577, 24924.750752492244, 25044.248672539852, 40983.0, 41046.794766027175, 41571.37642935419, 41798.58201418068, 42032.78983605062, 35091.47362789611, 35194.47208291929, 35648.10879728028, 35730.464043039894, 36240.0, 35544.11139721424, 35616.46575301424, 35646.64353356537, 35781.28437431289, 35896.820515897256, 34580.481292780125, 34929.30141397208, 35136.64863351334, 35407.64592354043, 35600.28799424049, 31099.378012440076, 31866.0, 31961.68038319587, 32027.199320017186, 32271.67728322687, 23779.881100594386, 25032.874835625706, 25251.0, 25322.873385632953, 25350.644192338907], "value": 16009.792035742039, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.2926105030966, 381.2051871847198, 383.40763520361037, 383.42677696843225, 394.27256711367534, 16350.264238109285, 17133.485995420127, 17232.56918577036, 18056.27774889006, 18072.54818629535, 11062.1703372247, 11162.441877906112, 11184.38485883276, 11284.379359135244, 11331.150163737731, 4424.734515929038, 4725.125851717443, 5364.758585863626, 5394.056040192951, 5397.62216644835, 5201.115810312256, 5238.895222095556, 5271.762770675329, 5279.551238144743, 5285.6300058995885, 4645.558671926162, 4770.809167633291, 4774.68964517307, 4789.544993225655, 5225.2162175673575, 4789.760511974405, 4796.232602783538, 4824.083424149436, 4841.8547443576745, 4846.75766211688, 4514.525974772667, 4722.480527142012, 4763.380760501147, 4765.475797662254, 4766.833160839388, 13051.086423950232, 13053.282069486175, 15977.121258330788, 21194.682079768816, 21608.243711470022, 1925.0374812593702, 1962.9116689748996, 2199.0, 2360.962753691173, 2438.085717855804, 9484.573194206278, 10006.49967501622, 10088.596456141746, 11784.646460606196, 13803.102798318077, 1746.362577659158, 1774.7160454327245, 1778.0665150795805, 1807.204829874851, 1912.0917564157053, 71300.64349678218, 73351.63324183345, 74278.25721742821, 74633.25366746317, 75777.24222757758, 10259.948700256451, 10333.295006323873, 10468.267221294589, 10485.9475702621, 10771.999999999844, 14258.287085645676, 14385.0, 14396.352164152639, 14408.42366305357, 14409.567712968628, 7001.824954376181, 7171.247019063027, 7173.246809085075, 7186.748463803847, 7237.819054523679, 6576.309487503839, 6613.5370524062855, 6732.629705366154, 6739.831504212434, 6753.763618273337, 4429.889252768707, 4432.357308190294, 4493.168763778712, 4494.865154045384, 4524.117797029548, 1807.2590238002356, 1817.13685999152, 1821.6356728654057, 1844.6587381334496, 1882.4729075858781, 868.0538213347481, 878.3412440669498, 888.4136469929815, 907.3240435875315, 927.174814415171, 17297.221625026905, 17740.0, 17787.0, 17984.640307194044, 18068.277268909358, 872.8734333521604, 888.4624801994701, 899.1458114790975, 927.1933417926313, 947.5735918836551, 9723.0, 10010.899891000996, 10234.795304094025, 10246.846297305547, 10261.9486902565, 368765.0, 372181.0, 373975.0, 375317.0, 376544.0, 52840.471595284325, 69424.0, 71147.64426177836, 76659.0, 76962.61518692371, 887.1749273176008, 891.2157301574573, 910.226307638502, 910.6903652758094, 925.2967744514083, 12484.0, 16524.75212871784, 16630.251638676047, 16762.826602138222, 17023.829761702225, 37940.62059379481, 38442.231155376176, 38585.80707096447, 39432.40851387174, 39844.80077599594, 50341.0, 50677.74661126671, 51565.742171288905, 51768.48231517636, 52082.0, 7625.847483050419, 7660.0, 7782.649780759766, 7840.451168418156, 7989.2010798921965, 8525.0, 10877.945610271898, 11651.126165537788, 11818.645440636794, 12072.275663460221, 10604.098651614391, 10690.679279621623, 10818.296810707594, 10967.670969870916, 11127.888721112686, 10195.337303075275, 10386.740331491774, 10601.893981060399, 10666.253362264877, 10792.136629069813, 10429.374237545771, 10447.843282350923, 10617.0, 10918.890811092106, 10921.126309895348, 7346.96326518364, 7383.187849336676, 7472.29013243741, 7517.0, 7667.769966901002, 10288.794224115327, 10550.841737373792, 10604.0, 10663.520141593803, 10836.512356943798], "value": 8147.166973268309, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.606430199544, 1431.062653961655, 1554.1374537131887, 1715.4339068107527, 1755.8244175582442, 7663.961680191598, 8126.512409255449, 8175.877361839577, 8184.959075204623, 8196.508209507434, 6877.41541968932, 7084.64576771162, 7630.923690763091, 7849.058113026431, 8040.075391329999, 2210.613142700029, 2243.3270018994317, 2245.236619549353, 2245.977540224593, 2251.1783199132365, 1912.9426117216435, 2190.233418303597, 2198.714167158267, 2226.6437370020717, 2240.2271216430304, 2156.568686262746, 2235.3740952533235, 2237.8209743220505, 2247.067467001201, 2248.9662655060106, 2218.267971569378, 2236.642137258039, 2243.046705150313, 2249.3926639807323, 2259.819214462839, 2230.5538892221457, 2248.9437764055865, 2255.548890221946, 2255.6278214094655, 2255.842091053627, 8092.473989190684, 8100.068492123422, 8115.472494287852, 8122.959385203095, 8139.633716482773, 4564.178447879381, 4658.790354434059, 4713.882152946204, 4781.521847815191, 4819.397575303087, 2856.571514272876, 2940.323725543116, 2975.9702402976127, 2984.9701502985126, 3000.699930007004, 890.3100097424548, 896.3859756067114, 910.1444642036531, 916.4547094478775, 921.2491819167424, 10930.344179349264, 10936.617218397318, 10940.343579385182, 10953.068989135852, 10975.121990240761, 4106.548279689232, 4234.830606775756, 4314.913701725948, 4321.870343889688, 4389.648828093745, 9185.0, 9307.953460232655, 9322.906770932339, 9324.906750932403, 9351.906480935104, 4809.062232864558, 4812.5668689817385, 4812.590929770939, 4817.301491283744, 4821.686590371614, 4321.049369138785, 4388.451443569554, 4388.5611438856495, 4397.362382454527, 4432.091421258634, 3726.5900750917376, 3741.1395379062424, 3773.0, 3801.8099095045136, 3851.5378154621626, 1067.727729429006, 1162.581470670558, 1186.6143503361443, 1190.7023244188954, 1202.9819552706717, 669.3427094435085, 795.9084705258854, 796.8246985663145, 820.2371794231422, 834.8163404051099, 5063.974680126576, 5115.335006449138, 5152.330197074431, 5156.92264616031, 5223.843284701465, 792.7066985215507, 805.3637626275294, 809.1706001348665, 832.8709050097124, 853.8591132463074, 3353.0680024498247, 3445.793252404864, 3453.153977275533, 3493.3537295600395, 3537.858485660596, 9412.105849944244, 10014.048665376778, 10022.949885250528, 10033.046860548237, 10035.548400322003, 8759.518226497605, 9787.265955053399, 9856.704298871045, 9867.0, 9872.901270987342, 7167.677454514559, 7479.700811967569, 7487.925120748723, 7671.808204794813, 7677.769666910001, 5283.1546952487415, 5449.863753406196, 5485.396606373216, 5539.058360078877, 5601.467860553241, 9026.277897768032, 9094.772630684285, 9154.496502692416, 9427.952860235655, 9463.763405914908, 9307.0, 9361.906380936103, 9422.387544809566, 9475.715728528156, 9512.191463725385, 6467.5472716911245, 6547.0, 6579.0, 6599.571027883172, 6601.9009714853355, 7412.6664300107705, 7426.071741032371, 7427.368673662583, 7428.182899880901, 7428.851422971402, 5273.841784746463, 6715.160604924385, 6839.89740153908, 6915.135608048994, 7021.122359705037, 6823.55646882951, 6926.168859736864, 6936.861262774817, 7005.649717514206, 7128.964355178191, 6038.7282572283475, 6052.334243233153, 6098.786542470993, 6103.389661033861, 6104.236970378703, 4453.0871171409135, 4466.977665111654, 4502.3921770561865, 4564.132814765184, 4629.953700463087, 4502.529510961188, 4528.320751887111, 4539.954600453953, 4541.772911354486, 4544.954550454453], "value": 4179.174496271704, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.2164351043776, 303.89819410497483, 305.26279036127755, 309.44609149622175, 309.67948173640275, 4821.30091136785, 4870.9269360959615, 4877.365942427479, 4879.902401951958, 4887.287555406748, 4754.405699287589, 4762.7856746446405, 4811.615070794337, 4822.180229361008, 4823.662343635947, 3604.5674519057748, 3746.232022435408, 3747.306748251582, 3807.714421418391, 3853.9401664542197, 3793.412021136728, 3844.788536630484, 3851.268259030789, 3855.2867719471988, 3874.438206460062, 3804.9239015219678, 3819.3698039823535, 3833.884983450487, 3835.0, 3848.5766565677754, 3637.108908317466, 3659.432787917877, 3819.656230939229, 3830.693544516432, 3847.1343947611845, 3419.9316013679836, 3421.760476766627, 3424.366492198926, 3425.469052296898, 3431.811250381228, 4752.120857641347, 4752.500987396343, 4765.428148622187, 4769.666123371366, 4771.737554434505, 4233.23801715691, 4371.191329604034, 4376.737395756265, 4385.7149285296355, 4406.6694997874965, 5003.974185292006, 5014.674046186986, 5028.396592408934, 5038.0, 5054.620903432257, 3715.5912849586525, 3717.1822199116423, 3747.2880152770886, 3748.137928276484, 3748.175401411685, 5162.406323272834, 5274.182501712241, 5327.706976116312, 5339.745159887423, 5357.491038351351, 3808.6762625176616, 3821.1784466339313, 3840.9039774005873, 3845.423186522044, 3845.653891149754, 3902.35611124167, 3902.687784977195, 3904.1020565269578, 3906.9451248163405, 3909.4331321958725, 4388.210122177975, 4389.626881715026, 4392.2752745797225, 4393.011572396185, 4397.6042156206095, 4962.379702537183, 5011.373578302712, 5017.8243761468175, 5018.322526458955, 5026.547610715053, 1315.5592876386327, 1326.8872145867517, 1333.533263357817, 1337.4650139943906, 1342.0940864916142, 876.4171825735941, 882.9426087304305, 906.3338446242007, 907.4736652741394, 911.5396724654007, 4390.078083602415, 4402.8679139625865, 4421.602055814992, 4429.0, 4435.911281774346, 5052.141136006888, 5077.669951453144, 5077.974610126926, 5077.974610126999, 5101.795928162906, 4297.763623000765, 4406.744077937772, 4414.558544145624, 4431.62331201845, 4432.556744325541, 2694.528457519926, 2708.8374697517816, 2725.5639097744265, 2733.658292713411, 2737.712540183292, 4847.515248475124, 4848.369711937426, 4855.36880205571, 4877.853664390074, 4884.65807393479, 5007.524285192901, 5194.597458686209, 5200.81797137106, 5222.21666749983, 5225.320708307896, 4794.137055330074, 4828.348172996671, 4833.927491087637, 4838.685485443435, 4849.4908034656555, 5109.718965456936, 5116.28372027909, 5116.590672746097, 5134.921666450087, 5140.12617854973, 4808.687435316693, 5010.098182327216, 5055.266986287042, 5057.69653820772, 5079.87300317495, 4836.202026665561, 4847.006363695505, 4855.830045948375, 4868.537488938545, 4888.877778055577, 3711.6473934976134, 3714.442833574877, 3743.596151443209, 3747.4004159334377, 3750.9062273443383, 5045.268436076822, 5100.0, 5168.689878607295, 5177.689338639693, 5183.196604526304, 4918.311436398835, 4947.529984651453, 4975.825846095369, 4976.576990955809, 5023.899522009612, 4849.95150048495, 4930.767308172957, 4931.235658472942, 4935.915053091818, 4936.456989731055, 4700.929486057644, 4819.397575303087, 4838.491958344464, 4844.32179494864, 4849.393825771778, 4776.164171269945, 4869.464358920587, 4974.527419895249, 5078.21287700407, 5088.135017047185, 4941.604671626334, 4979.875503112451, 4983.925241121314, 4988.975055124701, 5000.874978125576], "value": 3823.360822412672, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.8101753480152, 599.4814485470067, 639.4309064932211, 726.0452504955979, 729.1541811498663, 11839.644810655673, 12959.805602915963, 13008.674783130426, 13021.414036368351, 13070.215787052772, 12001.759964800698, 12075.335856527887, 12125.211861229014, 12127.090468214898, 12127.332996685178, 614.9508039356863, 630.9558330916838, 636.0, 637.5919411576594, 640.4780104215073, 731.7877815433518, 760.8706519891604, 765.3265126688507, 774.6049514747491, 778.5056489129387, 804.798800299925, 810.9797255068612, 813.8657121574963, 813.8697808350665, 814.0231721933674, 650.7429565321671, 811.7767613906193, 812.070179644304, 812.9309008734265, 813.0243707550933, 581.3024370755111, 597.8714576366059, 598.437468779346, 601.0263373335181, 601.5578549765919, 12256.264624122581, 12279.815802762965, 12288.262704237684, 12303.630891073282, 12305.138640295094, 11731.53073877044, 12269.815952760715, 12303.32331721755, 12535.498580056787, 12732.23606583608, 11236.0, 11361.034312083482, 11422.257553259094, 11425.771484570261, 11495.942520287344, 3990.700697447703, 4010.83956641734, 4022.8994275143064, 4055.0, 4086.59134086589, 58945.0, 59012.70493647547, 60399.094013589835, 60487.69756151235, 60683.69658151681, 10051.949740251252, 10073.59705611782, 10135.087842094246, 10192.388456692623, 10297.227707921937, 18478.630427391377, 18602.627947441248, 18647.90676046611, 18704.625907482045, 18709.43871683852, 6723.159605049369, 6746.089277947414, 6795.660216989131, 6861.142357205349, 6861.558606459892, 3641.0, 3734.1038150844147, 3750.5311836020496, 3776.055986003499, 3827.138893748884, 2769.736874996872, 2862.1985843963726, 2866.3407416294367, 2868.125221807327, 2875.0, 466.37039996005797, 476.8974670445871, 482.99054975102206, 488.5627363509639, 506.25327641728893, 521.5957632834584, 537.7230726176023, 569.0809342911251, 579.0734824281117, 583.3379114704841, 7347.0, 7359.337659610661, 7902.762917112495, 8082.717104901418, 8303.894848555774, 481.6917173009277, 534.3561008984242, 545.7107732901569, 551.7213807027425, 553.7065355361665, 3719.5536535615356, 3783.2244389900006, 3809.5999920008553, 3866.516685414323, 3885.261800257942, 51668.0, 52177.0, 52317.47682523126, 52374.0, 52391.21413178805, 83375.0, 86637.0, 86974.56512717396, 87102.0, 87563.56218218867, 75123.24876751272, 75140.62429687816, 75176.24823751692, 75329.6233518829, 77785.0, 7211.891821622575, 7344.706211751683, 7435.8884616731875, 7487.700491980478, 7518.774436766906, 30135.849320753256, 30166.0, 30741.0, 34845.47731784076, 43203.35194972141, 61623.383766161765, 63862.3613763875, 64424.0, 65840.0, 66177.66911165413, 10733.785324293627, 10802.0, 11402.027027027092, 11779.764404711686, 11886.465109069939, 13435.664108397368, 13465.259410732504, 13467.124636898961, 13559.0, 14273.57179284623, 11939.164258501822, 12539.623811285675, 12544.749105017667, 12701.682457938256, 12870.485180593047, 12310.138290319592, 12526.749465010467, 12858.074374940832, 12868.485260589847, 12998.220106793622, 11775.646730598095, 12369.876301237233, 12589.496420143458, 12856.035797315426, 12868.871311287141, 10338.948305258426, 10517.842232366675, 10748.838767418338, 10795.514201861091, 11096.223264371416, 9025.864612030693, 9365.39124956903, 9675.322727409013, 9692.612295508101, 10028.197744180303], "value": 6348.480504260436, "unit": "score"}], "name": "dromaeo_css", "value": 6287.119513123823, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 112.0, 113.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 125.0, 124.0, 182.0, 129.0, 149.0, 146.0, 136.0, 183.0, 125.0], "value": 144.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 160.0, 162.0, 161.0, 161.0, 161.0, 163.0, 161.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 111.0, 77.0, 78.0, 76.0, 76.0, 102.0, 77.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 117.0, 85.0, 87.0, 86.0, 131.0, 85.0, 86.0, 128.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 64.0, 62.0, 63.0, 64.0, 64.0, 64.0, 63.0, 62.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 64.0, 64.0, 64.0, 65.0, 65.0, 65.0, 65.0], "value": 64.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 79.0, 81.0, 87.0, 85.0, 84.0, 79.0, 80.0, 79.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 131.0, 129.0, 128.0, 131.0, 131.0, 132.0, 209.0, 130.0], "value": 137.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 166.0, 167.0, 164.0, 166.0, 163.0, 168.0, 169.0, 164.0], "value": 166.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 70.0, 68.0, 67.0, 70.0, 67.0, 67.0, 67.0, 67.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1537.2, "alertThreshold": 2.0}]} 18:56:53 INFO - Return code: 0 18:56:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:56:53 INFO - # TBPL SUCCESS # 18:56:53 INFO - Running post-action listener: _resource_record_post_action 18:56:53 INFO - ##### 18:56:53 INFO - ##### Finished run-tests step (success) 18:56:53 INFO - ##### 18:56:53 INFO - Running post-run listener: _resource_record_post_run 18:56:53 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 545275904; Read time: 12; Write time: 555384 18:56:53 INFO - TinderboxPrint: CPU usage
13.0% 18:56:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 18:56:53 INFO - TinderboxPrint: I/O write bytes / time
545,275,904 / 555,384 18:56:53 INFO - TinderboxPrint: CPU idle
8,340.0 (87.0%) 18:56:53 INFO - TinderboxPrint: CPU user
1,201.9 (12.5%) 18:56:53 INFO - TinderboxPrint: Swap in / out
0 / 0 18:56:53 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 245436416; Read time: 0; Write time: 299740 18:56:53 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 299560960; Read time: 0; Write time: 255616 18:56:54 INFO - Running post-run listener: _upload_blobber_files 18:56:54 INFO - Blob upload gear active. 18:56:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:56:54 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 18:56:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:56:54 INFO - (blobuploader) - INFO - Open directory for files ... 18:56:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:56:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:56:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:55 INFO - (blobuploader) - INFO - Done attempting. 18:56:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:56:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:56:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:58 INFO - (blobuploader) - INFO - Done attempting. 18:56:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:56:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:56:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:59 INFO - (blobuploader) - INFO - Done attempting. 18:56:59 INFO - (blobuploader) - INFO - Iteration through files over. 18:56:59 INFO - Return code: 0 18:56:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:56:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:56:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0fb02832313e0eaf4c589d38d87571d4e9ec36e03ceb2c087c9936ceff435e0f4ca0918f11a031d4a4dfd08c1905de7df88d01a3f06b9b06bb162b40a9859a13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/17f0acf0423cd621a3d0f1263ee6c8be14c907a867f5c5083dc89266a7867577079666e579132f090e71f7fd3d2c446dbb5fc02be2bac433c92f2ebf2ec0c137", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1d26d28ea58f43faef70b3a8b7f9112335c6a1af9e7b53adf8f05e003fe456b30c33f3e47b2d30c95ee75da9eb9a4a960c02f4e34af234a53128afcd49b8095c"} 18:56:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:56:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:56:59 INFO - Contents: 18:56:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0fb02832313e0eaf4c589d38d87571d4e9ec36e03ceb2c087c9936ceff435e0f4ca0918f11a031d4a4dfd08c1905de7df88d01a3f06b9b06bb162b40a9859a13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/17f0acf0423cd621a3d0f1263ee6c8be14c907a867f5c5083dc89266a7867577079666e579132f090e71f7fd3d2c446dbb5fc02be2bac433c92f2ebf2ec0c137", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1d26d28ea58f43faef70b3a8b7f9112335c6a1af9e7b53adf8f05e003fe456b30c33f3e47b2d30c95ee75da9eb9a4a960c02f4e34af234a53128afcd49b8095c"} 18:56:59 INFO - Running post-run listener: copy_logs_to_upload_dir 18:56:59 INFO - Copying logs to upload dir... 18:56:59 INFO - mkdir: /builds/slave/test/build/upload/logs 18:56:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.646622 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-07-05 18:56:58.860001) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 18:56:58.864179) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0fb02832313e0eaf4c589d38d87571d4e9ec36e03ceb2c087c9936ceff435e0f4ca0918f11a031d4a4dfd08c1905de7df88d01a3f06b9b06bb162b40a9859a13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/17f0acf0423cd621a3d0f1263ee6c8be14c907a867f5c5083dc89266a7867577079666e579132f090e71f7fd3d2c446dbb5fc02be2bac433c92f2ebf2ec0c137", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1d26d28ea58f43faef70b3a8b7f9112335c6a1af9e7b53adf8f05e003fe456b30c33f3e47b2d30c95ee75da9eb9a4a960c02f4e34af234a53128afcd49b8095c"} build_url:https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011263 build_url: 'https://queue.taskcluster.net/v1/task/blVkHgK5T3u2Z1wnwUE_gg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0fb02832313e0eaf4c589d38d87571d4e9ec36e03ceb2c087c9936ceff435e0f4ca0918f11a031d4a4dfd08c1905de7df88d01a3f06b9b06bb162b40a9859a13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/17f0acf0423cd621a3d0f1263ee6c8be14c907a867f5c5083dc89266a7867577079666e579132f090e71f7fd3d2c446dbb5fc02be2bac433c92f2ebf2ec0c137", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1d26d28ea58f43faef70b3a8b7f9112335c6a1af9e7b53adf8f05e003fe456b30c33f3e47b2d30c95ee75da9eb9a4a960c02f4e34af234a53128afcd49b8095c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 18:56:58.889989) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:56:58.890329) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1467768677.120103-1496226916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:56:58.972448) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 18:56:58.972788) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 18:56:58.973117) ========= ========= Total master_lag: 0.15 =========