builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1470356974.73 results: success (0) buildid: 20160804143003 builduid: 98e19d50e341477abb16d71907d9e702 revision: c60886d5c003908affbe74e56a7c5b88021b4c6e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:34.727487) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:34.727908) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:34.728200) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:35.058079) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:35.058472) ========= 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=62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-04 17:29:35-- 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% 65.9M=0s 2016-08-04 17:29:35 (65.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.401796 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:35.475294) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:35.478844) ========= 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=62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020346 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:35.528287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:35.528670) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c60886d5c003908affbe74e56a7c5b88021b4c6e --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 c60886d5c003908affbe74e56a7c5b88021b4c6e --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=62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-04 17:29:35,801 truncating revision to first 12 chars 2016-08-04 17:29:35,801 Setting DEBUG logging. 2016-08-04 17:29:35,801 attempt 1/10 2016-08-04 17:29:35,801 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c60886d5c003?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-04 17:29:36,119 unpacking tar archive at: fx-team-c60886d5c003/testing/mozharness/ program finished with exit code 0 elapsedTime=0.653099 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:36.199561) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:36.199947) ========= script_repo_revision: c60886d5c003908affbe74e56a7c5b88021b4c6e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:36.200430) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:36.200707) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 17:29:36.210933) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-08-04 17:29:36.211373) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:29:36 INFO - MultiFileLogger online at 20160804 17:29:36 in /builds/slave/test 17:29:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:29:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:29:36 INFO - {'append_to_log': False, 17:29:36 INFO - 'base_work_dir': '/builds/slave/test', 17:29:36 INFO - 'blob_upload_branch': 'Fx-Team', 17:29:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:29:36 INFO - 'branch': 'Fx-Team', 17:29:36 INFO - 'buildbot_json_path': 'buildprops.json', 17:29:36 INFO - 'config_files': ('talos/linux_config.py',), 17:29:36 INFO - 'default_actions': ('clobber', 17:29:36 INFO - 'read-buildbot-config', 17:29:36 INFO - 'download-and-extract', 17:29:36 INFO - 'populate-webroot', 17:29:36 INFO - 'create-virtualenv', 17:29:36 INFO - 'install', 17:29:36 INFO - 'run-tests'), 17:29:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:29:36 INFO - 'download_minidump_stackwalk': True, 17:29:36 INFO - 'download_symbols': 'ondemand', 17:29:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:29:36 INFO - 'tooltool.py': '/tools/tooltool.py', 17:29:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:29:36 INFO - '/tools/misc-python/virtualenv.py')}, 17:29:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:29:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:29:36 INFO - 'installer_path': 'installer.exe', 17:29:36 INFO - 'log_level': 'info', 17:29:36 INFO - 'log_name': 'talos', 17:29:36 INFO - 'log_to_console': True, 17:29:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:29:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:29:36 INFO - 'opt_config_files': (), 17:29:36 INFO - 'pip_index': False, 17:29:36 INFO - 'sps_profile': False, 17:29:36 INFO - 'sps_profile_interval': 0, 17:29:36 INFO - 'suite': 'dromaeojs', 17:29:36 INFO - 'system_bits': '32', 17:29:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:29:36 INFO - 'title': 'talos-linux64-ix-018', 17:29:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:29:36 INFO - 'use_talos_json': True, 17:29:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:29:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:29:36 INFO - 'work_dir': 'build'} 17:29:36 INFO - [mozharness: 2016-08-05 00:29:36.573863Z] Running clobber step. 17:29:36 INFO - Running pre-action listener: _resource_record_pre_action 17:29:36 INFO - Running main action method: clobber 17:29:36 INFO - rmtree: /builds/slave/test/build 17:29:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:29:37 INFO - Running post-action listener: _resource_record_post_action 17:29:37 INFO - [mozharness: 2016-08-05 00:29:37.543702Z] Finished clobber step (success) 17:29:37 INFO - [mozharness: 2016-08-05 00:29:37.543802Z] Running read-buildbot-config step. 17:29:37 INFO - Running pre-action listener: _resource_record_pre_action 17:29:37 INFO - Running main action method: read_buildbot_config 17:29:37 INFO - Using buildbot properties: 17:29:37 INFO - { 17:29:37 INFO - "project": "", 17:29:37 INFO - "product": "firefox", 17:29:37 INFO - "script_repo_revision": "production", 17:29:37 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:29:37 INFO - "repository": "", 17:29:37 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 17:29:37 INFO - "buildid": "20160804143003", 17:29:37 INFO - "pgo_build": "True", 17:29:37 INFO - "basedir": "/builds/slave/test", 17:29:37 INFO - "buildnumber": 1420, 17:29:37 INFO - "slavename": "talos-linux64-ix-018", 17:29:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:29:37 INFO - "platform": "ubuntu64_hw", 17:29:37 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 17:29:37 INFO - "branch": "fx-team", 17:29:37 INFO - "repo_path": "integration/fx-team", 17:29:37 INFO - "revision": "c60886d5c003908affbe74e56a7c5b88021b4c6e", 17:29:37 INFO - "stage_platform": "linux64-pgo", 17:29:37 INFO - "builduid": "98e19d50e341477abb16d71907d9e702", 17:29:37 INFO - "slavebuilddir": "test" 17:29:37 INFO - } 17:29:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 17:29:37 INFO - Running post-action listener: _resource_record_post_action 17:29:37 INFO - [mozharness: 2016-08-05 00:29:37.546256Z] Finished read-buildbot-config step (success) 17:29:37 INFO - [mozharness: 2016-08-05 00:29:37.546345Z] Running download-and-extract step. 17:29:37 INFO - Running pre-action listener: _resource_record_pre_action 17:29:37 INFO - Running main action method: download_and_extract 17:29:37 INFO - mkdir: /builds/slave/test/build/tests 17:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:37 INFO - trying https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 17:29:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 17:29:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:29:38 INFO - Downloaded 1361 bytes. 17:29:38 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 17:29:38 INFO - Using the following test package requirements: 17:29:38 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 17:29:38 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:29:38 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:29:38 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:38 INFO - u'jsshell-linux-x86_64.zip'], 17:29:38 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:29:38 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 17:29:38 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:29:38 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:29:38 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:29:38 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:29:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:38 INFO - trying https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 17:29:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 17:29:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:29:39 INFO - Downloaded 21821025 bytes. 17:29:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:29:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:29:40 INFO - Return code: 0 17:29:40 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:40 INFO - trying https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 17:29:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 17:29:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:29:41 INFO - Downloaded 21821025 bytes. 17:29:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:29:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:29:42 INFO - Return code: 0 17:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:42 INFO - trying https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 17:29:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 17:29:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:29:44 INFO - Downloaded 14413723 bytes. 17:29:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:29:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:29:44 INFO - Return code: 0 17:29: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:44 INFO - trying https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:29:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:29:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:29:47 INFO - Downloaded 59346989 bytes. 17:29:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:29:47 INFO - mkdir: /builds/slave/test/properties 17:29:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:29:47 INFO - Writing to file /builds/slave/test/properties/build_url 17:29:47 INFO - Contents: 17:29:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:29:47 INFO - Running post-action listener: _resource_record_post_action 17:29:47 INFO - Running post-action listener: set_extra_try_arguments 17:29:47 INFO - [mozharness: 2016-08-05 00:29:47.677795Z] Finished download-and-extract step (success) 17:29:47 INFO - [mozharness: 2016-08-05 00:29:47.678027Z] Running populate-webroot step. 17:29:47 INFO - Running pre-action listener: _resource_record_pre_action 17:29:47 INFO - Running main action method: populate_webroot 17:29:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:29:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:29:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:29:47 INFO - '--tppagecycles', 17:29:47 INFO - '7'], 17:29:47 INFO - 'tests': ['tsvgm']}}, 17:29:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:29:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:29:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:29:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:29:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:47 INFO - 'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:29:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:29:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:47 INFO - 'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['damp', 'tps']}, 17:29:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:47 INFO - 'tests': ['damp', 'tps']}, 17:29:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['dromaeo_dom']}, 17:29:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:29:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['basic_compositor_video']}, 17:29:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:29:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['a11yr', 17:29:47 INFO - 'ts_paint', 17:29:47 INFO - 'tpaint', 17:29:47 INFO - 'sessionrestore', 17:29:47 INFO - 'sessionrestore_no_auto_restore', 17:29:47 INFO - 'tabpaint']}, 17:29:47 INFO - 'other-e10s': {'tests': ['a11yr', 17:29:47 INFO - 'ts_paint', 17:29:47 INFO - 'tpaint', 17:29:47 INFO - 'sessionrestore', 17:29:47 INFO - 'sessionrestore_no_auto_restore', 17:29:47 INFO - 'tabpaint']}, 17:29:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:29:47 INFO - 'ts_paint', 17:29:47 INFO - 'tpaint', 17:29:47 INFO - 'sessionrestore', 17:29:47 INFO - 'sessionrestore_no_auto_restore', 17:29:47 INFO - 'tabpaint']}, 17:29:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:29:47 INFO - 'ts_paint', 17:29:47 INFO - 'tpaint', 17:29:47 INFO - 'sessionrestore', 17:29:47 INFO - 'sessionrestore_no_auto_restore', 17:29:47 INFO - 'tabpaint']}, 17:29:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['a11yr', 17:29:47 INFO - 'ts_paint', 17:29:47 INFO - 'tpaint', 17:29:47 INFO - 'sessionrestore', 17:29:47 INFO - 'sessionrestore_no_auto_restore', 17:29:47 INFO - 'tabpaint']}, 17:29:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['a11yr', 17:29:47 INFO - 'ts_paint', 17:29:47 INFO - 'tpaint', 17:29:47 INFO - 'sessionrestore', 17:29:47 INFO - 'sessionrestore_no_auto_restore', 17:29:47 INFO - 'tabpaint']}, 17:29:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['tsvgx', 17:29:47 INFO - 'tsvgr_opacity', 17:29:47 INFO - 'tart', 17:29:47 INFO - 'tscrollx', 17:29:47 INFO - 'cart']}, 17:29:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:29:47 INFO - 'tsvgr_opacity', 17:29:47 INFO - 'tart', 17:29:47 INFO - 'tscrollx', 17:29:47 INFO - 'cart']}, 17:29:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:47 INFO - 'talos_options': ['--disable-e10s'], 17:29:47 INFO - 'tests': ['tp5o']}, 17:29:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:47 INFO - 'tests': ['tp5o']}, 17:29:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:47 INFO - 'talos_options': ['--disable-e10s', 17:29:47 INFO - '--xperf_path', 17:29:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:29:47 INFO - 'tests': ['tp5n']}, 17:29:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:47 INFO - 'talos_options': ['--xperf_path', 17:29:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:29:47 INFO - 'tests': ['tp5n']}}, 17:29:47 INFO - 'talos.zip': {'path': '', 17:29:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:29:47 INFO - Running post-action listener: _resource_record_post_action 17:29:47 INFO - [mozharness: 2016-08-05 00:29:47.693640Z] Finished populate-webroot step (success) 17:29:47 INFO - [mozharness: 2016-08-05 00:29:47.693729Z] Running create-virtualenv step. 17:29:47 INFO - Running pre-action listener: _resource_record_pre_action 17:29:47 INFO - Running main action method: create_virtualenv 17:29:47 INFO - Creating virtualenv /builds/slave/test/build/venv 17:29:47 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 17:29:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:29:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:29:47 INFO - Using real prefix '/usr' 17:29:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:29:48 INFO - Installing distribute.............................................................................................................................................................................................done. 17:29:50 INFO - Installing pip.................done. 17:29:50 INFO - Return code: 0 17:29:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1263030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1524180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, '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': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', '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 17:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:29:50 INFO - Using env: {'DISPLAY': ':0', 17:29:50 INFO - 'HOME': '/home/cltbld', 17:29:50 INFO - 'LANG': 'en_US.UTF-8', 17:29:50 INFO - 'LANGUAGE': 'en_US:en', 17:29:50 INFO - 'LOGNAME': 'cltbld', 17:29:50 INFO - 'MAIL': '/var/mail/cltbld', 17:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:50 INFO - 'MOZ_NO_REMOTE': '1', 17:29:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:50 INFO - 'NO_EM_RESTART': '1', 17:29:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:50 INFO - 'PWD': '/builds/slave/test', 17:29:50 INFO - 'SHELL': '/bin/bash', 17:29:50 INFO - 'SHLVL': '1', 17:29:50 INFO - 'TERM': 'linux', 17:29:50 INFO - 'TMOUT': '86400', 17:29:50 INFO - 'USER': 'cltbld', 17:29:50 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:50 INFO - '_': '/tools/buildbot/bin/python'} 17:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:50 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 17:29:50 INFO - Cleaning up... 17:29:50 INFO - Return code: 0 17:29:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1263030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1524180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, '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': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', '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 17:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:29:50 INFO - Using env: {'DISPLAY': ':0', 17:29:50 INFO - 'HOME': '/home/cltbld', 17:29:50 INFO - 'LANG': 'en_US.UTF-8', 17:29:50 INFO - 'LANGUAGE': 'en_US:en', 17:29:50 INFO - 'LOGNAME': 'cltbld', 17:29:50 INFO - 'MAIL': '/var/mail/cltbld', 17:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:50 INFO - 'MOZ_NO_REMOTE': '1', 17:29:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:50 INFO - 'NO_EM_RESTART': '1', 17:29:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:50 INFO - 'PWD': '/builds/slave/test', 17:29:50 INFO - 'SHELL': '/bin/bash', 17:29:50 INFO - 'SHLVL': '1', 17:29:50 INFO - 'TERM': 'linux', 17:29:50 INFO - 'TMOUT': '86400', 17:29:50 INFO - 'USER': 'cltbld', 17:29:50 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:50 INFO - '_': '/tools/buildbot/bin/python'} 17:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:50 INFO - Downloading/unpacking psutil>=3.1.1 17:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:29:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:29:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:29:52 INFO - Installing collected packages: psutil 17:29:52 INFO - Running setup.py install for psutil 17:29:52 INFO - building 'psutil._psutil_linux' extension 17:29:52 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 17:29:52 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 17:29:52 INFO - building 'psutil._psutil_posix' extension 17:29:52 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 17:29:53 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 17:29:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:29:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:29:53 INFO - Successfully installed psutil 17:29:53 INFO - Cleaning up... 17:29:53 INFO - Return code: 0 17:29:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1263030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1524180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, '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': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', '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 17:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:29:53 INFO - Using env: {'DISPLAY': ':0', 17:29:53 INFO - 'HOME': '/home/cltbld', 17:29:53 INFO - 'LANG': 'en_US.UTF-8', 17:29:53 INFO - 'LANGUAGE': 'en_US:en', 17:29:53 INFO - 'LOGNAME': 'cltbld', 17:29:53 INFO - 'MAIL': '/var/mail/cltbld', 17:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:53 INFO - 'MOZ_NO_REMOTE': '1', 17:29:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:53 INFO - 'NO_EM_RESTART': '1', 17:29:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:53 INFO - 'PWD': '/builds/slave/test', 17:29:53 INFO - 'SHELL': '/bin/bash', 17:29:53 INFO - 'SHLVL': '1', 17:29:53 INFO - 'TERM': 'linux', 17:29:53 INFO - 'TMOUT': '86400', 17:29:53 INFO - 'USER': 'cltbld', 17:29:53 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:53 INFO - '_': '/tools/buildbot/bin/python'} 17:29:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:29:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:29:55 INFO - Installing collected packages: mozsystemmonitor 17:29:55 INFO - Running setup.py install for mozsystemmonitor 17:29:55 INFO - Successfully installed mozsystemmonitor 17:29:55 INFO - Cleaning up... 17:29:55 INFO - Return code: 0 17:29:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1263030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1524180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, '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': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', '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 17:29:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:29:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:29:55 INFO - Using env: {'DISPLAY': ':0', 17:29:55 INFO - 'HOME': '/home/cltbld', 17:29:55 INFO - 'LANG': 'en_US.UTF-8', 17:29:55 INFO - 'LANGUAGE': 'en_US:en', 17:29:55 INFO - 'LOGNAME': 'cltbld', 17:29:55 INFO - 'MAIL': '/var/mail/cltbld', 17:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:55 INFO - 'MOZ_NO_REMOTE': '1', 17:29:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:55 INFO - 'NO_EM_RESTART': '1', 17:29:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:55 INFO - 'PWD': '/builds/slave/test', 17:29:55 INFO - 'SHELL': '/bin/bash', 17:29:55 INFO - 'SHLVL': '1', 17:29:55 INFO - 'TERM': 'linux', 17:29:55 INFO - 'TMOUT': '86400', 17:29:55 INFO - 'USER': 'cltbld', 17:29:55 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:55 INFO - '_': '/tools/buildbot/bin/python'} 17:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:55 INFO - Downloading/unpacking jsonschema==2.5.1 17:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:29:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:57 INFO - Downloading functools32-3.2.3-2.tar.gz 17:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:29:57 INFO - warning: no files found matching '*.txt' 17:29:57 INFO - no previously-included directories found matching 'build' 17:29:57 INFO - no previously-included directories found matching 'dist' 17:29:57 INFO - no previously-included directories found matching '.git*' 17:29:57 INFO - Installing collected packages: jsonschema, functools32 17:29:57 INFO - Running setup.py install for functools32 17:29:58 INFO - warning: no files found matching '*.txt' 17:29:58 INFO - no previously-included directories found matching 'build' 17:29:58 INFO - no previously-included directories found matching 'dist' 17:29:58 INFO - no previously-included directories found matching '.git*' 17:29:58 INFO - Successfully installed jsonschema functools32 17:29:58 INFO - Cleaning up... 17:29:58 INFO - Return code: 0 17:29:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1263030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1524180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, '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': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', '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 17:29:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:29:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:29:58 INFO - Using env: {'DISPLAY': ':0', 17:29:58 INFO - 'HOME': '/home/cltbld', 17:29:58 INFO - 'LANG': 'en_US.UTF-8', 17:29:58 INFO - 'LANGUAGE': 'en_US:en', 17:29:58 INFO - 'LOGNAME': 'cltbld', 17:29:58 INFO - 'MAIL': '/var/mail/cltbld', 17:29:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:58 INFO - 'MOZ_NO_REMOTE': '1', 17:29:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:58 INFO - 'NO_EM_RESTART': '1', 17:29:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:58 INFO - 'PWD': '/builds/slave/test', 17:29:58 INFO - 'SHELL': '/bin/bash', 17:29:58 INFO - 'SHLVL': '1', 17:29:58 INFO - 'TERM': 'linux', 17:29:58 INFO - 'TMOUT': '86400', 17:29:58 INFO - 'USER': 'cltbld', 17:29:58 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:29:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:58 INFO - '_': '/tools/buildbot/bin/python'} 17:29:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:29:58 INFO - Cleaning up... 17:29:58 INFO - Return code: 0 17:29:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1263030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1524180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, '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': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', '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 17:29:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:29:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:29:58 INFO - Using env: {'DISPLAY': ':0', 17:29:58 INFO - 'HOME': '/home/cltbld', 17:29:58 INFO - 'LANG': 'en_US.UTF-8', 17:29:58 INFO - 'LANGUAGE': 'en_US:en', 17:29:58 INFO - 'LOGNAME': 'cltbld', 17:29:58 INFO - 'MAIL': '/var/mail/cltbld', 17:29:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:58 INFO - 'MOZ_NO_REMOTE': '1', 17:29:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:58 INFO - 'NO_EM_RESTART': '1', 17:29:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:58 INFO - 'PWD': '/builds/slave/test', 17:29:58 INFO - 'SHELL': '/bin/bash', 17:29:58 INFO - 'SHLVL': '1', 17:29:58 INFO - 'TERM': 'linux', 17:29:58 INFO - 'TMOUT': '86400', 17:29:58 INFO - 'USER': 'cltbld', 17:29:58 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:29:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:58 INFO - '_': '/tools/buildbot/bin/python'} 17:29:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:58 INFO - Downloading/unpacking blobuploader==1.2.4 17:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:00 INFO - Downloading blobuploader-1.2.4.tar.gz 17:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:30:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:30:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:01 INFO - Downloading docopt-0.6.1.tar.gz 17:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:30:01 INFO - Installing collected packages: blobuploader, requests, docopt 17:30:01 INFO - Running setup.py install for blobuploader 17:30:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:30:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:30:01 INFO - Running setup.py install for requests 17:30:01 INFO - Running setup.py install for docopt 17:30:01 INFO - Successfully installed blobuploader requests docopt 17:30:01 INFO - Cleaning up... 17:30:01 INFO - Return code: 0 17:30:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:01 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 0x1263030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1524180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, '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': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', '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 17:30:01 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 17:30:01 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 17:30:01 INFO - Using env: {'DISPLAY': ':0', 17:30:01 INFO - 'HOME': '/home/cltbld', 17:30:01 INFO - 'LANG': 'en_US.UTF-8', 17:30:01 INFO - 'LANGUAGE': 'en_US:en', 17:30:01 INFO - 'LOGNAME': 'cltbld', 17:30:01 INFO - 'MAIL': '/var/mail/cltbld', 17:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:01 INFO - 'MOZ_NO_REMOTE': '1', 17:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:01 INFO - 'NO_EM_RESTART': '1', 17:30:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:01 INFO - 'PWD': '/builds/slave/test', 17:30:01 INFO - 'SHELL': '/bin/bash', 17:30:01 INFO - 'SHLVL': '1', 17:30:01 INFO - 'TERM': 'linux', 17:30:01 INFO - 'TMOUT': '86400', 17:30:01 INFO - 'USER': 'cltbld', 17:30:01 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:01 INFO - '_': '/tools/buildbot/bin/python'} 17:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:02 INFO - Running setup.py (path:/tmp/pip-5hKHeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:02 INFO - Running setup.py (path:/tmp/pip-5NzPs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:02 INFO - Running setup.py (path:/tmp/pip-At1H_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:02 INFO - Running setup.py (path:/tmp/pip-pkLmGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:02 INFO - Running setup.py (path:/tmp/pip-iyWOID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:02 INFO - Running setup.py (path:/tmp/pip-citvvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:02 INFO - Running setup.py (path:/tmp/pip-PGfxH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:02 INFO - Running setup.py (path:/tmp/pip-GDOq3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:02 INFO - Running setup.py (path:/tmp/pip-1qN0Y5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:02 INFO - Running setup.py (path:/tmp/pip-lZpbQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:02 INFO - Running setup.py (path:/tmp/pip-M6zjB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:02 INFO - Running setup.py (path:/tmp/pip-1I2pKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:02 INFO - Running setup.py (path:/tmp/pip-imkexp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:02 INFO - Running setup.py (path:/tmp/pip-z6y0Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:03 INFO - Running setup.py (path:/tmp/pip-5jJgrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:03 INFO - Running setup.py (path:/tmp/pip-gIMCw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:03 INFO - Running setup.py (path:/tmp/pip-gfJjnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:30:03 INFO - Running setup.py install for manifestparser 17:30:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:30:03 INFO - Running setup.py install for mozcrash 17:30:03 INFO - Running setup.py install for mozdebug 17:30:03 INFO - Running setup.py install for mozdevice 17:30:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:30:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:30:03 INFO - Running setup.py install for mozfile 17:30:03 INFO - Running setup.py install for mozhttpd 17:30:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:30:03 INFO - Running setup.py install for mozinfo 17:30:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:30:03 INFO - Running setup.py install for mozInstall 17:30:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:30:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:30:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:30:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:30:04 INFO - Running setup.py install for mozleak 17:30:04 INFO - Running setup.py install for mozlog 17:30:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:30:04 INFO - Running setup.py install for moznetwork 17:30:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:30:04 INFO - Running setup.py install for mozprocess 17:30:04 INFO - Running setup.py install for mozprofile 17:30:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:30:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:30:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:30:04 INFO - Running setup.py install for mozrunner 17:30:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:30:04 INFO - Running setup.py install for mozscreenshot 17:30:04 INFO - Running setup.py install for moztest 17:30:04 INFO - Running setup.py install for mozversion 17:30:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:30:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:30:05 INFO - Cleaning up... 17:30:05 INFO - Return code: 0 17:30:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:05 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 0x1263030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1524180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, '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': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', '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 17:30:05 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 17:30:05 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 17:30:05 INFO - Using env: {'DISPLAY': ':0', 17:30:05 INFO - 'HOME': '/home/cltbld', 17:30:05 INFO - 'LANG': 'en_US.UTF-8', 17:30:05 INFO - 'LANGUAGE': 'en_US:en', 17:30:05 INFO - 'LOGNAME': 'cltbld', 17:30:05 INFO - 'MAIL': '/var/mail/cltbld', 17:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:05 INFO - 'MOZ_NO_REMOTE': '1', 17:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:05 INFO - 'NO_EM_RESTART': '1', 17:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:05 INFO - 'PWD': '/builds/slave/test', 17:30:05 INFO - 'SHELL': '/bin/bash', 17:30:05 INFO - 'SHLVL': '1', 17:30:05 INFO - 'TERM': 'linux', 17:30:05 INFO - 'TMOUT': '86400', 17:30:05 INFO - 'USER': 'cltbld', 17:30:05 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:05 INFO - '_': '/tools/buildbot/bin/python'} 17:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:05 INFO - Running setup.py (path:/tmp/pip-y42VKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:05 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)) 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:05 INFO - Running setup.py (path:/tmp/pip-_oK8Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:05 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)) 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:05 INFO - Running setup.py (path:/tmp/pip-qXXQ1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:05 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)) 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:05 INFO - Running setup.py (path:/tmp/pip-usc53R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:05 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)) 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:05 INFO - Running setup.py (path:/tmp/pip-KQwE8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:05 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)) 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:05 INFO - Running setup.py (path:/tmp/pip-7YQ0SX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:05 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)) 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:05 INFO - Running setup.py (path:/tmp/pip-JDetZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:05 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)) 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:05 INFO - Running setup.py (path:/tmp/pip-_2g_2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:05 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)) 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:05 INFO - Running setup.py (path:/tmp/pip-x6Ysku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:05 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)) 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:05 INFO - Running setup.py (path:/tmp/pip-vWO79V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:05 INFO - Running setup.py (path:/tmp/pip-Mq5Bgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:06 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)) 17:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:06 INFO - Running setup.py (path:/tmp/pip-O5KYFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:06 INFO - Running setup.py (path:/tmp/pip-vf0sYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:06 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)) 17:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:06 INFO - Running setup.py (path:/tmp/pip-Okhla7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:06 INFO - Running setup.py (path:/tmp/pip-B2KDFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:06 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)) 17:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:06 INFO - Running setup.py (path:/tmp/pip-OuZqPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:06 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)) 17:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:06 INFO - Running setup.py (path:/tmp/pip-2aunfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:06 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)) 17:30:06 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)) 17:30:06 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)) 17:30:06 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)) 17:30:06 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)) 17:30:06 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)) 17:30:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:08 INFO - Downloading blessings-1.6.tar.gz 17:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:30:08 INFO - Installing collected packages: blessings 17:30:08 INFO - Running setup.py install for blessings 17:30:08 INFO - Successfully installed blessings 17:30:08 INFO - Cleaning up... 17:30:08 INFO - Return code: 0 17:30:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:30:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:30:08 INFO - Reading from file tmpfile_stdout 17:30:08 INFO - Current package versions: 17:30:08 INFO - argparse == 1.2.1 17:30:08 INFO - blessings == 1.6 17:30:08 INFO - blobuploader == 1.2.4 17:30:08 INFO - docopt == 0.6.1 17:30:08 INFO - functools32 == 3.2.3-2 17:30:08 INFO - jsonschema == 2.5.1 17:30:08 INFO - manifestparser == 1.1 17:30:08 INFO - mozInstall == 1.12 17:30:08 INFO - mozcrash == 0.17 17:30:08 INFO - mozdebug == 0.1 17:30:08 INFO - mozdevice == 0.48 17:30:08 INFO - mozfile == 1.2 17:30:08 INFO - mozhttpd == 0.7 17:30:08 INFO - mozinfo == 0.9 17:30:08 INFO - mozleak == 0.1 17:30:08 INFO - mozlog == 3.3 17:30:08 INFO - moznetwork == 0.27 17:30:08 INFO - mozprocess == 0.23 17:30:08 INFO - mozprofile == 0.28 17:30:08 INFO - mozrunner == 6.12 17:30:08 INFO - mozscreenshot == 0.1 17:30:08 INFO - mozsystemmonitor == 0.3 17:30:08 INFO - moztest == 0.7 17:30:08 INFO - mozversion == 1.4 17:30:08 INFO - psutil == 3.1.1 17:30:08 INFO - requests == 1.2.3 17:30:08 INFO - wsgiref == 0.1.2 17:30:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:08 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 0x1263030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1524180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, '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': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', '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 17:30:08 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 17:30:08 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 17:30:08 INFO - Using env: {'DISPLAY': ':0', 17:30:08 INFO - 'HOME': '/home/cltbld', 17:30:08 INFO - 'LANG': 'en_US.UTF-8', 17:30:08 INFO - 'LANGUAGE': 'en_US:en', 17:30:08 INFO - 'LOGNAME': 'cltbld', 17:30:08 INFO - 'MAIL': '/var/mail/cltbld', 17:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:08 INFO - 'MOZ_NO_REMOTE': '1', 17:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:08 INFO - 'NO_EM_RESTART': '1', 17:30:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:08 INFO - 'PWD': '/builds/slave/test', 17:30:08 INFO - 'SHELL': '/bin/bash', 17:30:08 INFO - 'SHLVL': '1', 17:30:08 INFO - 'TERM': 'linux', 17:30:08 INFO - 'TMOUT': '86400', 17:30:08 INFO - 'USER': 'cltbld', 17:30:08 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:08 INFO - '_': '/tools/buildbot/bin/python'} 17:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:09 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)) 17:30:09 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)) 17:30:09 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)) 17:30:09 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)) 17:30:09 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)) 17:30:09 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)) 17:30:09 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)) 17:30:09 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)) 17:30:09 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)) 17:30:09 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)) 17:30:09 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)) 17:30:09 INFO - Cleaning up... 17:30:09 INFO - Return code: 0 17:30:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1263030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1524180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, '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': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', '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 17:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:30:09 INFO - Using env: {'DISPLAY': ':0', 17:30:09 INFO - 'HOME': '/home/cltbld', 17:30:09 INFO - 'LANG': 'en_US.UTF-8', 17:30:09 INFO - 'LANGUAGE': 'en_US:en', 17:30:09 INFO - 'LOGNAME': 'cltbld', 17:30:09 INFO - 'MAIL': '/var/mail/cltbld', 17:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:09 INFO - 'MOZ_NO_REMOTE': '1', 17:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:09 INFO - 'NO_EM_RESTART': '1', 17:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:09 INFO - 'PWD': '/builds/slave/test', 17:30:09 INFO - 'SHELL': '/bin/bash', 17:30:09 INFO - 'SHLVL': '1', 17:30:09 INFO - 'TERM': 'linux', 17:30:09 INFO - 'TMOUT': '86400', 17:30:09 INFO - 'USER': 'cltbld', 17:30:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:09 INFO - '_': '/tools/buildbot/bin/python'} 17:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:30:09 INFO - Cleaning up... 17:30:09 INFO - Return code: 0 17:30:09 INFO - Running post-action listener: _resource_record_post_action 17:30:09 INFO - Running post-action listener: _start_resource_monitoring 17:30:09 INFO - Starting resource monitoring. 17:30:09 INFO - [mozharness: 2016-08-05 00:30:09.306523Z] Finished create-virtualenv step (success) 17:30:09 INFO - [mozharness: 2016-08-05 00:30:09.306956Z] Running install step. 17:30:09 INFO - Running pre-action listener: _resource_record_pre_action 17:30:09 INFO - Running main action method: install 17:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:30:09 INFO - Reading from file tmpfile_stdout 17:30:09 INFO - Detecting whether we're running mozinstall >=1.0... 17:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:30:09 INFO - Reading from file tmpfile_stdout 17:30:09 INFO - Output received: 17:30:09 INFO - Usage: mozinstall [options] installer 17:30:09 INFO - Options: 17:30:09 INFO - -h, --help show this help message and exit 17:30:09 INFO - -d DEST, --destination=DEST 17:30:09 INFO - Directory to install application into. [default: 17:30:09 INFO - "/builds/slave/test"] 17:30:09 INFO - --app=APP Application being installed. [default: firefox] 17:30:09 INFO - mkdir: /builds/slave/test/build/application 17:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:30:26 INFO - Reading from file tmpfile_stdout 17:30:26 INFO - Output received: 17:30:26 INFO - /builds/slave/test/build/application/firefox/firefox 17:30:26 INFO - Running post-action listener: _resource_record_post_action 17:30:26 INFO - [mozharness: 2016-08-05 00:30:26.459574Z] Finished install step (success) 17:30:26 INFO - [mozharness: 2016-08-05 00:30:26.459820Z] Running run-tests step. 17:30:26 INFO - Running pre-action listener: _resource_record_pre_action 17:30:26 INFO - Running main action method: run_tests 17:30:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:30:26 INFO - Python 2.7.3 17:30:26 INFO - Return code: 0 17:30:26 INFO - grabbing minidump binary from tooltool 17:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:26 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 0x15223b0>, '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 0x15232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1523740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:30:26 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 17:30:26 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 17:30:26 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 17:30:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:30:26 INFO - Return code: 0 17:30:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:30:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:30:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:30:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:30:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:30:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 17:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 17:30:26 INFO - Using env: {'DISPLAY': ':0', 17:30:26 INFO - 'HOME': '/home/cltbld', 17:30:26 INFO - 'LANG': 'en_US.UTF-8', 17:30:26 INFO - 'LANGUAGE': 'en_US:en', 17:30:26 INFO - 'LOGNAME': 'cltbld', 17:30:26 INFO - 'MAIL': '/var/mail/cltbld', 17:30:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:30:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:26 INFO - 'MOZ_NO_REMOTE': '1', 17:30:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:30:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:26 INFO - 'NO_EM_RESTART': '1', 17:30:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:26 INFO - 'PWD': '/builds/slave/test', 17:30:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:30:26 INFO - 'SHELL': '/bin/bash', 17:30:26 INFO - 'SHLVL': '1', 17:30:26 INFO - 'TERM': 'linux', 17:30:26 INFO - 'TMOUT': '86400', 17:30:26 INFO - 'USER': 'cltbld', 17:30:26 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850', 17:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:26 INFO - '_': '/tools/buildbot/bin/python'} 17:30:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 17:30:26 INFO - mozversion application_buildid: 20160804143003 17:30:26 INFO - mozversion application_changeset: c60886d5c003908affbe74e56a7c5b88021b4c6e 17:30:26 INFO - mozversion application_display_name: Nightly 17:30:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:30:26 INFO - mozversion application_name: Firefox 17:30:26 INFO - mozversion application_remotingname: firefox 17:30:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:30:26 INFO - mozversion application_vendor: Mozilla 17:30:26 INFO - mozversion application_version: 51.0a1 17:30:26 INFO - mozversion platform_buildid: 20160804143003 17:30:26 INFO - mozversion platform_changeset: c60886d5c003908affbe74e56a7c5b88021b4c6e 17:30:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:30:26 INFO - mozversion platform_version: 51.0a1 17:30:26 INFO - using testdate: 1470357026 17:30:26 INFO - actual date: 1470357026 17:30:26 INFO - starting webserver on 'localhost:44768' 17:30:26 INFO - SUITE-START | Running 2 tests 17:30:26 INFO - TEST-START | dromaeo_css 17:30:26 INFO - operating with platform_type : linux_ 17:30:26 INFO - Initialising browser for dromaeo_css test... 17:30:26 INFO - TEST-INFO | started process 23026 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGvaa60/profile http://localhost:44768/getInfo.html) 17:30:30 INFO - PROCESS | 23026 | __metrics Screen width/height:1600/1200 17:30:30 INFO - PROCESS | 23026 | colorDepth:24 17:30:30 INFO - PROCESS | 23026 | Browser inner width/height: 1024/697 17:30:30 INFO - PROCESS | 23026 | __metrics 17:30:31 INFO - PROCESS | 23026 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:30:31 INFO - PROCESS | 23026 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:30:32 INFO - TEST-INFO | 23026: exit 0 17:30:32 INFO - Browser initialized. 17:30:32 INFO - Running cycle 1/1 for dromaeo_css test... 17:30:32 INFO - TEST-INFO | started process 23106 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGvaa60/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:30:43 INFO - PROCESS | 23106 | RSS: Main: 156819456 17:30:43 INFO - PROCESS | 23106 | 17:31:35 INFO - PROCESS | 23106 | 1470357095604 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] 17:33:44 INFO - PROCESS | 23106 | Cycle 1(1): loaded http://localhost:44768/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44768/tests/dromaeo/cssquery-ext.html) 17:33:45 INFO - PROCESS | 23106 | RSS: Main: 262234112 17:33:45 INFO - PROCESS | 23106 | 17:36:48 INFO - PROCESS | 23106 | Cycle 1(1): loaded http://localhost:44768/tests/dromaeo/cssquery-ext.html (next: http://localhost:44768/tests/dromaeo/cssquery-jquery.html) 17:36:48 INFO - PROCESS | 23106 | RSS: Main: 239984640 17:36:48 INFO - PROCESS | 23106 | 17:39:50 INFO - PROCESS | 23106 | Cycle 1(1): loaded http://localhost:44768/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44768/tests/dromaeo/cssquery-mootools.html) 17:39:51 INFO - PROCESS | 23106 | RSS: Main: 215683072 17:39:51 INFO - PROCESS | 23106 | 17:42:53 INFO - PROCESS | 23106 | Cycle 1(1): loaded http://localhost:44768/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44768/tests/dromaeo/cssquery-prototype.html) 17:42:54 INFO - PROCESS | 23106 | RSS: Main: 222056448 17:42:54 INFO - PROCESS | 23106 | 17:45:56 INFO - PROCESS | 23106 | Cycle 1(1): loaded http://localhost:44768/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44768/tests/dromaeo/cssquery-yui.html) 17:45:57 INFO - PROCESS | 23106 | RSS: Main: 185192448 17:45:57 INFO - PROCESS | 23106 | 17:49:00 INFO - PROCESS | 23106 | Cycle 1(1): loaded http://localhost:44768/tests/dromaeo/cssquery-yui.html (next: http://localhost:44768/tests/dromaeo/cssquery-dojo.html) 17:49:00 INFO - PROCESS | 23106 | RSS: Main: 275890176 17:49:00 INFO - PROCESS | 23106 | 17:49:00 INFO - PROCESS | 23106 | __start_tp_report 17:49:00 INFO - PROCESS | 23106 | _x_x_mozilla_page_load 17:49:00 INFO - PROCESS | 23106 | _x_x_mozilla_page_load_details 17:49:00 INFO - PROCESS | 23106 | |i|pagename|runs| 17:49:00 INFO - PROCESS | 23106 | |0;dojo.html;3046.1318524220596;3046.1775320663423;3065.8926937557185;3086.243870251789;3101.3642203348318;5011.69929804212;5090.389153301599;5236.2145678148245;5295.443978382267;5322.281491998584;5191.480851914806;5261.316028916235;5310.468953104688;5352.5182733553975;5389.272448219489;4679;4731.739754313511;4737.384140061788;4745.90508189836;4908;4829.251466022772;4858.78135483904;4872.4640289568115;4877.561019508244;4916.926246106311;4699.600533954584;4741.454732705749;4747.83382581608;4792.424909010923;4794.472608013133;4697.906041879178;4704.129735998851;4709.811607535695;4746.121967436035;4773.952260477386;4680.110778951988;4740.834070807505;4748.050389921995;4763.04739052191;4793.520647935214;5109.6678715883345;5159.355080614923;5198;5216.452272511369;5256.027634887558;5554.805581804618;5577.721113944286;5583.916241256385;5626.324841019063;5633.943660563383;5644.491995720364;5651.124075768263;5663.207150998864;5675.290588676416;5682.460166284196;5055.5702765265;5195.2986346843445;5254.842354729364;5258.132408152688;5298.576113910916;5776.046952252916;5785.4214578541805;5815.651060936358;5838.970805145947;5841;5531.502164805187;5537.695426751487;5591.496765291144;5593.161025846156;5594.832155035356;5366.7853285868905;5499.697516636624;5566.66600003993;5644;5644.379118296984;5560.916586251209;5568.248286481273;5572.08060669993;5607.80372686954;5613.073842815889;5548.278723765965;5575.024370735106;5581.888362232733;5587.7485513152005;5594.160875868571;4261.424707664488;4297.871063868089;4326.913461730748;4336.392904993303;4360.9127817444105;3843.327417701947;3855.036240939765;3881.825317860703;3902.1415288636454;3918.2555314490155;5318.70747108905;5329.706866122323;5331.054934901679;5331.200319952039;5380.892382152335;5525.475079867406;5570.387257401682;5580.218769372291;5645.294338207724;5652.2652055233375;5278.340207474066;5294.655847369909;5301.973490132525;5330.520253177233;5342.439043900412;4826.155422800995;4845.854624361275;4846.321514987904;4847.151748444008;4889.511048895153;5611.6071874969175;5697.6296540724725;5803.390643982321;5830.4461076197695;5841.211436456109;5661.57538184646;5661.830145095653;5693.572982026281;5707.514861236841;5709.514691251124;5825.592208545361;5834.649921004753;5839.35767065614;5843.1235314703135;5854.619449735754;5292.04743146222;5413.891722165614;5511.944880551304;5538.86152846166;5540.972295138659;5522.6134170607675;5547.112462006221;5548.667079975215;5609.411011843697;5632.746526406239;5379.704116273642;5417.756200970887;5419.566434685137;5419.918701219563;5425.267588875451;5174;5361.213897235857;5382.67703937765;5425.348958125051;5471.699056551928;5373.543248823894;5444.918326225189;5445.346558413015;5447.182922561489;5492.176173573996;5528.695921724344;5530.446955304437;5578.9163162553405;5581.665100094007;5587.636803607753;5287.074761916572;5325.627206095536;5343.4389389113585;5390.811321603725;5391.62258641907 17:49:00 INFO - PROCESS | 23106 | |1;ext.html;571.225988785196;598.9191459153014;605.8005149803389;612.1307743004934;615.4645458451148;31802.20494487639;32192.517112243306;42308.99999999996;42912.356314655226;43038.784806076;40886.38670419946;44817.32774008384;44846.77576612112;44862.55137448633;44938.775306123425;9011.54942252888;9153.405028673149;9163.312751543628;9166.816663666723;9175.908240917572;8975.281977441822;9066.909330906705;9072.591733372014;9077.092290770903;9085.954570227139;9124.908750912473;9142.268618510536;9176.632934682604;9178.173964343208;9188.1730644242;9073.13805188506;9119.863202051974;9122.08779122089;9139.954300228523;9145.085491450802;8898.59956301968;8939.553022348857;8962.058983806672;8967.461952282883;8967.820643587156;42729.78635106836;42964.57035429637;42983.78508107439;43186.35220471695;43210.135797284194;3445.3109378124223;3493.598236202844;3534.6642069003406;3662.359087159736;4396.054848207651;32684.67315326864;33096.1725956853;33111;33621.831890840636;33648.83175584131;4325.286327755916;4449.955500444987;4463.732176069414;4511.458624964992;4676.7427791471455;357368;357848;358259;358459.2077039624;359608;56646.43353566494;56674.43325566774;56720;57071;57176;60736;60785;60877.69561152166;60913.39086609077;60925.69537152375;16754.162291885357;17012.91493542549;17047.550958168446;17053.14734263282;17063.23215455283;13171.143875648053;13388.93305533466;13392.330383480787;13412.329383530785;13413.530526431528;14133;15985.600359991093;16075.517734467985;16181.514554563382;16261.268242929096;6219.502439804804;6233.158523599347;6340.112384266206;6353.14232578596;6431.356864313625;815.9428839981144;952.9689413779612;1027.091024443373;1105.4748994227677;1130.112861403794;32119.839400802848;32641.51037734436;32644.183895402806;32739.50890736641;33144;985.8915519292872;1086.163653986442;1093.8195197792434;1126.9943650281696;1136.6249137784512;16218.675626487404;17251.223694933757;17354.739678904825;17460.82539174592;17517.38689145899;563956;567865;569095;569668;569762;58319.41680583225;58529;58570.242892713286;58592;58796.706016469645;1230.5508489401304;1259.1878238536092;1326.5754958413409;1364.6520137364907;1394.2889126545535;29736.553951690308;33631;33636.49545256774;33920;33930.49104263388;49533.752331238116;49987.75006125092;50114;50193.24710129278;50197;374978;376162;376813;376894;377205;27878.58182127229;28702;28948.565771513866;30086.849565752033;41338;48119;48281.51718482911;49178.75410622924;49397;49897;40908.386374203816;41058.589414105474;41359.793201035005;42102.789486052374;43782.34326485042;41228.1754364905;41639;41951.37072943847;43076;43203;41475;42384.576154238064;42639.1472170561;42697;42717;35051;37252;37570;37603.02146806481;38043.80978095092;27861.860690696416;28087.88331526195;28091.859540702164;28295.575566366108;28412 17:49:00 INFO - PROCESS | 23106 | |2;jquery.html;369.60821529179054;394.8045717369902;397.20360676842944;400.5453809925734;424.45669542984973;16304.021758694471;19477.3182938597;19518.219271229133;19594.510137246558;19943.202271909144;18981.715274270897;18997.525061873424;19461.124249408775;20223.49441263969;20242.190312387487;5352.411234764173;5682.431756824316;5955.047192449209;5993.4006599340155;6000.399960004008;5876.882462350751;5941.85145371367;5991.970040149793;5995.040793473046;5995.250593675791;5715.599908006441;5875.941240587604;5880.235569375996;5883.970580147094;5979.46184843364;5698.8290351289525;5853.970730146343;5892.734826932799;5894.882102357972;5895.498882594985;5278.841634750964;5313.521783039545;5314.627976041678;5316.9202461963105;5317.468253174651;23908.880455597784;24865.875670621714;24873.129440469496;25029.374265643324;25057.87471062633;2494.189388448743;2537.340291524217;2570.640110384529;2988.208124846917;3043.45217860785;14431.639209019859;14602.415903363853;14921.403143874231;15144.848551514564;15600.453984110614;1914.5596512801994;1918.5875036867;1922.356010736405;1966.9606607867763;2011.1553147677864;83595.58202209011;88186;90486.54756726175;90888.09111908928;93439.53280233555;11189.328640281446;11282.548698052151;11339.319640821577;11374.31754094757;11462.66335715783;15726.135062571668;15833.762493562375;15835.68328633421;15877.52367428979;16037.278322475517;8291.917080829235;8347.123552027071;8389;8506.914930850613;8575.528345940907;8089.878651820228;8280.7578863171;8281.917180828235;8312.916870831214;8545.060043395222;4751.192297309466;4848.612111031109;4870.179075145052;4880.641303866458;4897.975510122426;1856.378113332049;1903.0009245146132;1931.7295578619005;1986.0466975851673;2034.3490083173538;877.8814859993819;935.2564711054728;996.2080146283722;1022.2895087913977;1029.8620024872557;19892.10485528155;20568.485787855123;20617.58764824695;20634.79365206359;20670.79329206719;950.3062764182048;959.2709540749081;996.5565323431149;1008.5511947183433;1020.882598501167;11060.115190784718;11432.31406115619;11473.426328683532;11501.482433290523;11509.76980460386;507021;508335;516374;522506;536502;81356;90749.54625226963;98552;99794.50102749439;100007.49996249973;985.556499575194;997.4987643596342;1010.6058637131524;1021.5454122915231;1031.1235449867552;17945.371911983017;18298.176582054104;18819.341323054174;18866.434006979813;19267.710984334964;42679.3598096035;42861;43367;45262.321065184704;45459;59745;60449.39550604438;60478.697606513444;61822.38177618166;62443.6877815608;5998.7502603624725;8570.100139485265;8833.911660883567;8892.110788921056;9195.494247816434;12221.511139554319;13452.932735336652;13635.727285454037;13667.248301343521;14013.089149205269;11107.222494425312;11537.423128843357;11966.46150923193;12029.699257518632;12032.819507707216;11327.773444531229;11432.37121958267;11626.306550706311;11748.530058797895;11790.823137652771;11739.473868397054;11887.762244754884;11969.760604788029;12015.75968480608;12071.517139314328;7200.8919866201;7721.382289416721;7817.218278172138;8703.43427677199;8871.245944094824;8529.786755330919;11312.773744525228;11435.142364322875;11670.766584668429;11848.703782405164 17:49:00 INFO - PROCESS | 23106 | |3;mootools.html;1442.8412874583796;1492.6193820575752;1638.737801951688;1769.2922830867644;1824.3797108982942;8610.268127209187;9285.535723213847;9288.628454861797;9300.534973251344;9304;7887.250711182429;8142.6335814888325;8149.511029338244;8149.714759983402;9064.864027039599;2262.2082271205113;2356.2577787996725;2360.327306717586;2380.571497130516;2381.4760752634393;2322.63999080142;2367.5264947010583;2381.071382160959;2384.642303654457;2388;2364.397078744926;2365.3377054424705;2367.2069856597973;2368.100121953664;2372.789877162651;2315.907363705458;2353.52929414117;2358.8466749661216;2359.3629719975684;2367.9052837886466;2259.1302348595746;2261.434641339665;2318.7681231876845;2321.0483701682397;2326.592846251899;8933.330000249942;9005.594748236344;9048.638054477813;9058.365914385995;9079.546022698838;5129.358830146231;5264.947350526484;5267.947320526784;5271.841844744664;5277.9472205278225;3371.123507887942;3443.5695538057744;3463.1342164458883;3508.2106526031694;3513;1038.4600007995875;1087.7715679707271;1108.1467270201979;1111.572044762764;1131.796276670199;12462.875371246353;12497.437615307243;12520.812187817099;12531.874681253163;12541.435635396338;4715.929261061086;4760.952390476051;4842.297866809293;4846.055019271208;4866.343043689128;9863.260255480867;10037.849432258521;10055.949720251352;10059.647912323033;10066.748331291776;5343.251944727741;5346.732663366816;5347.331583552056;5354.678719276778;5357.249985002103;4401.977990110093;4875.293082503088;4916.70499770015;5027.547520723079;5035.546800787946;3650.3976843821;3750.868719594801;3777.0934975606206;3797.6392242736897;3845.442410850411;1171.7305019845483;1175.76484703058;1186.3652945674023;1191.362620997762;1193.773183095209;931.7530854323475;976.8534719792088;978.5303054533864;978.6623614852864;993.7515621094726;5905.232319798399;5947.37552556984;5972.671503067286;6083.209182806296;6125.418085281891;922.7646950027699;957.1577012229194;987.8073775613686;994.945278009702;1009.9545520451597;4106.91786164281;4181.330987042059;4213.47331583552;4214.262504061839;4282.272013757669;10950.671479855617;11129.276597021168;11197;11201.383923884265;11368;11317.943410282896;11335.206535542433;11343.716407089889;11343.886561134283;11347.943260283811;8028.237317454832;8260.752177434686;8334.833303333899;8389.07720150783;8533.104024077382;5940.940590594038;6014.879702406015;6029.457348838626;6092.634441933498;6229.314775374614;10217;10324.174066074846;10338.328008679411;10352.741181470523;10477.52851121686;10421.426821524585;10718.196135289729;10719.088877445505;10728.73178170552;10742.62400815999;7053.4004609608755;7787.415943804124;7902.0912595052305;7928.246816552418;7928.722494712657;8399.202075802788;8640.395172337876;8719.476831390137;8743.519106449206;8747.562621868754;7566.054243219598;7645.235476452311;7679.500832445873;7694.07671079474;7907.802304942423;7598.96200519016;7606.885896711665;7656.4640475168435;7748.65131069092;7767.339776119094;7093.503454758324;7202.099737532809;7202.243764404662;7207.1711753146965;7211.495195336276;5012.248162775612;5047.646664733434;5072.82245121419;5154.072266991978;5171.50870667286;4890.853274401774;5107.9489205107475;5142.151544995034;5178.508041736029;5310.336207974004 17:49:00 INFO - PROCESS | 23106 | |4;prototype.html;329.7367958948745;339.5500961226375;345.5507839808249;349.6346318097586;351.15371953591847;5295.232191332255;5384.461553844613;5399.973000134999;5402.70285134317;5444.428335024825;5043.899122017558;5170.474709960561;5179.14544100224;5196.92204616931;5353.678779273237;4051.979740101295;4080.816363263653;4086.1827634473075;4173.5409104998425;4191.413202151702;4136.503619565657;4142.1922725068625;4146.751194928314;4147.979260103695;4168.8332466701295;4072.3891416287497;4109.725984944662;4131.876043718693;4141.62725354718;4169.541350451448;4130.896727581834;4130.946608614814;4143.8342466301465;4144.8756537303925;4145.233131870584;3662.9926770138427;3670.2292518570866;3670.8164591770305;3686.686631636314;3687.7418580699364;4990.925136122961;5120.487951204887;5142.07442660321;5154.407243167008;5163.251328557376;4681.291328665046;4690.771883355413;4707.882302942419;4708.952910470885;4729.9763501182615;5439.211314359396;5446.319210098738;5453.727313634303;5455.318085239345;5462.371827239839;3942.8225729842225;4022.0146064214164;4023.456833327522;4024.6981476389096;4032.737872038308;5601.831945041656;5619.241402410663;5620.5503559715125;5625.240592519998;5709.457601527848;4080.163566468867;4094.28350038742;4100.2414553307735;4106.856260030884;4108.938365924513;4096.467459230281;4187.392828039917;4194.874153775391;4194.874153775391;4209.810558524874;4626.236670949323;4646.163690535736;4680.95319046812;4714.528547145258;4723.378524918635;5444;5444.727763611803;5452.536534394541;5453.291072160673;5457.28095649869;1353.1505045855658;1426.436557559758;1449.2174225918097;1452.4553292515307;1452.5039209614115;927.1609193679731;963.8409662405764;1014.3710899242517;1018.5671089786811;1019.6125472320471;5152.1148157032185;5159.819406320761;5203.323567936144;5207.921881171786;5217.921731174036;5489.737360407049;5513.807016754395;5525.944740552623;5550.888982220414;5553.361363443256;5056.595472362275;5188.584913206934;5225.6603320784025;5227.372715274116;5229.6339256251695;2961.126467692036;2963.555466679972;2971.7919745618033;2973.1675130963363;2974.4051189761703;5543.556515478752;5735.512481439125;5746.913796293059;5777.653340799481;5803.593748437654;5566.749496272678;5572.86067848307;5601.775928962796;5612.719364031782;5634.295713035871;5715.942840571624;5731.426857314235;5778.191053252548;5819.6217245879725;5825.165073001991;5383.838484845461;5615.241942337732;5615.248528221061;5667.826017430252;5689.573282003784;5555.888822235488;5563.527100196528;5578.2748242728185;5597.608167428241;5610.5231055360755;5694.829155125353;5849.5612829037145;5894.793682221273;5905.261842269716;5940.485176280194;4035;4055.229506393776;4059.4722686050623;4062.4515690382605;4072.185562887375;5408.513233808976;5581.134924086773;5597.692126933058;5608.747606357642;5632.6338787980285;5465.808696695596;5475.534579560782;5522.5581953442825;5560.721963901708;5612;5264.236685680631;5530.806421775219;5531;5537.307836520435;5542.11326187808;5418.972905135449;5434.809781657781;5483.259759932358;5495.505404513613;5500.477454641803;5554.694491802989;5567.443255674562;5570;5591.944080559305;5593.440655934537;5218.8956220876125;5453.182022696468;5471.452854714496;5476.3702174250475;5493.340799104133 17:49:00 INFO - PROCESS | 23106 | |5;yui.html;690.1683471416943;704.144464475662;766.7546385156752;775.8215610409605;793.3811626930996;15394.538163855073;16722.916385418055;16768.83231167688;16770.664586708273;16793.91603041983;14561;14629.634259143526;14670;14817.407303707865;14901.701965960674;639.3223183425583;752.5183882315323;768.8077980504873;773.9032620922385;778.1984555907396;778.0313509680427;780.6760194519268;781.4646966827713;787.2167193642306;787.2560430393281;841.877927700483;844.0546587821618;845.8392905347995;846.0397448895486;846.5386364431369;682.3142741544741;808.1433680298902;820.7989042684521;821.0926925747024;844.8648216285395;602.0908428273295;602.1780269931555;602.1990752299431;602.8191542537221;603.0983679399283;15354.769678454832;15378.846211537853;15388.538343849703;15424.845751542565;15473.845261547352;14152.716945661132;14196.290185490787;14321.92585556077;14443.927780361137;14573.12561246318;12937.870621293761;12942.275135710157;13177.077604567685;13243.404046817916;13332.200067995855;4039.454673619053;4049.6152865477734;4180.540140584533;4193.03560181157;4208.9789551052045;81309.73363453589;82244;82407.17592824055;83857;84155;11521.711957201138;11552.94223528877;11632.592859249886;11700.414979251003;11733.589324373777;20452.181912723623;20624;20944.790552094284;21025.15899364008;21155.894220529106;8395.118512556093;8401.915980840113;8409.747707568782;8493.617787199591;8721.433106848035;4750.192467280571;4799.3760811095035;4810.95189048112;4916.164252077084;4934.432540257844;3400.914977125542;3499.9650003499637;3500.4749287607065;3500.9474857877153;3506.2461570762407;587.8354060862965;589.9882002359928;590.5246276747174;597.1520440973827;597.5787070115558;511.93088932993567;514.7966553211461;567.4268988321825;679.4835924697265;698.9405900498515;9084.40951338161;9454.763630909283;9538.14156725898;9732.993961171916;9836.409815411098;596.0552524249114;639.4692405303587;644.4103645164622;677.0949168458328;693.9167299924042;4554.863354099382;4580.976278501188;4688.367070445514;4729.243321068612;4746.857594272177;125306;125394;128079;128521;132022;114667.42666286616;114954;116320.41839790747;118534;120402.3979880095;95328.52335738415;96031;100010.4999474998;101941.49029254806;102142.4892875531;8816.260503572277;9171.587278572613;9204.125608067223;9208.585613647268;9212.447253164832;55788.44211557995;56044.43955560392;56920.715396422755;57030.7148464255;57974.13038804337;77851.22148778666;83966.16033839827;84653.57673211841;84758.57620711857;87435.56282218549;11678.88321116778;11908.702282443008;12141.332226727614;12507.74984500323;12517.687057823627;12265.754684906073;12394.256344619353;12507.687307817378;12516.12387132892;13263.66840828987;11345.659630211107;11388.14588905852;11640.243384180001;11953.940230298793;12015.09886758514;11641.6507504775;11839.526418943491;11926.284422934652;12033.939830300793;12174.269543827399;11821.82267266009;12023.75952480963;12276.447559860006;12388.62834114978;12466.065045121471;8659.13408659129;8852.070532593985;9008;9457.10157535033;9510.667126650535;10177;10219.897801021894;10261.871194168785;10590.417527036087;12021.158518903592 17:49:00 INFO - PROCESS | 23106 | __end_tp_report 17:49:00 INFO - PROCESS | 23106 | __start_cc_report 17:49:00 INFO - PROCESS | 23106 | _x_x_mozilla_cycle_collect,928 17:49:00 INFO - PROCESS | 23106 | __end_cc_report 17:49:00 INFO - PROCESS | 23106 | __startTimestamp1470358140507__endTimestamp 17:49:00 INFO - PROCESS | 23106 | 17:49:00 INFO - PROCESS | 23106 | ------- Summary: start ------- 17:49:00 INFO - PROCESS | 23106 | Number of tests: 6 17:49:00 INFO - PROCESS | 23106 | 17:49:00 INFO - PROCESS | 23106 | [#0] dojo.html Cycles:165 Average:5208.98 Median:5380.89 stddev:582.72 (10.8%) stddev-sans-first:559.26 17:49:00 INFO - PROCESS | 23106 | Values: 3046.1 3046.2 3065.9 3086.2 3101.4 5011.7 5090.4 5236.2 5295.4 5322.3 5191.5 5261.3 5310.5 5352.5 5389.3 4679.0 4731.7 4737.4 4745.9 4908.0 4829.3 4858.8 4872.5 4877.6 4916.9 4699.6 4741.5 4747.8 4792.4 4794.5 4697.9 4704.1 4709.8 4746.1 4774.0 4680.1 4740.8 4748.1 4763.0 4793.5 5109.7 5159.4 5198.0 5216.5 5256.0 5554.8 5577.7 5583.9 5626.3 5633.9 5644.5 5651.1 5663.2 5675.3 5682.5 5055.6 5195.3 5254.8 5258.1 5298.6 5776.0 5785.4 5815.7 5839.0 5841.0 5531.5 5537.7 5591.5 5593.2 5594.8 5366.8 5499.7 5566.7 5644.0 5644.4 5560.9 5568.2 5572.1 5607.8 5613.1 5548.3 5575.0 5581.9 5587.7 5594.2 4261.4 4297.9 4326.9 4336.4 4360.9 3843.3 3855.0 3881.8 3902.1 3918.3 5318.7 5329.7 5331.1 5331.2 5380.9 5525.5 5570.4 5580.2 5645.3 5652.3 5278.3 5294.7 5302.0 5330.5 5342.4 4826.2 4845.9 4846.3 4847.2 4889.5 5611.6 5697.6 5803.4 5830.4 5841.2 5661.6 5661.8 5693.6 5707.5 5709.5 5825.6 5834.6 5839.4 5843.1 5854.6 5292.0 5413.9 5511.9 5538.9 5541.0 5522.6 5547.1 5548.7 5609.4 5632.7 5379.7 5417.8 5419.6 5419.9 5425.3 5174.0 5361.2 5382.7 5425.3 5471.7 5373.5 5444.9 5445.3 5447.2 5492.2 5528.7 5530.4 5578.9 5581.7 5587.6 5287.1 5325.6 5343.4 5390.8 5391.6 17:49:00 INFO - PROCESS | 23106 | 17:49:00 INFO - PROCESS | 23106 | [#1] ext.html Cycles:180 Average:59796.80 Median:42707.00 stddev:117930.70 (276.1%) stddev-sans-first:118177.69 17:49:00 INFO - PROCESS | 23106 | Values: 571.2 598.9 605.8 612.1 615.5 31802.2 32192.5 42309.0 42912.4 43038.8 40886.4 44817.3 44846.8 44862.6 44938.8 9011.5 9153.4 9163.3 9166.8 9175.9 8975.3 9066.9 9072.6 9077.1 9086.0 9124.9 9142.3 9176.6 9178.2 9188.2 9073.1 9119.9 9122.1 9140.0 9145.1 8898.6 8939.6 8962.1 8967.5 8967.8 42729.8 42964.6 42983.8 43186.4 43210.1 3445.3 3493.6 3534.7 3662.4 4396.1 32684.7 33096.2 33111.0 33621.8 33648.8 4325.3 4450.0 4463.7 4511.5 4676.7 357368.0 357848.0 358259.0 358459.2 359608.0 56646.4 56674.4 56720.0 57071.0 57176.0 60736.0 60785.0 60877.7 60913.4 60925.7 16754.2 17012.9 17047.6 17053.1 17063.2 13171.1 13388.9 13392.3 13412.3 13413.5 14133.0 15985.6 16075.5 16181.5 16261.3 6219.5 6233.2 6340.1 6353.1 6431.4 815.9 953.0 1027.1 1105.5 1130.1 32119.8 32641.5 32644.2 32739.5 33144.0 985.9 1086.2 1093.8 1127.0 1136.6 16218.7 17251.2 17354.7 17460.8 17517.4 563956.0 567865.0 569095.0 569668.0 569762.0 58319.4 58529.0 58570.2 58592.0 58796.7 1230.6 1259.2 1326.6 1364.7 1394.3 29736.6 33631.0 33636.5 33920.0 33930.5 49533.8 49987.8 50114.0 50193.2 50197.0 374978.0 376162.0 376813.0 376894.0 377205.0 27878.6 28702.0 28948.6 30086.8 41338.0 48119.0 48281.5 49178.8 49397.0 49897.0 40908.4 41058.6 41359.8 42102.8 43782.3 41228.2 41639.0 41951.4 43076.0 43203.0 41475.0 42384.6 42639.1 42697.0 42717.0 35051.0 37252.0 37570.0 37603.0 38043.8 27861.9 28087.9 28091.9 28295.6 28412.0 17:49:00 INFO - PROCESS | 23106 | 17:49:00 INFO - PROCESS | 23106 | [#2] jquery.html Cycles:180 Average:30520.05 Median:3015.83 stddev:85492.80 (2834.8%) stddev-sans-first:85702.66 17:49:00 INFO - PROCESS | 23106 | Values: 369.6 394.8 397.2 400.5 424.5 16304.0 19477.3 19518.2 19594.5 19943.2 18981.7 18997.5 19461.1 20223.5 20242.2 5352.4 5682.4 5955.0 5993.4 6000.4 5876.9 5941.9 5992.0 5995.0 5995.3 5715.6 5875.9 5880.2 5884.0 5979.5 5698.8 5854.0 5892.7 5894.9 5895.5 5278.8 5313.5 5314.6 5316.9 5317.5 23908.9 24865.9 24873.1 25029.4 25057.9 2494.2 2537.3 2570.6 2988.2 3043.5 14431.6 14602.4 14921.4 15144.8 15600.5 1914.6 1918.6 1922.4 1967.0 2011.2 83595.6 88186.0 90486.5 90888.1 93439.5 11189.3 11282.5 11339.3 11374.3 11462.7 15726.1 15833.8 15835.7 15877.5 16037.3 8291.9 8347.1 8389.0 8506.9 8575.5 8089.9 8280.8 8281.9 8312.9 8545.1 4751.2 4848.6 4870.2 4880.6 4898.0 1856.4 1903.0 1931.7 1986.0 2034.3 877.9 935.3 996.2 1022.3 1029.9 19892.1 20568.5 20617.6 20634.8 20670.8 950.3 959.3 996.6 1008.6 1020.9 11060.1 11432.3 11473.4 11501.5 11509.8 507021.0 508335.0 516374.0 522506.0 536502.0 81356.0 90749.5 98552.0 99794.5 100007.5 985.6 997.5 1010.6 1021.5 1031.1 17945.4 18298.2 18819.3 18866.4 19267.7 42679.4 42861.0 43367.0 45262.3 45459.0 59745.0 60449.4 60478.7 61822.4 62443.7 5998.8 8570.1 8833.9 8892.1 9195.5 12221.5 13452.9 13635.7 13667.2 14013.1 11107.2 11537.4 11966.5 12029.7 12032.8 11327.8 11432.4 11626.3 11748.5 11790.8 11739.5 11887.8 11969.8 12015.8 12071.5 7200.9 7721.4 7817.2 8703.4 8871.2 8529.8 11312.8 11435.1 11670.8 11848.7 17:49:00 INFO - PROCESS | 23106 | 17:49:00 INFO - PROCESS | 23106 | [#3] mootools.html Cycles:180 Average:5869.25 Median:4870.82 stddev:3362.18 (69.0%) stddev-sans-first:3355.16 17:49:00 INFO - PROCESS | 23106 | Values: 1442.8 1492.6 1638.7 1769.3 1824.4 8610.3 9285.5 9288.6 9300.5 9304.0 7887.3 8142.6 8149.5 8149.7 9064.9 2262.2 2356.3 2360.3 2380.6 2381.5 2322.6 2367.5 2381.1 2384.6 2388.0 2364.4 2365.3 2367.2 2368.1 2372.8 2315.9 2353.5 2358.8 2359.4 2367.9 2259.1 2261.4 2318.8 2321.0 2326.6 8933.3 9005.6 9048.6 9058.4 9079.5 5129.4 5264.9 5267.9 5271.8 5277.9 3371.1 3443.6 3463.1 3508.2 3513.0 1038.5 1087.8 1108.1 1111.6 1131.8 12462.9 12497.4 12520.8 12531.9 12541.4 4715.9 4761.0 4842.3 4846.1 4866.3 9863.3 10037.8 10055.9 10059.6 10066.7 5343.3 5346.7 5347.3 5354.7 5357.2 4402.0 4875.3 4916.7 5027.5 5035.5 3650.4 3750.9 3777.1 3797.6 3845.4 1171.7 1175.8 1186.4 1191.4 1193.8 931.8 976.9 978.5 978.7 993.8 5905.2 5947.4 5972.7 6083.2 6125.4 922.8 957.2 987.8 994.9 1010.0 4106.9 4181.3 4213.5 4214.3 4282.3 10950.7 11129.3 11197.0 11201.4 11368.0 11317.9 11335.2 11343.7 11343.9 11347.9 8028.2 8260.8 8334.8 8389.1 8533.1 5940.9 6014.9 6029.5 6092.6 6229.3 10217.0 10324.2 10338.3 10352.7 10477.5 10421.4 10718.2 10719.1 10728.7 10742.6 7053.4 7787.4 7902.1 7928.2 7928.7 8399.2 8640.4 8719.5 8743.5 8747.6 7566.1 7645.2 7679.5 7694.1 7907.8 7599.0 7606.9 7656.5 7748.7 7767.3 7093.5 7202.1 7202.2 7207.2 7211.5 5012.2 5047.6 5072.8 5154.1 5171.5 4890.9 5107.9 5142.2 5178.5 5310.3 17:49:00 INFO - PROCESS | 23106 | 17:49:00 INFO - PROCESS | 23106 | [#4] prototype.html Cycles:180 Average:4637.39 Median:5226.52 stddev:1338.83 (25.6%) stddev-sans-first:1302.96 17:49:00 INFO - PROCESS | 23106 | Values: 329.7 339.6 345.6 349.6 351.2 5295.2 5384.5 5400.0 5402.7 5444.4 5043.9 5170.5 5179.1 5196.9 5353.7 4052.0 4080.8 4086.2 4173.5 4191.4 4136.5 4142.2 4146.8 4148.0 4168.8 4072.4 4109.7 4131.9 4141.6 4169.5 4130.9 4130.9 4143.8 4144.9 4145.2 3663.0 3670.2 3670.8 3686.7 3687.7 4990.9 5120.5 5142.1 5154.4 5163.3 4681.3 4690.8 4707.9 4709.0 4730.0 5439.2 5446.3 5453.7 5455.3 5462.4 3942.8 4022.0 4023.5 4024.7 4032.7 5601.8 5619.2 5620.6 5625.2 5709.5 4080.2 4094.3 4100.2 4106.9 4108.9 4096.5 4187.4 4194.9 4194.9 4209.8 4626.2 4646.2 4681.0 4714.5 4723.4 5444.0 5444.7 5452.5 5453.3 5457.3 1353.2 1426.4 1449.2 1452.5 1452.5 927.2 963.8 1014.4 1018.6 1019.6 5152.1 5159.8 5203.3 5207.9 5217.9 5489.7 5513.8 5525.9 5550.9 5553.4 5056.6 5188.6 5225.7 5227.4 5229.6 2961.1 2963.6 2971.8 2973.2 2974.4 5543.6 5735.5 5746.9 5777.7 5803.6 5566.7 5572.9 5601.8 5612.7 5634.3 5715.9 5731.4 5778.2 5819.6 5825.2 5383.8 5615.2 5615.2 5667.8 5689.6 5555.9 5563.5 5578.3 5597.6 5610.5 5694.8 5849.6 5894.8 5905.3 5940.5 4035.0 4055.2 4059.5 4062.5 4072.2 5408.5 5581.1 5597.7 5608.7 5632.6 5465.8 5475.5 5522.6 5560.7 5612.0 5264.2 5530.8 5531.0 5537.3 5542.1 5419.0 5434.8 5483.3 5495.5 5500.5 5554.7 5567.4 5570.0 5591.9 5593.4 5218.9 5453.2 5471.5 5476.4 5493.3 17:49:00 INFO - PROCESS | 23106 | 17:49:00 INFO - PROCESS | 23106 | [#5] yui.html Cycles:180 Average:22351.07 Median:4708.81 stddev:34216.77 (726.7%) stddev-sans-first:34274.10 17:49:00 INFO - PROCESS | 23106 | Values: 690.2 704.1 766.8 775.8 793.4 15394.5 16722.9 16768.8 16770.7 16793.9 14561.0 14629.6 14670.0 14817.4 14901.7 639.3 752.5 768.8 773.9 778.2 778.0 780.7 781.5 787.2 787.3 841.9 844.1 845.8 846.0 846.5 682.3 808.1 820.8 821.1 844.9 602.1 602.2 602.2 602.8 603.1 15354.8 15378.8 15388.5 15424.8 15473.8 14152.7 14196.3 14321.9 14443.9 14573.1 12937.9 12942.3 13177.1 13243.4 13332.2 4039.5 4049.6 4180.5 4193.0 4209.0 81309.7 82244.0 82407.2 83857.0 84155.0 11521.7 11552.9 11632.6 11700.4 11733.6 20452.2 20624.0 20944.8 21025.2 21155.9 8395.1 8401.9 8409.7 8493.6 8721.4 4750.2 4799.4 4811.0 4916.2 4934.4 3400.9 3500.0 3500.5 3500.9 3506.2 587.8 590.0 590.5 597.2 597.6 511.9 514.8 567.4 679.5 698.9 9084.4 9454.8 9538.1 9733.0 9836.4 596.1 639.5 644.4 677.1 693.9 4554.9 4581.0 4688.4 4729.2 4746.9 125306.0 125394.0 128079.0 128521.0 132022.0 114667.4 114954.0 116320.4 118534.0 120402.4 95328.5 96031.0 100010.5 101941.5 102142.5 8816.3 9171.6 9204.1 9208.6 9212.4 55788.4 56044.4 56920.7 57030.7 57974.1 77851.2 83966.2 84653.6 84758.6 87435.6 11678.9 11908.7 12141.3 12507.7 12517.7 12265.8 12394.3 12507.7 12516.1 13263.7 11345.7 11388.1 11640.2 11953.9 12015.1 11641.7 11839.5 11926.3 12033.9 12174.3 11821.8 12023.8 12276.4 12388.6 12466.1 8659.1 8852.1 9008.0 9457.1 9510.7 10177.0 10219.9 10261.9 10590.4 12021.2 17:49:00 INFO - PROCESS | 23106 | -------- Summary: end -------- 17:49:00 INFO - PROCESS | 23106 | 17:49:01 INFO - TEST-INFO | 23106: exit 0 17:49:01 INFO - TEST-OK | dromaeo_css | took 1114580ms 17:49:01 INFO - TEST-START | kraken 17:49:01 INFO - operating with platform_type : linux_ 17:49:01 INFO - Initialising browser for kraken test... 17:49:01 INFO - TEST-INFO | started process 23297 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptNeP4F/profile http://localhost:44768/getInfo.html) 17:49:05 INFO - PROCESS | 23297 | __metrics Screen width/height:1600/1200 17:49:05 INFO - PROCESS | 23297 | colorDepth:24 17:49:05 INFO - PROCESS | 23297 | Browser inner width/height: 1024/697 17:49:05 INFO - PROCESS | 23297 | __metrics 17:49:05 INFO - PROCESS | 23297 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:49:05 INFO - PROCESS | 23297 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:49:06 INFO - PROCESS | 23297 | console.error: PushService: 17:49:06 INFO - PROCESS | 23297 | stateChangeProcessEnqueue: Error transitioning state 17:49:06 INFO - PROCESS | 23297 | UnknownError 17:49:06 INFO - TEST-INFO | 23297: exit 0 17:49:06 INFO - Browser initialized. 17:49:06 INFO - Running cycle 1/1 for kraken test... 17:49:06 INFO - TEST-INFO | started process 23380 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptNeP4F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:49:18 INFO - PROCESS | 23380 | RSS: Main: 156831744 17:49:18 INFO - PROCESS | 23380 | 17:49:21 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44768/tests/kraken/driver.html?testName=audio-beat-detection) 17:49:21 INFO - PROCESS | 23380 | RSS: Main: 213245952 17:49:21 INFO - PROCESS | 23380 | 17:49:24 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44768/tests/kraken/driver.html?testName=audio-dft) 17:49:25 INFO - PROCESS | 23380 | RSS: Main: 191795200 17:49:25 INFO - PROCESS | 23380 | 17:49:28 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44768/tests/kraken/driver.html?testName=audio-fft) 17:49:28 INFO - PROCESS | 23380 | RSS: Main: 270819328 17:49:28 INFO - PROCESS | 23380 | 17:49:31 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44768/tests/kraken/driver.html?testName=audio-oscillator) 17:49:31 INFO - PROCESS | 23380 | RSS: Main: 180146176 17:49:31 INFO - PROCESS | 23380 | 17:49:33 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44768/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:49:34 INFO - PROCESS | 23380 | RSS: Main: 184782848 17:49:34 INFO - PROCESS | 23380 | 17:49:37 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44768/tests/kraken/driver.html?testName=imaging-darkroom) 17:49:37 INFO - PROCESS | 23380 | RSS: Main: 218877952 17:49:37 INFO - PROCESS | 23380 | 17:49:41 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44768/tests/kraken/driver.html?testName=imaging-desaturate) 17:49:41 INFO - PROCESS | 23380 | RSS: Main: 220246016 17:49:41 INFO - PROCESS | 23380 | 17:49:44 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44768/tests/kraken/driver.html?testName=json-parse-financial) 17:49:45 INFO - PROCESS | 23380 | RSS: Main: 220352512 17:49:45 INFO - PROCESS | 23380 | 17:49:46 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44768/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:49:47 INFO - PROCESS | 23380 | RSS: Main: 184373248 17:49:47 INFO - PROCESS | 23380 | 17:49:49 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44768/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:49:49 INFO - PROCESS | 23380 | RSS: Main: 189300736 17:49:49 INFO - PROCESS | 23380 | 17:49:51 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44768/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:49:52 INFO - PROCESS | 23380 | RSS: Main: 194850816 17:49:52 INFO - PROCESS | 23380 | 17:49:54 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:49:55 INFO - PROCESS | 23380 | RSS: Main: 190545920 17:49:55 INFO - PROCESS | 23380 | 17:49:58 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:49:58 INFO - PROCESS | 23380 | RSS: Main: 193249280 17:49:58 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | Cycle 1(1): loaded http://localhost:44768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44768/tests/kraken/driver.html?testName=ai-astar) 17:50:00 INFO - PROCESS | 23380 | RSS: Main: 243818496 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | __start_tp_report 17:50:00 INFO - PROCESS | 23380 | _x_x_mozilla_page_load 17:50:00 INFO - PROCESS | 23380 | _x_x_mozilla_page_load_details 17:50:00 INFO - PROCESS | 23380 | |i|pagename|runs| 17:50:00 INFO - PROCESS | 23380 | |0;ai-astar;103;113;114;111;113;112;112;112;111;112 17:50:00 INFO - PROCESS | 23380 | |1;audio-beat-detection;140;121;119;191;118;118;142;117;168;117 17:50:00 INFO - PROCESS | 23380 | |2;audio-dft;161;160;160;160;160;160;160;160;160;161 17:50:00 INFO - PROCESS | 23380 | |3;audio-fft;74;75;74;108;75;75;74;75;105;75 17:50:00 INFO - PROCESS | 23380 | |4;audio-oscillator;84;82;120;83;84;83;113;83;83;134 17:50:00 INFO - PROCESS | 23380 | |5;imaging-gaussian-blur;117;116;117;118;117;117;117;116;117;119 17:50:00 INFO - PROCESS | 23380 | |6;imaging-darkroom;146;147;145;146;146;146;146;146;146;145 17:50:00 INFO - PROCESS | 23380 | |7;imaging-desaturate;94;94;94;94;95;95;95;95;96;95 17:50:00 INFO - PROCESS | 23380 | |8;json-parse-financial;51;51;53;51;51;51;52;50;50;50 17:50:00 INFO - PROCESS | 23380 | |9;json-stringify-tinderbox;62;62;61;63;63;61;62;62;62;62 17:50:00 INFO - PROCESS | 23380 | |10;stanford-crypto-aes;76;76;75;75;75;76;75;75;75;85 17:50:00 INFO - PROCESS | 23380 | |11;stanford-crypto-ccm;123;130;119;121;120;127;120;120;143;129 17:50:00 INFO - PROCESS | 23380 | |12;stanford-crypto-pbkdf2;159;159;155;156;164;158;160;160;157;164 17:50:00 INFO - PROCESS | 23380 | |13;stanford-crypto-sha256-iterative;66;65;64;63;64;64;70;63;62;63 17:50:00 INFO - PROCESS | 23380 | __end_tp_report 17:50:00 INFO - PROCESS | 23380 | __start_cc_report 17:50:00 INFO - PROCESS | 23380 | _x_x_mozilla_cycle_collect,1683 17:50:00 INFO - PROCESS | 23380 | __end_cc_report 17:50:00 INFO - PROCESS | 23380 | __startTimestamp1470358200696__endTimestamp 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | ------- Summary: start ------- 17:50:00 INFO - PROCESS | 23380 | Number of tests: 14 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:3.06 (2.7%) stddev-sans-first:0.97 17:50:00 INFO - PROCESS | 23380 | Values: 103.0 113.0 114.0 111.0 113.0 112.0 112.0 112.0 111.0 112.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:130.50 stddev:25.77 (19.7%) stddev-sans-first:27.27 17:50:00 INFO - PROCESS | 23380 | Values: 140.0 121.0 119.0 191.0 118.0 118.0 142.0 117.0 168.0 117.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 17:50:00 INFO - PROCESS | 23380 | Values: 161.0 160.0 160.0 160.0 160.0 160.0 160.0 160.0 160.0 161.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:13.47 (18.0%) stddev-sans-first:14.04 17:50:00 INFO - PROCESS | 23380 | Values: 74.0 75.0 74.0 108.0 75.0 75.0 74.0 75.0 105.0 75.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:83.00 stddev:19.60 (23.6%) stddev-sans-first:20.39 17:50:00 INFO - PROCESS | 23380 | Values: 84.0 82.0 120.0 83.0 84.0 83.0 113.0 83.0 83.0 134.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.93 17:50:00 INFO - PROCESS | 23380 | Values: 117.0 116.0 117.0 118.0 117.0 117.0 117.0 116.0 117.0 119.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 17:50:00 INFO - PROCESS | 23380 | Values: 146.0 147.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 17:50:00 INFO - PROCESS | 23380 | Values: 94.0 94.0 94.0 94.0 95.0 95.0 95.0 95.0 96.0 95.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:0.94 (1.8%) stddev-sans-first:1.00 17:50:00 INFO - PROCESS | 23380 | Values: 51.0 51.0 53.0 51.0 51.0 51.0 52.0 50.0 50.0 50.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 17:50:00 INFO - PROCESS | 23380 | Values: 62.0 62.0 61.0 63.0 63.0 61.0 62.0 62.0 62.0 62.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:75.50 stddev:3.09 (4.1%) stddev-sans-first:3.28 17:50:00 INFO - PROCESS | 23380 | Values: 76.0 76.0 75.0 75.0 75.0 76.0 75.0 75.0 75.0 85.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:125.00 stddev:7.45 (6.0%) stddev-sans-first:7.86 17:50:00 INFO - PROCESS | 23380 | Values: 123.0 130.0 119.0 121.0 120.0 127.0 120.0 120.0 143.0 129.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.20 Median:159.50 stddev:3.01 (1.9%) stddev-sans-first:3.19 17:50:00 INFO - PROCESS | 23380 | Values: 159.0 159.0 155.0 156.0 164.0 158.0 160.0 160.0 157.0 164.0 17:50:00 INFO - PROCESS | 23380 | 17:50:00 INFO - PROCESS | 23380 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.00 stddev:2.27 (3.5%) stddev-sans-first:2.33 17:50:00 INFO - PROCESS | 23380 | Values: 66.0 65.0 64.0 63.0 64.0 64.0 70.0 63.0 62.0 63.0 17:50:00 INFO - PROCESS | 23380 | -------- Summary: end -------- 17:50:00 INFO - PROCESS | 23380 | 17:50:01 INFO - TEST-INFO | 23380: exit 0 17:50:01 INFO - TEST-OK | kraken | took 60431ms 17:50:01 INFO - SUITE-END | took 1175s 17:50:01 INFO - Completed test suite (00:19:35) 17:50:01 INFO - javascript benchmark 17:50:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3046.1318524220596, 3046.1775320663423, 3065.8926937557185, 3086.243870251789, 3101.3642203348318, 5011.69929804212, 5090.389153301599, 5236.2145678148245, 5295.443978382267, 5322.281491998584, 5191.480851914806, 5261.316028916235, 5310.468953104688, 5352.5182733553975, 5389.272448219489, 4679.0, 4731.739754313511, 4737.384140061788, 4745.90508189836, 4908.0, 4829.251466022772, 4858.78135483904, 4872.4640289568115, 4877.561019508244, 4916.926246106311, 4699.600533954584, 4741.454732705749, 4747.83382581608, 4792.424909010923, 4794.472608013133, 4697.906041879178, 4704.129735998851, 4709.811607535695, 4746.121967436035, 4773.952260477386, 4680.110778951988, 4740.834070807505, 4748.050389921995, 4763.04739052191, 4793.520647935214, 5109.6678715883345, 5159.355080614923, 5198.0, 5216.452272511369, 5256.027634887558, 5554.805581804618, 5577.721113944286, 5583.916241256385, 5626.324841019063, 5633.943660563383, 5644.491995720364, 5651.124075768263, 5663.207150998864, 5675.290588676416, 5682.460166284196, 5055.5702765265, 5195.2986346843445, 5254.842354729364, 5258.132408152688, 5298.576113910916, 5776.046952252916, 5785.4214578541805, 5815.651060936358, 5838.970805145947, 5841.0, 5531.502164805187, 5537.695426751487, 5591.496765291144, 5593.161025846156, 5594.832155035356, 5366.7853285868905, 5499.697516636624, 5566.66600003993, 5644.0, 5644.379118296984, 5560.916586251209, 5568.248286481273, 5572.08060669993, 5607.80372686954, 5613.073842815889, 5548.278723765965, 5575.024370735106, 5581.888362232733, 5587.7485513152005, 5594.160875868571, 4261.424707664488, 4297.871063868089, 4326.913461730748, 4336.392904993303, 4360.9127817444105, 3843.327417701947, 3855.036240939765, 3881.825317860703, 3902.1415288636454, 3918.2555314490155, 5318.70747108905, 5329.706866122323, 5331.054934901679, 5331.200319952039, 5380.892382152335, 5525.475079867406, 5570.387257401682, 5580.218769372291, 5645.294338207724, 5652.2652055233375, 5278.340207474066, 5294.655847369909, 5301.973490132525, 5330.520253177233, 5342.439043900412, 4826.155422800995, 4845.854624361275, 4846.321514987904, 4847.151748444008, 4889.511048895153, 5611.6071874969175, 5697.6296540724725, 5803.390643982321, 5830.4461076197695, 5841.211436456109, 5661.57538184646, 5661.830145095653, 5693.572982026281, 5707.514861236841, 5709.514691251124, 5825.592208545361, 5834.649921004753, 5839.35767065614, 5843.1235314703135, 5854.619449735754, 5292.04743146222, 5413.891722165614, 5511.944880551304, 5538.86152846166, 5540.972295138659, 5522.6134170607675, 5547.112462006221, 5548.667079975215, 5609.411011843697, 5632.746526406239, 5379.704116273642, 5417.756200970887, 5419.566434685137, 5419.918701219563, 5425.267588875451, 5174.0, 5361.213897235857, 5382.67703937765, 5425.348958125051, 5471.699056551928, 5373.543248823894, 5444.918326225189, 5445.346558413015, 5447.182922561489, 5492.176173573996, 5528.695921724344, 5530.446955304437, 5578.9163162553405, 5581.665100094007, 5587.636803607753, 5287.074761916572, 5325.627206095536, 5343.4389389113585, 5390.811321603725, 5391.62258641907], "value": 5170.533273821975, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.225988785196, 598.9191459153014, 605.8005149803389, 612.1307743004934, 615.4645458451148, 31802.20494487639, 32192.517112243306, 42308.99999999996, 42912.356314655226, 43038.784806076, 40886.38670419946, 44817.32774008384, 44846.77576612112, 44862.55137448633, 44938.775306123425, 9011.54942252888, 9153.405028673149, 9163.312751543628, 9166.816663666723, 9175.908240917572, 8975.281977441822, 9066.909330906705, 9072.591733372014, 9077.092290770903, 9085.954570227139, 9124.908750912473, 9142.268618510536, 9176.632934682604, 9178.173964343208, 9188.1730644242, 9073.13805188506, 9119.863202051974, 9122.08779122089, 9139.954300228523, 9145.085491450802, 8898.59956301968, 8939.553022348857, 8962.058983806672, 8967.461952282883, 8967.820643587156, 42729.78635106836, 42964.57035429637, 42983.78508107439, 43186.35220471695, 43210.135797284194, 3445.3109378124223, 3493.598236202844, 3534.6642069003406, 3662.359087159736, 4396.054848207651, 32684.67315326864, 33096.1725956853, 33111.0, 33621.831890840636, 33648.83175584131, 4325.286327755916, 4449.955500444987, 4463.732176069414, 4511.458624964992, 4676.7427791471455, 357368.0, 357848.0, 358259.0, 358459.2077039624, 359608.0, 56646.43353566494, 56674.43325566774, 56720.0, 57071.0, 57176.0, 60736.0, 60785.0, 60877.69561152166, 60913.39086609077, 60925.69537152375, 16754.162291885357, 17012.91493542549, 17047.550958168446, 17053.14734263282, 17063.23215455283, 13171.143875648053, 13388.93305533466, 13392.330383480787, 13412.329383530785, 13413.530526431528, 14133.0, 15985.600359991093, 16075.517734467985, 16181.514554563382, 16261.268242929096, 6219.502439804804, 6233.158523599347, 6340.112384266206, 6353.14232578596, 6431.356864313625, 815.9428839981144, 952.9689413779612, 1027.091024443373, 1105.4748994227677, 1130.112861403794, 32119.839400802848, 32641.51037734436, 32644.183895402806, 32739.50890736641, 33144.0, 985.8915519292872, 1086.163653986442, 1093.8195197792434, 1126.9943650281696, 1136.6249137784512, 16218.675626487404, 17251.223694933757, 17354.739678904825, 17460.82539174592, 17517.38689145899, 563956.0, 567865.0, 569095.0, 569668.0, 569762.0, 58319.41680583225, 58529.0, 58570.242892713286, 58592.0, 58796.706016469645, 1230.5508489401304, 1259.1878238536092, 1326.5754958413409, 1364.6520137364907, 1394.2889126545535, 29736.553951690308, 33631.0, 33636.49545256774, 33920.0, 33930.49104263388, 49533.752331238116, 49987.75006125092, 50114.0, 50193.24710129278, 50197.0, 374978.0, 376162.0, 376813.0, 376894.0, 377205.0, 27878.58182127229, 28702.0, 28948.565771513866, 30086.849565752033, 41338.0, 48119.0, 48281.51718482911, 49178.75410622924, 49397.0, 49897.0, 40908.386374203816, 41058.589414105474, 41359.793201035005, 42102.789486052374, 43782.34326485042, 41228.1754364905, 41639.0, 41951.37072943847, 43076.0, 43203.0, 41475.0, 42384.576154238064, 42639.1472170561, 42697.0, 42717.0, 35051.0, 37252.0, 37570.0, 37603.02146806481, 38043.80978095092, 27861.860690696416, 28087.88331526195, 28091.859540702164, 28295.575566366108, 28412.0], "value": 20111.16585984911, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.60821529179054, 394.8045717369902, 397.20360676842944, 400.5453809925734, 424.45669542984973, 16304.021758694471, 19477.3182938597, 19518.219271229133, 19594.510137246558, 19943.202271909144, 18981.715274270897, 18997.525061873424, 19461.124249408775, 20223.49441263969, 20242.190312387487, 5352.411234764173, 5682.431756824316, 5955.047192449209, 5993.4006599340155, 6000.399960004008, 5876.882462350751, 5941.85145371367, 5991.970040149793, 5995.040793473046, 5995.250593675791, 5715.599908006441, 5875.941240587604, 5880.235569375996, 5883.970580147094, 5979.46184843364, 5698.8290351289525, 5853.970730146343, 5892.734826932799, 5894.882102357972, 5895.498882594985, 5278.841634750964, 5313.521783039545, 5314.627976041678, 5316.9202461963105, 5317.468253174651, 23908.880455597784, 24865.875670621714, 24873.129440469496, 25029.374265643324, 25057.87471062633, 2494.189388448743, 2537.340291524217, 2570.640110384529, 2988.208124846917, 3043.45217860785, 14431.639209019859, 14602.415903363853, 14921.403143874231, 15144.848551514564, 15600.453984110614, 1914.5596512801994, 1918.5875036867, 1922.356010736405, 1966.9606607867763, 2011.1553147677864, 83595.58202209011, 88186.0, 90486.54756726175, 90888.09111908928, 93439.53280233555, 11189.328640281446, 11282.548698052151, 11339.319640821577, 11374.31754094757, 11462.66335715783, 15726.135062571668, 15833.762493562375, 15835.68328633421, 15877.52367428979, 16037.278322475517, 8291.917080829235, 8347.123552027071, 8389.0, 8506.914930850613, 8575.528345940907, 8089.878651820228, 8280.7578863171, 8281.917180828235, 8312.916870831214, 8545.060043395222, 4751.192297309466, 4848.612111031109, 4870.179075145052, 4880.641303866458, 4897.975510122426, 1856.378113332049, 1903.0009245146132, 1931.7295578619005, 1986.0466975851673, 2034.3490083173538, 877.8814859993819, 935.2564711054728, 996.2080146283722, 1022.2895087913977, 1029.8620024872557, 19892.10485528155, 20568.485787855123, 20617.58764824695, 20634.79365206359, 20670.79329206719, 950.3062764182048, 959.2709540749081, 996.5565323431149, 1008.5511947183433, 1020.882598501167, 11060.115190784718, 11432.31406115619, 11473.426328683532, 11501.482433290523, 11509.76980460386, 507021.0, 508335.0, 516374.0, 522506.0, 536502.0, 81356.0, 90749.54625226963, 98552.0, 99794.50102749439, 100007.49996249973, 985.556499575194, 997.4987643596342, 1010.6058637131524, 1021.5454122915231, 1031.1235449867552, 17945.371911983017, 18298.176582054104, 18819.341323054174, 18866.434006979813, 19267.710984334964, 42679.3598096035, 42861.0, 43367.0, 45262.321065184704, 45459.0, 59745.0, 60449.39550604438, 60478.697606513444, 61822.38177618166, 62443.6877815608, 5998.7502603624725, 8570.100139485265, 8833.911660883567, 8892.110788921056, 9195.494247816434, 12221.511139554319, 13452.932735336652, 13635.727285454037, 13667.248301343521, 14013.089149205269, 11107.222494425312, 11537.423128843357, 11966.46150923193, 12029.699257518632, 12032.819507707216, 11327.773444531229, 11432.37121958267, 11626.306550706311, 11748.530058797895, 11790.823137652771, 11739.473868397054, 11887.762244754884, 11969.760604788029, 12015.75968480608, 12071.517139314328, 7200.8919866201, 7721.382289416721, 7817.218278172138, 8703.43427677199, 8871.245944094824, 8529.786755330919, 11312.773744525228, 11435.142364322875, 11670.766584668429, 11848.703782405164], "value": 9519.661584346788, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.8412874583796, 1492.6193820575752, 1638.737801951688, 1769.2922830867644, 1824.3797108982942, 8610.268127209187, 9285.535723213847, 9288.628454861797, 9300.534973251344, 9304.0, 7887.250711182429, 8142.6335814888325, 8149.511029338244, 8149.714759983402, 9064.864027039599, 2262.2082271205113, 2356.2577787996725, 2360.327306717586, 2380.571497130516, 2381.4760752634393, 2322.63999080142, 2367.5264947010583, 2381.071382160959, 2384.642303654457, 2388.0, 2364.397078744926, 2365.3377054424705, 2367.2069856597973, 2368.100121953664, 2372.789877162651, 2315.907363705458, 2353.52929414117, 2358.8466749661216, 2359.3629719975684, 2367.9052837886466, 2259.1302348595746, 2261.434641339665, 2318.7681231876845, 2321.0483701682397, 2326.592846251899, 8933.330000249942, 9005.594748236344, 9048.638054477813, 9058.365914385995, 9079.546022698838, 5129.358830146231, 5264.947350526484, 5267.947320526784, 5271.841844744664, 5277.9472205278225, 3371.123507887942, 3443.5695538057744, 3463.1342164458883, 3508.2106526031694, 3513.0, 1038.4600007995875, 1087.7715679707271, 1108.1467270201979, 1111.572044762764, 1131.796276670199, 12462.875371246353, 12497.437615307243, 12520.812187817099, 12531.874681253163, 12541.435635396338, 4715.929261061086, 4760.952390476051, 4842.297866809293, 4846.055019271208, 4866.343043689128, 9863.260255480867, 10037.849432258521, 10055.949720251352, 10059.647912323033, 10066.748331291776, 5343.251944727741, 5346.732663366816, 5347.331583552056, 5354.678719276778, 5357.249985002103, 4401.977990110093, 4875.293082503088, 4916.70499770015, 5027.547520723079, 5035.546800787946, 3650.3976843821, 3750.868719594801, 3777.0934975606206, 3797.6392242736897, 3845.442410850411, 1171.7305019845483, 1175.76484703058, 1186.3652945674023, 1191.362620997762, 1193.773183095209, 931.7530854323475, 976.8534719792088, 978.5303054533864, 978.6623614852864, 993.7515621094726, 5905.232319798399, 5947.37552556984, 5972.671503067286, 6083.209182806296, 6125.418085281891, 922.7646950027699, 957.1577012229194, 987.8073775613686, 994.945278009702, 1009.9545520451597, 4106.91786164281, 4181.330987042059, 4213.47331583552, 4214.262504061839, 4282.272013757669, 10950.671479855617, 11129.276597021168, 11197.0, 11201.383923884265, 11368.0, 11317.943410282896, 11335.206535542433, 11343.716407089889, 11343.886561134283, 11347.943260283811, 8028.237317454832, 8260.752177434686, 8334.833303333899, 8389.07720150783, 8533.104024077382, 5940.940590594038, 6014.879702406015, 6029.457348838626, 6092.634441933498, 6229.314775374614, 10217.0, 10324.174066074846, 10338.328008679411, 10352.741181470523, 10477.52851121686, 10421.426821524585, 10718.196135289729, 10719.088877445505, 10728.73178170552, 10742.62400815999, 7053.4004609608755, 7787.415943804124, 7902.0912595052305, 7928.246816552418, 7928.722494712657, 8399.202075802788, 8640.395172337876, 8719.476831390137, 8743.519106449206, 8747.562621868754, 7566.054243219598, 7645.235476452311, 7679.500832445873, 7694.07671079474, 7907.802304942423, 7598.96200519016, 7606.885896711665, 7656.4640475168435, 7748.65131069092, 7767.339776119094, 7093.503454758324, 7202.099737532809, 7202.243764404662, 7207.1711753146965, 7211.495195336276, 5012.248162775612, 5047.646664733434, 5072.82245121419, 5154.072266991978, 5171.50870667286, 4890.853274401774, 5107.9489205107475, 5142.151544995034, 5178.508041736029, 5310.336207974004], "value": 4682.599493554094, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.7367958948745, 339.5500961226375, 345.5507839808249, 349.6346318097586, 351.15371953591847, 5295.232191332255, 5384.461553844613, 5399.973000134999, 5402.70285134317, 5444.428335024825, 5043.899122017558, 5170.474709960561, 5179.14544100224, 5196.92204616931, 5353.678779273237, 4051.979740101295, 4080.816363263653, 4086.1827634473075, 4173.5409104998425, 4191.413202151702, 4136.503619565657, 4142.1922725068625, 4146.751194928314, 4147.979260103695, 4168.8332466701295, 4072.3891416287497, 4109.725984944662, 4131.876043718693, 4141.62725354718, 4169.541350451448, 4130.896727581834, 4130.946608614814, 4143.8342466301465, 4144.8756537303925, 4145.233131870584, 3662.9926770138427, 3670.2292518570866, 3670.8164591770305, 3686.686631636314, 3687.7418580699364, 4990.925136122961, 5120.487951204887, 5142.07442660321, 5154.407243167008, 5163.251328557376, 4681.291328665046, 4690.771883355413, 4707.882302942419, 4708.952910470885, 4729.9763501182615, 5439.211314359396, 5446.319210098738, 5453.727313634303, 5455.318085239345, 5462.371827239839, 3942.8225729842225, 4022.0146064214164, 4023.456833327522, 4024.6981476389096, 4032.737872038308, 5601.831945041656, 5619.241402410663, 5620.5503559715125, 5625.240592519998, 5709.457601527848, 4080.163566468867, 4094.28350038742, 4100.2414553307735, 4106.856260030884, 4108.938365924513, 4096.467459230281, 4187.392828039917, 4194.874153775391, 4194.874153775391, 4209.810558524874, 4626.236670949323, 4646.163690535736, 4680.95319046812, 4714.528547145258, 4723.378524918635, 5444.0, 5444.727763611803, 5452.536534394541, 5453.291072160673, 5457.28095649869, 1353.1505045855658, 1426.436557559758, 1449.2174225918097, 1452.4553292515307, 1452.5039209614115, 927.1609193679731, 963.8409662405764, 1014.3710899242517, 1018.5671089786811, 1019.6125472320471, 5152.1148157032185, 5159.819406320761, 5203.323567936144, 5207.921881171786, 5217.921731174036, 5489.737360407049, 5513.807016754395, 5525.944740552623, 5550.888982220414, 5553.361363443256, 5056.595472362275, 5188.584913206934, 5225.6603320784025, 5227.372715274116, 5229.6339256251695, 2961.126467692036, 2963.555466679972, 2971.7919745618033, 2973.1675130963363, 2974.4051189761703, 5543.556515478752, 5735.512481439125, 5746.913796293059, 5777.653340799481, 5803.593748437654, 5566.749496272678, 5572.86067848307, 5601.775928962796, 5612.719364031782, 5634.295713035871, 5715.942840571624, 5731.426857314235, 5778.191053252548, 5819.6217245879725, 5825.165073001991, 5383.838484845461, 5615.241942337732, 5615.248528221061, 5667.826017430252, 5689.573282003784, 5555.888822235488, 5563.527100196528, 5578.2748242728185, 5597.608167428241, 5610.5231055360755, 5694.829155125353, 5849.5612829037145, 5894.793682221273, 5905.261842269716, 5940.485176280194, 4035.0, 4055.229506393776, 4059.4722686050623, 4062.4515690382605, 4072.185562887375, 5408.513233808976, 5581.134924086773, 5597.692126933058, 5608.747606357642, 5632.6338787980285, 5465.808696695596, 5475.534579560782, 5522.5581953442825, 5560.721963901708, 5612.0, 5264.236685680631, 5530.806421775219, 5531.0, 5537.307836520435, 5542.11326187808, 5418.972905135449, 5434.809781657781, 5483.259759932358, 5495.505404513613, 5500.477454641803, 5554.694491802989, 5567.443255674562, 5570.0, 5591.944080559305, 5593.440655934537, 5218.8956220876125, 5453.182022696468, 5471.452854714496, 5476.3702174250475, 5493.340799104133], "value": 4217.364150061084, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.1683471416943, 704.144464475662, 766.7546385156752, 775.8215610409605, 793.3811626930996, 15394.538163855073, 16722.916385418055, 16768.83231167688, 16770.664586708273, 16793.91603041983, 14561.0, 14629.634259143526, 14670.0, 14817.407303707865, 14901.701965960674, 639.3223183425583, 752.5183882315323, 768.8077980504873, 773.9032620922385, 778.1984555907396, 778.0313509680427, 780.6760194519268, 781.4646966827713, 787.2167193642306, 787.2560430393281, 841.877927700483, 844.0546587821618, 845.8392905347995, 846.0397448895486, 846.5386364431369, 682.3142741544741, 808.1433680298902, 820.7989042684521, 821.0926925747024, 844.8648216285395, 602.0908428273295, 602.1780269931555, 602.1990752299431, 602.8191542537221, 603.0983679399283, 15354.769678454832, 15378.846211537853, 15388.538343849703, 15424.845751542565, 15473.845261547352, 14152.716945661132, 14196.290185490787, 14321.92585556077, 14443.927780361137, 14573.12561246318, 12937.870621293761, 12942.275135710157, 13177.077604567685, 13243.404046817916, 13332.200067995855, 4039.454673619053, 4049.6152865477734, 4180.540140584533, 4193.03560181157, 4208.9789551052045, 81309.73363453589, 82244.0, 82407.17592824055, 83857.0, 84155.0, 11521.711957201138, 11552.94223528877, 11632.592859249886, 11700.414979251003, 11733.589324373777, 20452.181912723623, 20624.0, 20944.790552094284, 21025.15899364008, 21155.894220529106, 8395.118512556093, 8401.915980840113, 8409.747707568782, 8493.617787199591, 8721.433106848035, 4750.192467280571, 4799.3760811095035, 4810.95189048112, 4916.164252077084, 4934.432540257844, 3400.914977125542, 3499.9650003499637, 3500.4749287607065, 3500.9474857877153, 3506.2461570762407, 587.8354060862965, 589.9882002359928, 590.5246276747174, 597.1520440973827, 597.5787070115558, 511.93088932993567, 514.7966553211461, 567.4268988321825, 679.4835924697265, 698.9405900498515, 9084.40951338161, 9454.763630909283, 9538.14156725898, 9732.993961171916, 9836.409815411098, 596.0552524249114, 639.4692405303587, 644.4103645164622, 677.0949168458328, 693.9167299924042, 4554.863354099382, 4580.976278501188, 4688.367070445514, 4729.243321068612, 4746.857594272177, 125306.0, 125394.0, 128079.0, 128521.0, 132022.0, 114667.42666286616, 114954.0, 116320.41839790747, 118534.0, 120402.3979880095, 95328.52335738415, 96031.0, 100010.4999474998, 101941.49029254806, 102142.4892875531, 8816.260503572277, 9171.587278572613, 9204.125608067223, 9208.585613647268, 9212.447253164832, 55788.44211557995, 56044.43955560392, 56920.715396422755, 57030.7148464255, 57974.13038804337, 77851.22148778666, 83966.16033839827, 84653.57673211841, 84758.57620711857, 87435.56282218549, 11678.88321116778, 11908.702282443008, 12141.332226727614, 12507.74984500323, 12517.687057823627, 12265.754684906073, 12394.256344619353, 12507.687307817378, 12516.12387132892, 13263.66840828987, 11345.659630211107, 11388.14588905852, 11640.243384180001, 11953.940230298793, 12015.09886758514, 11641.6507504775, 11839.526418943491, 11926.284422934652, 12033.939830300793, 12174.269543827399, 11821.82267266009, 12023.75952480963, 12276.447559860006, 12388.62834114978, 12466.065045121471, 8659.13408659129, 8852.070532593985, 9008.0, 9457.10157535033, 9510.667126650535, 10177.0, 10219.897801021894, 10261.871194168785, 10590.417527036087, 12021.158518903592], "value": 7444.779789902248, "unit": "score"}], "name": "dromaeo_css", "value": 7252.760571434121, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 114.0, 111.0, 113.0, 112.0, 112.0, 112.0, 111.0, 112.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 121.0, 119.0, 191.0, 118.0, 118.0, 142.0, 117.0, 168.0, 117.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 160.0, 160.0, 160.0, 160.0, 160.0, 161.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 108.0, 75.0, 75.0, 74.0, 75.0, 105.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 120.0, 83.0, 84.0, 83.0, 113.0, 83.0, 83.0, 134.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 118.0, 117.0, 117.0, 117.0, 116.0, 117.0, 119.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 53.0, 51.0, 51.0, 51.0, 52.0, 50.0, 50.0, 50.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 63.0, 63.0, 61.0, 62.0, 62.0, 62.0, 62.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 75.0, 75.0, 76.0, 75.0, 75.0, 75.0, 85.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 130.0, 119.0, 121.0, 120.0, 127.0, 120.0, 120.0, 143.0, 129.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 155.0, 156.0, 164.0, 158.0, 160.0, 160.0, 157.0, 164.0], "value": 159.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 63.0, 64.0, 64.0, 70.0, 63.0, 62.0, 63.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1478.3000000000002, "alertThreshold": 2.0}]} 17:50:01 INFO - Return code: 0 17:50:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:50:01 INFO - # TBPL SUCCESS # 17:50:01 INFO - Running post-action listener: _resource_record_post_action 17:50:01 INFO - [mozharness: 2016-08-05 00:50:01.989627Z] Finished run-tests step (success) 17:50:01 INFO - Running post-run listener: _resource_record_post_run 17:50:02 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 557453312; Read time: 12; Write time: 637888 17:50:02 INFO - TinderboxPrint: CPU usage
13.0% 17:50:02 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:50:02 INFO - TinderboxPrint: I/O write bytes / time
557,453,312 / 637,888 17:50:02 INFO - TinderboxPrint: CPU idle
8,276.1 (87.0%) 17:50:02 INFO - TinderboxPrint: CPU user
1,193.9 (12.5%) 17:50:02 INFO - TinderboxPrint: Swap in / out
0 / 0 17:50:02 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 259612672; Read time: 0; Write time: 307536 17:50:02 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 292954112; Read time: 0; Write time: 329476 17:50:02 INFO - Running post-run listener: _upload_blobber_files 17:50:02 INFO - Blob upload gear active. 17:50:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:50:02 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 17:50:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:50:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:50:03 INFO - (blobuploader) - INFO - Open directory for files ... 17:50:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:50:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:50:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:03 INFO - (blobuploader) - INFO - Done attempting. 17:50:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:50:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:50:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:04 INFO - (blobuploader) - INFO - Done attempting. 17:50:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:50:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:50:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:08 INFO - (blobuploader) - INFO - Done attempting. 17:50:08 INFO - (blobuploader) - INFO - Iteration through files over. 17:50:08 INFO - Return code: 0 17:50:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:50:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:50:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d705ebc69a7f1841fe778d3803857d08e452f0375f03de1b88b71e9db32f3de62b9b0075113f4dcc6d1dd0bd9cef5b0ae06910a355205da5de12ed4308e20767", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a75b51e0a471070f338e477ee2ee81d5077fb98691fee6b13bbde823c7e1fb9737a832c0da3dd788e3e75240378372e74fbed96b14efbf94f71a88dea118711d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24ed4a3c3c98827759558eb87ce472ad115a7530719797ddff45b260dff3c068989f9ad0aefdaaa64bc75e4b49d97c586d4ff0cd0c9196cb01d03182b91d3ae0"} 17:50:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:50:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:50:08 INFO - Contents: 17:50:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d705ebc69a7f1841fe778d3803857d08e452f0375f03de1b88b71e9db32f3de62b9b0075113f4dcc6d1dd0bd9cef5b0ae06910a355205da5de12ed4308e20767", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a75b51e0a471070f338e477ee2ee81d5077fb98691fee6b13bbde823c7e1fb9737a832c0da3dd788e3e75240378372e74fbed96b14efbf94f71a88dea118711d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24ed4a3c3c98827759558eb87ce472ad115a7530719797ddff45b260dff3c068989f9ad0aefdaaa64bc75e4b49d97c586d4ff0cd0c9196cb01d03182b91d3ae0"} 17:50:08 INFO - Running post-run listener: copy_logs_to_upload_dir 17:50:08 INFO - Copying logs to upload dir... 17:50:08 INFO - mkdir: /builds/slave/test/build/upload/logs 17:50:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.036608 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-08-04 17:50:08.279749) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 17:50:08.284074) ========= 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=62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d705ebc69a7f1841fe778d3803857d08e452f0375f03de1b88b71e9db32f3de62b9b0075113f4dcc6d1dd0bd9cef5b0ae06910a355205da5de12ed4308e20767", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a75b51e0a471070f338e477ee2ee81d5077fb98691fee6b13bbde823c7e1fb9737a832c0da3dd788e3e75240378372e74fbed96b14efbf94f71a88dea118711d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24ed4a3c3c98827759558eb87ce472ad115a7530719797ddff45b260dff3c068989f9ad0aefdaaa64bc75e4b49d97c586d4ff0cd0c9196cb01d03182b91d3ae0"} build_url:https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011398 build_url: 'https://queue.taskcluster.net/v1/task/Pbuz5S7lTSu88t5EcIoUmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d705ebc69a7f1841fe778d3803857d08e452f0375f03de1b88b71e9db32f3de62b9b0075113f4dcc6d1dd0bd9cef5b0ae06910a355205da5de12ed4308e20767", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a75b51e0a471070f338e477ee2ee81d5077fb98691fee6b13bbde823c7e1fb9737a832c0da3dd788e3e75240378372e74fbed96b14efbf94f71a88dea118711d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24ed4a3c3c98827759558eb87ce472ad115a7530719797ddff45b260dff3c068989f9ad0aefdaaa64bc75e4b49d97c586d4ff0cd0c9196cb01d03182b91d3ae0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 17:50:08.314465) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 17:50:08.315068) ========= 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=62c568580acbfdaac3eec7be000001dc-1470356792.558739-1297433850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004534 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 17:50:08.405216) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 17:50:08.405873) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 17:50:08.406535) ========= ========= Total master_lag: 0.20 =========