builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1471608710.89 results: success (0) buildid: 20160819023002 builduid: 7b3c542b86a04654b5c217a911bc81fd revision: eaff4fe1c6194cc73476d14b4faff2f355bb64b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:50.885423) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:50.892787) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:50.893156) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:50.927606) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:50.927868) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-19 05:11:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 840K=0.01s 2016-08-19 05:11:51 (840 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.226331 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:51.169641) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:51.169963) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019494 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:51.219611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:51.219995) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev eaff4fe1c6194cc73476d14b4faff2f355bb64b0 --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 eaff4fe1c6194cc73476d14b4faff2f355bb64b0 --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=e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-19 05:11:51,481 truncating revision to first 12 chars 2016-08-19 05:11:51,481 Setting DEBUG logging. 2016-08-19 05:11:51,481 attempt 1/10 2016-08-19 05:11:51,481 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/eaff4fe1c619?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 05:11:51,792 unpacking tar archive at: fx-team-eaff4fe1c619/testing/mozharness/ program finished with exit code 0 elapsedTime=0.703394 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:51.940480) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:51.941094) ========= script_repo_revision: eaff4fe1c6194cc73476d14b4faff2f355bb64b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:51.941781) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:51.942285) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 05:11:51.968329) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-08-19 05:11:51.968973) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:11:52 INFO - MultiFileLogger online at 20160819 05:11:52 in /builds/slave/test 05:11:52 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 05:11:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:11:52 INFO - {'append_to_log': False, 05:11:52 INFO - 'base_work_dir': '/builds/slave/test', 05:11:52 INFO - 'blob_upload_branch': 'Fx-Team', 05:11:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:11:52 INFO - 'branch': 'Fx-Team', 05:11:52 INFO - 'buildbot_json_path': 'buildprops.json', 05:11:52 INFO - 'config_files': ('talos/linux_config.py',), 05:11:52 INFO - 'default_actions': ('clobber', 05:11:52 INFO - 'read-buildbot-config', 05:11:52 INFO - 'download-and-extract', 05:11:52 INFO - 'populate-webroot', 05:11:52 INFO - 'create-virtualenv', 05:11:52 INFO - 'install', 05:11:52 INFO - 'run-tests'), 05:11:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:11:52 INFO - 'download_minidump_stackwalk': True, 05:11:52 INFO - 'download_symbols': 'ondemand', 05:11:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:11:52 INFO - 'tooltool.py': '/tools/tooltool.py', 05:11:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:11:52 INFO - '/tools/misc-python/virtualenv.py')}, 05:11:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:11:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:11:52 INFO - 'installer_path': 'installer.exe', 05:11:52 INFO - 'log_level': 'info', 05:11:52 INFO - 'log_name': 'talos', 05:11:52 INFO - 'log_to_console': True, 05:11:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:11:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:11:52 INFO - 'opt_config_files': (), 05:11:52 INFO - 'pip_index': False, 05:11:52 INFO - 'sps_profile': False, 05:11:52 INFO - 'sps_profile_interval': 0, 05:11:52 INFO - 'suite': 'dromaeojs', 05:11:52 INFO - 'system_bits': '32', 05:11:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:11:52 INFO - 'title': 'talos-linux64-ix-006', 05:11:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:11:52 INFO - 'use_talos_json': True, 05:11:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:11:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:11:52 INFO - 'work_dir': 'build'} 05:11:52 INFO - [mozharness: 2016-08-19 12:11:52.335454Z] Running clobber step. 05:11:52 INFO - Running pre-action listener: _resource_record_pre_action 05:11:52 INFO - Running main action method: clobber 05:11:52 INFO - rmtree: /builds/slave/test/build 05:11:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:11:52 INFO - Running post-action listener: _resource_record_post_action 05:11:52 INFO - [mozharness: 2016-08-19 12:11:52.791152Z] Finished clobber step (success) 05:11:52 INFO - [mozharness: 2016-08-19 12:11:52.791254Z] Running read-buildbot-config step. 05:11:52 INFO - Running pre-action listener: _resource_record_pre_action 05:11:52 INFO - Running main action method: read_buildbot_config 05:11:52 INFO - Using buildbot properties: 05:11:52 INFO - { 05:11:52 INFO - "project": "", 05:11:52 INFO - "product": "firefox", 05:11:52 INFO - "script_repo_revision": "production", 05:11:52 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:11:52 INFO - "repository": "", 05:11:52 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:11:52 INFO - "buildid": "20160819023002", 05:11:52 INFO - "pgo_build": "True", 05:11:52 INFO - "basedir": "/builds/slave/test", 05:11:52 INFO - "buildnumber": 995, 05:11:52 INFO - "slavename": "talos-linux64-ix-006", 05:11:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:11:52 INFO - "platform": "ubuntu64_hw", 05:11:52 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:11:52 INFO - "branch": "fx-team", 05:11:52 INFO - "repo_path": "integration/fx-team", 05:11:52 INFO - "revision": "eaff4fe1c6194cc73476d14b4faff2f355bb64b0", 05:11:52 INFO - "stage_platform": "linux64-pgo", 05:11:52 INFO - "builduid": "7b3c542b86a04654b5c217a911bc81fd", 05:11:52 INFO - "slavebuilddir": "test" 05:11:52 INFO - } 05:11:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:11:52 INFO - Running post-action listener: _resource_record_post_action 05:11:52 INFO - [mozharness: 2016-08-19 12:11:52.793699Z] Finished read-buildbot-config step (success) 05:11:52 INFO - [mozharness: 2016-08-19 12:11:52.793788Z] Running download-and-extract step. 05:11:52 INFO - Running pre-action listener: _resource_record_pre_action 05:11:52 INFO - Running main action method: download_and_extract 05:11:52 INFO - mkdir: /builds/slave/test/build/tests 05:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:52 INFO - trying https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:11:52 INFO - Downloading https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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 05:11:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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 05:11:53 INFO - Downloaded 1361 bytes. 05:11:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:11:53 INFO - Using the following test package requirements: 05:11:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:11:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:11:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:11:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:53 INFO - u'jsshell-linux-x86_64.zip'], 05:11:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:11:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:11:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:11:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:11:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:11:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:11:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:11: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')]} 05:11:53 INFO - trying https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:11:53 INFO - Downloading https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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 05:11:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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 05:11:54 INFO - Downloaded 21907509 bytes. 05:11:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 05:11:58 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 05:11: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')]} 05:11:58 INFO - trying https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:11:58 INFO - Downloading https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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 05:11:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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 05:11:59 INFO - Downloaded 21907509 bytes. 05:11:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 05:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:04 INFO - trying https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:12:04 INFO - Downloading https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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 05:12:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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 05:12:04 INFO - Downloaded 14413981 bytes. 05:12:04 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 05:12: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')]} 05:12:05 INFO - trying https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:12:05 INFO - Downloading https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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 05:12:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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 05:12:06 INFO - Downloaded 59617999 bytes. 05:12:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:12:06 INFO - mkdir: /builds/slave/test/properties 05:12:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:12:06 INFO - Writing to file /builds/slave/test/properties/build_url 05:12:06 INFO - Contents: 05:12:06 INFO - build_url:https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:12:07 INFO - Running post-action listener: _resource_record_post_action 05:12:07 INFO - Running post-action listener: set_extra_try_arguments 05:12:07 INFO - [mozharness: 2016-08-19 12:12:07.338325Z] Finished download-and-extract step (success) 05:12:07 INFO - [mozharness: 2016-08-19 12:12:07.338550Z] Running populate-webroot step. 05:12:07 INFO - Running pre-action listener: _resource_record_pre_action 05:12:07 INFO - Running main action method: populate_webroot 05:12:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:12:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:12:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:12:07 INFO - '--tppagecycles', 05:12:07 INFO - '7'], 05:12:07 INFO - 'tests': ['tsvgm']}}, 05:12:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:12:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:12:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:12:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:12:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:07 INFO - 'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:07 INFO - 'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['damp', 'tps']}, 05:12:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:07 INFO - 'tests': ['damp', 'tps']}, 05:12:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['dromaeo_dom']}, 05:12:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:12:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['basic_compositor_video']}, 05:12:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:12:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['a11yr', 05:12:07 INFO - 'ts_paint', 05:12:07 INFO - 'tpaint', 05:12:07 INFO - 'sessionrestore', 05:12:07 INFO - 'sessionrestore_no_auto_restore', 05:12:07 INFO - 'tabpaint']}, 05:12:07 INFO - 'other-e10s': {'tests': ['a11yr', 05:12:07 INFO - 'ts_paint', 05:12:07 INFO - 'tpaint', 05:12:07 INFO - 'sessionrestore', 05:12:07 INFO - 'sessionrestore_no_auto_restore', 05:12:07 INFO - 'tabpaint']}, 05:12:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:12:07 INFO - 'ts_paint', 05:12:07 INFO - 'tpaint', 05:12:07 INFO - 'sessionrestore', 05:12:07 INFO - 'sessionrestore_no_auto_restore', 05:12:07 INFO - 'tabpaint']}, 05:12:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:12:07 INFO - 'ts_paint', 05:12:07 INFO - 'tpaint', 05:12:07 INFO - 'sessionrestore', 05:12:07 INFO - 'sessionrestore_no_auto_restore', 05:12:07 INFO - 'tabpaint']}, 05:12:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['a11yr', 05:12:07 INFO - 'ts_paint', 05:12:07 INFO - 'tpaint', 05:12:07 INFO - 'sessionrestore', 05:12:07 INFO - 'sessionrestore_no_auto_restore', 05:12:07 INFO - 'tabpaint']}, 05:12:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['a11yr', 05:12:07 INFO - 'ts_paint', 05:12:07 INFO - 'tpaint', 05:12:07 INFO - 'sessionrestore', 05:12:07 INFO - 'sessionrestore_no_auto_restore', 05:12:07 INFO - 'tabpaint']}, 05:12:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['tsvgx', 05:12:07 INFO - 'tsvgr_opacity', 05:12:07 INFO - 'tart', 05:12:07 INFO - 'tscrollx', 05:12:07 INFO - 'cart']}, 05:12:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:12:07 INFO - 'tsvgr_opacity', 05:12:07 INFO - 'tart', 05:12:07 INFO - 'tscrollx', 05:12:07 INFO - 'cart']}, 05:12:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:07 INFO - 'talos_options': ['--disable-e10s'], 05:12:07 INFO - 'tests': ['tp5o']}, 05:12:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:07 INFO - 'tests': ['tp5o']}, 05:12:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:07 INFO - 'talos_options': ['--disable-e10s', 05:12:07 INFO - '--xperf_path', 05:12:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:07 INFO - 'tests': ['tp5n']}, 05:12:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:07 INFO - 'talos_options': ['--xperf_path', 05:12:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:07 INFO - 'tests': ['tp5n']}}, 05:12:07 INFO - 'talos.zip': {'path': '', 05:12:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:12:07 INFO - Running post-action listener: _resource_record_post_action 05:12:07 INFO - [mozharness: 2016-08-19 12:12:07.356265Z] Finished populate-webroot step (success) 05:12:07 INFO - [mozharness: 2016-08-19 12:12:07.356353Z] Running create-virtualenv step. 05:12:07 INFO - Running pre-action listener: _resource_record_pre_action 05:12:07 INFO - Running main action method: create_virtualenv 05:12:07 INFO - Creating virtualenv /builds/slave/test/build/venv 05:12:07 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 05:12:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:12:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:12:07 INFO - Using real prefix '/usr' 05:12:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:12:07 INFO - Installing distribute.............................................................................................................................................................................................done. 05:12:09 INFO - Installing pip.................done. 05:12:09 INFO - Return code: 0 05:12:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:12: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')]} 05:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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')]} 05:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4dafdb9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', '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 05:12: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', 'pip>=1.5'] in /builds/slave/test/build 05:12: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 pip>=1.5 05:12:09 INFO - Using env: {'DISPLAY': ':0', 05:12:09 INFO - 'HOME': '/home/cltbld', 05:12:09 INFO - 'LANG': 'en_US.UTF-8', 05:12:09 INFO - 'LANGUAGE': 'en_US:en', 05:12:09 INFO - 'LOGNAME': 'cltbld', 05:12:09 INFO - 'MAIL': '/var/mail/cltbld', 05:12:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:09 INFO - 'MOZ_NO_REMOTE': '1', 05:12:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:09 INFO - 'NO_EM_RESTART': '1', 05:12:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:09 INFO - 'PWD': '/builds/slave/test', 05:12:09 INFO - 'SHELL': '/bin/bash', 05:12:09 INFO - 'SHLVL': '1', 05:12:09 INFO - 'TERM': 'linux', 05:12:09 INFO - 'TMOUT': '86400', 05:12:09 INFO - 'USER': 'cltbld', 05:12:09 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:09 INFO - '_': '/tools/buildbot/bin/python'} 05:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:10 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 05:12:10 INFO - Cleaning up... 05:12:10 INFO - Return code: 0 05:12:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:12:10 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')]} 05:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:10 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')]} 05:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:10 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 0x7f4dafdb9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', '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 05:12:10 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 05:12:10 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 05:12:10 INFO - Using env: {'DISPLAY': ':0', 05:12:10 INFO - 'HOME': '/home/cltbld', 05:12:10 INFO - 'LANG': 'en_US.UTF-8', 05:12:10 INFO - 'LANGUAGE': 'en_US:en', 05:12:10 INFO - 'LOGNAME': 'cltbld', 05:12:10 INFO - 'MAIL': '/var/mail/cltbld', 05:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:10 INFO - 'MOZ_NO_REMOTE': '1', 05:12:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:10 INFO - 'NO_EM_RESTART': '1', 05:12:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:10 INFO - 'PWD': '/builds/slave/test', 05:12:10 INFO - 'SHELL': '/bin/bash', 05:12:10 INFO - 'SHLVL': '1', 05:12:10 INFO - 'TERM': 'linux', 05:12:10 INFO - 'TMOUT': '86400', 05:12:10 INFO - 'USER': 'cltbld', 05:12:10 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:10 INFO - '_': '/tools/buildbot/bin/python'} 05:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:10 INFO - Downloading/unpacking psutil>=3.1.1 05:12:10 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 05:12:10 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 05:12:10 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 05:12:10 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 05:12:10 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 05:12:10 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 05:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:12:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:12 INFO - Installing collected packages: psutil 05:12:12 INFO - Running setup.py install for psutil 05:12:12 INFO - building 'psutil._psutil_linux' extension 05:12:12 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 05:12:12 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 05:12:12 INFO - building 'psutil._psutil_posix' extension 05:12:12 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 05:12:12 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 05:12:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:12 INFO - Successfully installed psutil 05:12:12 INFO - Cleaning up... 05:12:12 INFO - Return code: 0 05:12:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:12 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 0x7f4dafdb9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', '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 05:12:12 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 05:12:12 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 05:12:12 INFO - Using env: {'DISPLAY': ':0', 05:12:12 INFO - 'HOME': '/home/cltbld', 05:12:12 INFO - 'LANG': 'en_US.UTF-8', 05:12:12 INFO - 'LANGUAGE': 'en_US:en', 05:12:12 INFO - 'LOGNAME': 'cltbld', 05:12:12 INFO - 'MAIL': '/var/mail/cltbld', 05:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:12 INFO - 'MOZ_NO_REMOTE': '1', 05:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:12 INFO - 'NO_EM_RESTART': '1', 05:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:12 INFO - 'PWD': '/builds/slave/test', 05:12:12 INFO - 'SHELL': '/bin/bash', 05:12:12 INFO - 'SHLVL': '1', 05:12:12 INFO - 'TERM': 'linux', 05:12:12 INFO - 'TMOUT': '86400', 05:12:12 INFO - 'USER': 'cltbld', 05:12:12 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:12 INFO - '_': '/tools/buildbot/bin/python'} 05:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:12:13 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 05:12:13 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 05:12:13 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 05:12:13 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 05:12:13 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 05:12:13 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 05:12:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:12:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:12:15 INFO - Installing collected packages: mozsystemmonitor 05:12:15 INFO - Running setup.py install for mozsystemmonitor 05:12:15 INFO - Successfully installed mozsystemmonitor 05:12:15 INFO - Cleaning up... 05:12:15 INFO - Return code: 0 05:12:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4dafdb9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', '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 05:12:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:12:15 INFO - Using env: {'DISPLAY': ':0', 05:12:15 INFO - 'HOME': '/home/cltbld', 05:12:15 INFO - 'LANG': 'en_US.UTF-8', 05:12:15 INFO - 'LANGUAGE': 'en_US:en', 05:12:15 INFO - 'LOGNAME': 'cltbld', 05:12:15 INFO - 'MAIL': '/var/mail/cltbld', 05:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:15 INFO - 'MOZ_NO_REMOTE': '1', 05:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:15 INFO - 'NO_EM_RESTART': '1', 05:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:15 INFO - 'PWD': '/builds/slave/test', 05:12:15 INFO - 'SHELL': '/bin/bash', 05:12:15 INFO - 'SHLVL': '1', 05:12:15 INFO - 'TERM': 'linux', 05:12:15 INFO - 'TMOUT': '86400', 05:12:15 INFO - 'USER': 'cltbld', 05:12:15 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:15 INFO - '_': '/tools/buildbot/bin/python'} 05:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:15 INFO - Downloading/unpacking jsonschema==2.5.1 05:12:15 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 05:12:15 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 05:12:15 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 05:12:15 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 05:12:15 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 05:12:15 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 05:12:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:12:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:12:17 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 05:12:17 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 05:12:17 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 05:12:17 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 05:12:17 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 05:12:17 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 05:12:17 INFO - Downloading functools32-3.2.3-2.tar.gz 05:12:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:12:17 INFO - warning: no files found matching '*.txt' 05:12:17 INFO - no previously-included directories found matching 'build' 05:12:17 INFO - no previously-included directories found matching 'dist' 05:12:17 INFO - no previously-included directories found matching '.git*' 05:12:17 INFO - Installing collected packages: jsonschema, functools32 05:12:17 INFO - Running setup.py install for functools32 05:12:17 INFO - warning: no files found matching '*.txt' 05:12:17 INFO - no previously-included directories found matching 'build' 05:12:17 INFO - no previously-included directories found matching 'dist' 05:12:17 INFO - no previously-included directories found matching '.git*' 05:12:17 INFO - Successfully installed jsonschema functools32 05:12:17 INFO - Cleaning up... 05:12:17 INFO - Return code: 0 05:12:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:17 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 0x7f4dafdb9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', '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 05:12:17 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 05:12:17 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 05:12:17 INFO - Using env: {'DISPLAY': ':0', 05:12:17 INFO - 'HOME': '/home/cltbld', 05:12:17 INFO - 'LANG': 'en_US.UTF-8', 05:12:17 INFO - 'LANGUAGE': 'en_US:en', 05:12:17 INFO - 'LOGNAME': 'cltbld', 05:12:17 INFO - 'MAIL': '/var/mail/cltbld', 05:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:17 INFO - 'MOZ_NO_REMOTE': '1', 05:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:17 INFO - 'NO_EM_RESTART': '1', 05:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:17 INFO - 'PWD': '/builds/slave/test', 05:12:17 INFO - 'SHELL': '/bin/bash', 05:12:17 INFO - 'SHLVL': '1', 05:12:17 INFO - 'TERM': 'linux', 05:12:17 INFO - 'TMOUT': '86400', 05:12:17 INFO - 'USER': 'cltbld', 05:12:17 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:17 INFO - '_': '/tools/buildbot/bin/python'} 05:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:12:18 INFO - Cleaning up... 05:12:18 INFO - Return code: 0 05:12:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:12:18 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')]} 05:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:18 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')]} 05:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:18 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 0x7f4dafdb9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', '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 05:12:18 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 05:12:18 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 05:12:18 INFO - Using env: {'DISPLAY': ':0', 05:12:18 INFO - 'HOME': '/home/cltbld', 05:12:18 INFO - 'LANG': 'en_US.UTF-8', 05:12:18 INFO - 'LANGUAGE': 'en_US:en', 05:12:18 INFO - 'LOGNAME': 'cltbld', 05:12:18 INFO - 'MAIL': '/var/mail/cltbld', 05:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:18 INFO - 'MOZ_NO_REMOTE': '1', 05:12:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:18 INFO - 'NO_EM_RESTART': '1', 05:12:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:18 INFO - 'PWD': '/builds/slave/test', 05:12:18 INFO - 'SHELL': '/bin/bash', 05:12:18 INFO - 'SHLVL': '1', 05:12:18 INFO - 'TERM': 'linux', 05:12:18 INFO - 'TMOUT': '86400', 05:12:18 INFO - 'USER': 'cltbld', 05:12:18 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:18 INFO - '_': '/tools/buildbot/bin/python'} 05:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:18 INFO - Downloading/unpacking blobuploader==1.2.4 05:12:18 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 05:12:18 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 05:12:18 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 05:12:18 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 05:12:18 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 05:12:18 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 05:12:20 INFO - Downloading blobuploader-1.2.4.tar.gz 05:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:12:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:12:20 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 05:12:20 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 05:12:20 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 05:12:20 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 05:12:20 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 05:12:20 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 05:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:12:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:12:20 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 05:12:20 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 05:12:20 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 05:12:20 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 05:12:20 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 05:12:20 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 05:12:20 INFO - Downloading docopt-0.6.1.tar.gz 05:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:12:20 INFO - Installing collected packages: blobuploader, requests, docopt 05:12:20 INFO - Running setup.py install for blobuploader 05:12:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:12:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:12:20 INFO - Running setup.py install for requests 05:12:21 INFO - Running setup.py install for docopt 05:12:21 INFO - Successfully installed blobuploader requests docopt 05:12:21 INFO - Cleaning up... 05:12:21 INFO - Return code: 0 05:12:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:21 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 0x7f4dafdb9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', '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 05:12:21 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 05:12:21 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 05:12:21 INFO - Using env: {'DISPLAY': ':0', 05:12:21 INFO - 'HOME': '/home/cltbld', 05:12:21 INFO - 'LANG': 'en_US.UTF-8', 05:12:21 INFO - 'LANGUAGE': 'en_US:en', 05:12:21 INFO - 'LOGNAME': 'cltbld', 05:12:21 INFO - 'MAIL': '/var/mail/cltbld', 05:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:21 INFO - 'MOZ_NO_REMOTE': '1', 05:12:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:21 INFO - 'NO_EM_RESTART': '1', 05:12:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:21 INFO - 'PWD': '/builds/slave/test', 05:12:21 INFO - 'SHELL': '/bin/bash', 05:12:21 INFO - 'SHLVL': '1', 05:12:21 INFO - 'TERM': 'linux', 05:12:21 INFO - 'TMOUT': '86400', 05:12:21 INFO - 'USER': 'cltbld', 05:12:21 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:21 INFO - '_': '/tools/buildbot/bin/python'} 05:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:12:21 INFO - Running setup.py (path:/tmp/pip-CtzrDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:12:21 INFO - Running setup.py (path:/tmp/pip-XAOKDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:12:21 INFO - Running setup.py (path:/tmp/pip-6SKhq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:12:21 INFO - Running setup.py (path:/tmp/pip-1_BOme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:12:21 INFO - Running setup.py (path:/tmp/pip-CMfrEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:12:22 INFO - Running setup.py (path:/tmp/pip-HTZaHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:12:22 INFO - Running setup.py (path:/tmp/pip-FIzN1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:12:22 INFO - Running setup.py (path:/tmp/pip-E7uJVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:12:22 INFO - Running setup.py (path:/tmp/pip-lw_Jkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:12:22 INFO - Running setup.py (path:/tmp/pip-XesrXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:12:22 INFO - Running setup.py (path:/tmp/pip-sCABL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:12:22 INFO - Running setup.py (path:/tmp/pip-vg437V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:12:22 INFO - Running setup.py (path:/tmp/pip-7bl4Jv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:12:22 INFO - Running setup.py (path:/tmp/pip-nKS6jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:22 INFO - Running setup.py (path:/tmp/pip-X5JFv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:12:22 INFO - Running setup.py (path:/tmp/pip-ltmgs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:12:22 INFO - Running setup.py (path:/tmp/pip-PE4Atk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:12:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:12:22 INFO - Running setup.py install for manifestparser 05:12:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:12:23 INFO - Running setup.py install for mozcrash 05:12:23 INFO - Running setup.py install for mozdebug 05:12:23 INFO - Running setup.py install for mozdevice 05:12:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:12:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:12:23 INFO - Running setup.py install for mozfile 05:12:23 INFO - Running setup.py install for mozhttpd 05:12:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:12:23 INFO - Running setup.py install for mozinfo 05:12:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:12:23 INFO - Running setup.py install for mozInstall 05:12:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:12:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:12:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:12:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:12:23 INFO - Running setup.py install for mozleak 05:12:23 INFO - Running setup.py install for mozlog 05:12:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:12:23 INFO - Running setup.py install for moznetwork 05:12:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:12:24 INFO - Running setup.py install for mozprocess 05:12:24 INFO - Running setup.py install for mozprofile 05:12:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:12:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:12:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:12:24 INFO - Running setup.py install for mozrunner 05:12:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:12:24 INFO - Running setup.py install for mozscreenshot 05:12:24 INFO - Running setup.py install for moztest 05:12:24 INFO - Running setup.py install for mozversion 05:12:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:12:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:12:24 INFO - Cleaning up... 05:12:24 INFO - Return code: 0 05:12:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:24 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')]} 05:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:24 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')]} 05:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:24 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 0x7f4dafdb9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', '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 05:12:24 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 05:12:24 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 05:12:24 INFO - Using env: {'DISPLAY': ':0', 05:12:24 INFO - 'HOME': '/home/cltbld', 05:12:24 INFO - 'LANG': 'en_US.UTF-8', 05:12:24 INFO - 'LANGUAGE': 'en_US:en', 05:12:24 INFO - 'LOGNAME': 'cltbld', 05:12:24 INFO - 'MAIL': '/var/mail/cltbld', 05:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:24 INFO - 'MOZ_NO_REMOTE': '1', 05:12:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:24 INFO - 'NO_EM_RESTART': '1', 05:12:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:24 INFO - 'PWD': '/builds/slave/test', 05:12:24 INFO - 'SHELL': '/bin/bash', 05:12:24 INFO - 'SHLVL': '1', 05:12:24 INFO - 'TERM': 'linux', 05:12:24 INFO - 'TMOUT': '86400', 05:12:24 INFO - 'USER': 'cltbld', 05:12:24 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:24 INFO - '_': '/tools/buildbot/bin/python'} 05:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:12:24 INFO - Running setup.py (path:/tmp/pip-b2ulHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:12:25 INFO - Running setup.py (path:/tmp/pip-7WsUVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:12:25 INFO - Running setup.py (path:/tmp/pip-5CmpXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:12:25 INFO - Running setup.py (path:/tmp/pip-50YZJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:12:25 INFO - Running setup.py (path:/tmp/pip-ZBqPAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:12:25 INFO - Running setup.py (path:/tmp/pip-uDpo4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:12:25 INFO - Running setup.py (path:/tmp/pip-cVHHQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:12:25 INFO - Running setup.py (path:/tmp/pip-KlmSsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:12:25 INFO - Running setup.py (path:/tmp/pip-7xmXB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:12:25 INFO - Running setup.py (path:/tmp/pip-Hr5qyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:12:25 INFO - Running setup.py (path:/tmp/pip-X5ZdSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:12:25 INFO - Running setup.py (path:/tmp/pip-s2aj2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:12:25 INFO - Running setup.py (path:/tmp/pip-d8rvk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:12:25 INFO - Running setup.py (path:/tmp/pip-rbB9Zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:12:25 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)) 05:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:25 INFO - Running setup.py (path:/tmp/pip-dMRUk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:26 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)) 05:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:12:26 INFO - Running setup.py (path:/tmp/pip-cHVdb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:12:26 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)) 05:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:12:26 INFO - Running setup.py (path:/tmp/pip-f_Oc0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:12:26 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)) 05:12:26 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)) 05:12:26 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)) 05:12:26 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)) 05:12:26 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)) 05:12:26 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)) 05:12:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:12:26 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 05:12:26 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 05:12:26 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 05:12:26 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 05:12:26 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 05:12:26 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 05:12:28 INFO - Downloading blessings-1.6.tar.gz 05:12:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:12:28 INFO - Installing collected packages: blessings 05:12:28 INFO - Running setup.py install for blessings 05:12:28 INFO - Successfully installed blessings 05:12:28 INFO - Cleaning up... 05:12:28 INFO - Return code: 0 05:12:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:12:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:12:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:12:28 INFO - Reading from file tmpfile_stdout 05:12:28 INFO - Current package versions: 05:12:28 INFO - argparse == 1.2.1 05:12:28 INFO - blessings == 1.6 05:12:28 INFO - blobuploader == 1.2.4 05:12:28 INFO - docopt == 0.6.1 05:12:28 INFO - functools32 == 3.2.3-2 05:12:28 INFO - jsonschema == 2.5.1 05:12:28 INFO - manifestparser == 1.1 05:12:28 INFO - mozInstall == 1.12 05:12:28 INFO - mozcrash == 0.17 05:12:28 INFO - mozdebug == 0.1 05:12:28 INFO - mozdevice == 0.48 05:12:28 INFO - mozfile == 1.2 05:12:28 INFO - mozhttpd == 0.7 05:12:28 INFO - mozinfo == 0.9 05:12:28 INFO - mozleak == 0.1 05:12:28 INFO - mozlog == 3.3 05:12:28 INFO - moznetwork == 0.27 05:12:28 INFO - mozprocess == 0.23 05:12:28 INFO - mozprofile == 0.28 05:12:28 INFO - mozrunner == 6.12 05:12:28 INFO - mozscreenshot == 0.1 05:12:28 INFO - mozsystemmonitor == 0.3 05:12:28 INFO - moztest == 0.7 05:12:28 INFO - mozversion == 1.4 05:12:28 INFO - psutil == 3.1.1 05:12:28 INFO - requests == 1.2.3 05:12:28 INFO - wsgiref == 0.1.2 05:12:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:28 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 0x7f4dafdb9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', '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 05:12:28 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 05:12:28 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 05:12:28 INFO - Using env: {'DISPLAY': ':0', 05:12:28 INFO - 'HOME': '/home/cltbld', 05:12:28 INFO - 'LANG': 'en_US.UTF-8', 05:12:28 INFO - 'LANGUAGE': 'en_US:en', 05:12:28 INFO - 'LOGNAME': 'cltbld', 05:12:28 INFO - 'MAIL': '/var/mail/cltbld', 05:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:28 INFO - 'MOZ_NO_REMOTE': '1', 05:12:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:28 INFO - 'NO_EM_RESTART': '1', 05:12:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:28 INFO - 'PWD': '/builds/slave/test', 05:12:28 INFO - 'SHELL': '/bin/bash', 05:12:28 INFO - 'SHLVL': '1', 05:12:28 INFO - 'TERM': 'linux', 05:12:28 INFO - 'TMOUT': '86400', 05:12:28 INFO - 'USER': 'cltbld', 05:12:28 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:28 INFO - '_': '/tools/buildbot/bin/python'} 05:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:28 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)) 05:12:28 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)) 05:12:28 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)) 05:12:28 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)) 05:12:28 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)) 05:12:28 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)) 05:12:28 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)) 05:12:28 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)) 05:12:28 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)) 05:12:28 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)) 05:12:28 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)) 05:12:28 INFO - Cleaning up... 05:12:28 INFO - Return code: 0 05:12:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:12:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:28 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 0x7f4dafdb9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', '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 05:12:28 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 05:12:28 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 05:12:28 INFO - Using env: {'DISPLAY': ':0', 05:12:28 INFO - 'HOME': '/home/cltbld', 05:12:28 INFO - 'LANG': 'en_US.UTF-8', 05:12:28 INFO - 'LANGUAGE': 'en_US:en', 05:12:28 INFO - 'LOGNAME': 'cltbld', 05:12:28 INFO - 'MAIL': '/var/mail/cltbld', 05:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:28 INFO - 'MOZ_NO_REMOTE': '1', 05:12:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:28 INFO - 'NO_EM_RESTART': '1', 05:12:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:28 INFO - 'PWD': '/builds/slave/test', 05:12:28 INFO - 'SHELL': '/bin/bash', 05:12:28 INFO - 'SHLVL': '1', 05:12:28 INFO - 'TERM': 'linux', 05:12:28 INFO - 'TMOUT': '86400', 05:12:28 INFO - 'USER': 'cltbld', 05:12:28 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:28 INFO - '_': '/tools/buildbot/bin/python'} 05:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:12:28 INFO - Cleaning up... 05:12:28 INFO - Return code: 0 05:12:28 INFO - Running post-action listener: _resource_record_post_action 05:12:28 INFO - Running post-action listener: _start_resource_monitoring 05:12:29 INFO - Starting resource monitoring. 05:12:29 INFO - [mozharness: 2016-08-19 12:12:29.022716Z] Finished create-virtualenv step (success) 05:12:29 INFO - [mozharness: 2016-08-19 12:12:29.023176Z] Running install step. 05:12:29 INFO - Running pre-action listener: _resource_record_pre_action 05:12:29 INFO - Running main action method: install 05:12:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:12:29 INFO - Reading from file tmpfile_stdout 05:12:29 INFO - Detecting whether we're running mozinstall >=1.0... 05:12:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:12:29 INFO - Reading from file tmpfile_stdout 05:12:29 INFO - Output received: 05:12:29 INFO - Usage: mozinstall [options] installer 05:12:29 INFO - Options: 05:12:29 INFO - -h, --help show this help message and exit 05:12:29 INFO - -d DEST, --destination=DEST 05:12:29 INFO - Directory to install application into. [default: 05:12:29 INFO - "/builds/slave/test"] 05:12:29 INFO - --app=APP Application being installed. [default: firefox] 05:12:29 INFO - mkdir: /builds/slave/test/build/application 05:12:29 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'] 05:12:29 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 05:12:46 INFO - Reading from file tmpfile_stdout 05:12:46 INFO - Output received: 05:12:46 INFO - /builds/slave/test/build/application/firefox/firefox 05:12:46 INFO - Running post-action listener: _resource_record_post_action 05:12:46 INFO - [mozharness: 2016-08-19 12:12:46.285984Z] Finished install step (success) 05:12:46 INFO - [mozharness: 2016-08-19 12:12:46.286233Z] Running run-tests step. 05:12:46 INFO - Running pre-action listener: _resource_record_pre_action 05:12:46 INFO - Running main action method: run_tests 05:12:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:12:46 INFO - Python 2.7.3 05:12:46 INFO - Return code: 0 05:12:46 INFO - grabbing minidump binary from tooltool 05:12:46 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')]} 05:12:46 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 0x10af370>, '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 0x10b28f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:12:46 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 05:12:46 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 05:12:46 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 05:12:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:12:46 INFO - Return code: 0 05:12:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:12:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:12:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:12:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:12:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:12:46 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/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 05:12:46 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/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 05:12:46 INFO - Using env: {'DISPLAY': ':0', 05:12:46 INFO - 'HOME': '/home/cltbld', 05:12:46 INFO - 'LANG': 'en_US.UTF-8', 05:12:46 INFO - 'LANGUAGE': 'en_US:en', 05:12:46 INFO - 'LOGNAME': 'cltbld', 05:12:46 INFO - 'MAIL': '/var/mail/cltbld', 05:12:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:12:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:46 INFO - 'MOZ_NO_REMOTE': '1', 05:12:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:12:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:46 INFO - 'NO_EM_RESTART': '1', 05:12:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:46 INFO - 'PWD': '/builds/slave/test', 05:12:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:12:46 INFO - 'SHELL': '/bin/bash', 05:12:46 INFO - 'SHLVL': '1', 05:12:46 INFO - 'TERM': 'linux', 05:12:46 INFO - 'TMOUT': '86400', 05:12:46 INFO - 'USER': 'cltbld', 05:12:46 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550', 05:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:46 INFO - '_': '/tools/buildbot/bin/python'} 05:12:46 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/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 05:12:46 INFO - mozversion application_buildid: 20160819023002 05:12:46 INFO - mozversion application_changeset: eaff4fe1c6194cc73476d14b4faff2f355bb64b0 05:12:46 INFO - mozversion application_display_name: Nightly 05:12:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:12:46 INFO - mozversion application_name: Firefox 05:12:46 INFO - mozversion application_remotingname: firefox 05:12:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:12:46 INFO - mozversion application_vendor: Mozilla 05:12:46 INFO - mozversion application_version: 51.0a1 05:12:46 INFO - mozversion platform_buildid: 20160819023002 05:12:46 INFO - mozversion platform_changeset: eaff4fe1c6194cc73476d14b4faff2f355bb64b0 05:12:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:12:46 INFO - mozversion platform_version: 51.0a1 05:12:46 INFO - using testdate: 1471608766 05:12:46 INFO - actual date: 1471608766 05:12:46 INFO - starting webserver on 'localhost:45966' 05:12:46 INFO - SUITE-START | Running 2 tests 05:12:46 INFO - TEST-START | dromaeo_css 05:12:46 INFO - operating with platform_type : linux_ 05:12:46 INFO - Initialising browser for dromaeo_css test... 05:12:46 INFO - TEST-INFO | started process 10187 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3ohqNt/profile http://localhost:45966/getInfo.html) 05:12:50 INFO - PROCESS | 10187 | __metrics Screen width/height:1600/1200 05:12:50 INFO - PROCESS | 10187 | colorDepth:24 05:12:50 INFO - PROCESS | 10187 | Browser inner width/height: 1024/697 05:12:50 INFO - PROCESS | 10187 | __metrics 05:12:50 INFO - PROCESS | 10187 | 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" 05:12:50 INFO - PROCESS | 10187 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:12:51 INFO - PROCESS | 10187 | console.error: PushService: 05:12:51 INFO - PROCESS | 10187 | stateChangeProcessEnqueue: Error transitioning state 05:12:51 INFO - PROCESS | 10187 | UnknownError 05:12:52 INFO - TEST-INFO | 10187: exit 0 05:12:52 INFO - Browser initialized. 05:12:52 INFO - Running cycle 1/1 for dromaeo_css test... 05:12:52 INFO - TEST-INFO | started process 10265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3ohqNt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:13:03 INFO - PROCESS | 10265 | RSS: Main: 161054720 05:13:03 INFO - PROCESS | 10265 | 05:13:55 INFO - PROCESS | 10265 | 1471608835433 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] 05:16:04 INFO - PROCESS | 10265 | Cycle 1(1): loaded http://localhost:45966/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45966/tests/dromaeo/cssquery-ext.html) 05:16:05 INFO - PROCESS | 10265 | RSS: Main: 255893504 05:16:05 INFO - PROCESS | 10265 | 05:19:08 INFO - PROCESS | 10265 | Cycle 1(1): loaded http://localhost:45966/tests/dromaeo/cssquery-ext.html (next: http://localhost:45966/tests/dromaeo/cssquery-jquery.html) 05:19:08 INFO - PROCESS | 10265 | RSS: Main: 248348672 05:19:08 INFO - PROCESS | 10265 | 05:22:11 INFO - PROCESS | 10265 | Cycle 1(1): loaded http://localhost:45966/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45966/tests/dromaeo/cssquery-mootools.html) 05:22:11 INFO - PROCESS | 10265 | RSS: Main: 183697408 05:22:11 INFO - PROCESS | 10265 | 05:25:13 INFO - PROCESS | 10265 | Cycle 1(1): loaded http://localhost:45966/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45966/tests/dromaeo/cssquery-prototype.html) 05:25:14 INFO - PROCESS | 10265 | RSS: Main: 213401600 05:25:14 INFO - PROCESS | 10265 | 05:28:16 INFO - PROCESS | 10265 | Cycle 1(1): loaded http://localhost:45966/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45966/tests/dromaeo/cssquery-yui.html) 05:28:17 INFO - PROCESS | 10265 | RSS: Main: 185032704 05:28:17 INFO - PROCESS | 10265 | 05:31:20 INFO - PROCESS | 10265 | Cycle 1(1): loaded http://localhost:45966/tests/dromaeo/cssquery-yui.html (next: http://localhost:45966/tests/dromaeo/cssquery-dojo.html) 05:31:20 INFO - PROCESS | 10265 | RSS: Main: 268144640 05:31:20 INFO - PROCESS | 10265 | 05:31:20 INFO - PROCESS | 10265 | __start_tp_report 05:31:20 INFO - PROCESS | 10265 | _x_x_mozilla_page_load 05:31:20 INFO - PROCESS | 10265 | _x_x_mozilla_page_load_details 05:31:20 INFO - PROCESS | 10265 | |i|pagename|runs| 05:31:20 INFO - PROCESS | 10265 | |0;dojo.html;3040.1183656739545;3057.1439996800905;3071.493203621403;3079.153232860963;3080.7843450958426;5134.383873935124;5223.059849227137;5302.78788848446;5357.598180136491;5424.267723857283;5249.685018898868;5253.159494480884;5265.76304066317;5329.706866122362;5340;4733.431988161426;4786;4794.376731024962;4795.2087905495555;4949.628777841676;4898.608111351101;4918.483559226283;4921.901561968759;4935.333729946447;4960.900781984358;4718.598919091847;4733.958529123587;4858.922426064771;4894.681845680019;4920.065187614359;4750.8337208197545;4770.403699537558;4803.375561177059;4849.175640141185;4863.10032643962;4736.218523943545;4746.240601503778;4764.7617619119255;4842.031593681278;4880.219164933594;5111.412187598399;5309.62832601718;5333.066713325192;5350.464953504657;5357.812476563302;5593.216949627055;5668.97165514174;5679.290088738908;5719.084946408555;5771.192033115367;5713.085906255021;5759.712014399263;5818.679972601505;5820.9708951455395;5831.650100993954;5196.246544251138;5268.1834315681135;5385.165299378603;5412.648177868426;5414.91877621836;5815.185873977646;5862.062070068854;5868.061110222429;5919.763209471659;5920.052791553417;5626.803061892814;5627.831165065055;5676;5688.288963879515;5693.146028095736;5497.697626630494;5585;5713.542916566747;5752.798652047242;5769.451902069297;5644.491995720405;5698.943010569841;5708.086706127083;5734.139879018097;5744.913826292609;5626.74679639409;5706.201131841545;5706.343770466449;5709.25779648643;5710.200571919934;4272.636825869774;4338.522762496123;4368.759718215529;4374.868753937387;4414.602685758233;3882.7282090253975;3928.273269445162;3931.5871833457645;3952.3676211805973;4025.939610905839;5441.319835020622;5446.319210098738;5447.264619276426;5489.066858633962;5492.697901615449;5501.559875209973;5809.3609702932645;5815.360310365856;5815.534757219412;5819.447152520504;5320.866978325495;5372.489613486712;5391.541718953855;5425.186222066722;5462.918056229159;4922.286268491049;4933.975330123327;4946.8763280918265;4953.876153096201;4988.9750551247735;5732.283464566929;5934.851628709231;5961.105834124916;5987.700614969234;5995.61028533144;5667.489925906688;5677.630953987978;5698.25922630072;5698.401667824819;5791.681457519877;5890.410958904075;5898.528117750484;5899.144624029577;5905.852353691021;5911.497522710446;5423.376311724044;5547.528460080938;5587.329520457572;5588.804391846266;5592.664440133442;5643.125315576092;5677.659340439422;5695.686737229326;5748.6838223898085;5780.479756821906;5575;5614.831555053355;5616.85957851057;5620.662760234399;5626.662400255997;5376.865578360573;5574.777008919597;5580.720963951705;5583.330000399978;5590.468905454138;5501;5553.111502159635;5566.582506311962;5626.2967129108865;5667.234923285304;5667.971660141673;5680.573956953328;5721.628094173865;5724.542036636975;5726.198332233407;5338.733063346895;5533.861653458695;5543.9168412474655;5545.334559852843;5549.805756798654 05:31:20 INFO - PROCESS | 10265 | |1;ext.html;591.9822405327839;609.8414412252814;626.4925410417559;634.0900807341468;641.8459569703269;32367.21980291083;43853;44595.33107003389;44608.77695611517;44767.999999999956;45751.77124114383;46053;46146.53853461473;46171.307430388486;46199.30701039487;7627.580483073429;7716.112647045577;7725.8068548286465;8169.5098294102245;10695.572177112945;10559.313644613076;10949;10952.233343665946;10958.616448424307;10960.12319014481;10975.890241097606;11018.559257629686;11025.393603351813;11033.393163376011;11049.834252486218;10928.289661172039;10980.505877235464;10989.450527473595;10991.725206869813;10998.230123891371;10710.732231694192;10779.4610269487;10784.946075269652;10789.568417263301;10790.73023174419;43065.63139027352;43501.34747978751;43520;43530.564694352965;43629.12741745179;3308.718758905496;3379.662033796625;3384.204711892728;3430.6740859618294;3432.7768695034993;32675;32812;33026.33947321064;34211.31577368463;34382.14044648879;4038.6970977176534;4039.899002524931;4207.64235040022;4249.851255206053;4296.398504209413;272910;273118;275226;275313.6234318836;275541;56804.14793778097;57182.14226786602;57239.71380143073;57304;57342.7132864333;37020.81489592588;37410.25179496395;37605.62394376076;39436.802815985735;60429.69785151134;17239.82760172382;17351.47945561635;17398.478045658652;17402.564935876704;17404.303827846743;11246;11327.376994265229;11414.600488982845;12141;13249.801252981213;18888.81111188898;18994.620107598046;19015.1443185057;19084.900660927287;19118;7064.646767661597;7317.158526769384;7355;7396.926030739732;7435.591042492607;967.5936106835146;993.2004736187295;1084.2952081147316;1098.0831006109988;1118.1557923767502;31934.520982185284;31979.360412791615;32053;32399.19002024921;32618.510722339182;1051.9737006574896;1077.9892201078046;1087.276960821061;1095.2661716649864;1110.4836251143256;16380.590485237964;16985.830141698672;17165.399211027554;17228.741568876478;17265.39571115005;578763;586205;586335;587750;589242;59928.70035649794;60742.392576074555;60901.34226510343;60938;60968.695156523936;1234.5864135514523;1292.9030322725832;1334.619633404475;1336.5121730568274;1401.6425811418023;29183;33126.83436582898;33179.668203317655;33184.1703957403;33550.207650926764;49781.759820808875;50151.49848501614;50174.22996355466;50302.49697502978;50375.74812125916;331086;331621;331742;333581;333603;28618;28700.569491458064;28908.855455722587;29135.125946221648;29423.411531768816;44624;44730.55269447394;45264.77367613141;46027.53972460233;46514.069718606115;40128;40640.1871962565;41868.37197442102;42875.785621072944;42975.14049719051;40251.79874100611;40422.1915561693;40491;40992.79503602463;42105;40738.38892416557;40956.38565421462;41858;42479;42522.57477425186;33200.83399582987;37363.439548406255;37634.43548346832;38106;38314;28212.330776229177;28804.711952880203;29033;29051;29196.854015729787 05:31:20 INFO - PROCESS | 10265 | |2;jquery.html;369.9408094704848;379.3020841651359;398.8344836892689;399.3689969847641;417.2947718355978;14225.573232803019;15131.243437828105;16683.83316166838;16713.916430417845;16725.163741812892;18684.62630747384;19307.51731206717;19455.31906383277;19545.12046957887;19573.510662233417;4259.2759230930815;5543.722813859311;5750.712464376786;5758.251427314443;5759.308882934054;6032.788852390167;6304.684765761716;6382.521310901678;6442.323556026597;6442.7745028924;6231.22109736283;6406;6406.423421892029;6435.420812126908;6473.219864537266;6312.895243332445;6462.095306657048;6488.416042556146;6500.219973603151;6501.349865013484;5582.804601838957;5687.857803554903;5710.943475457054;5722.513586345166;5726.0838265877255;23073.53852922949;23777.7622223779;23995.28014159578;24262.514749705082;24398.512029759484;2372.4424760181346;2385.7017872765905;2443.9755602443925;2606.973930260692;2656.986715066412;14214;14917.925410372987;14968.850311496855;15681.686366272725;15685;1817.972730409045;1853.342063567441;1881.67070762616;1919.1651631540324;1922.1926790747918;76426.61786691031;80203;82290.76563851607;82368;85043;10822.134229261641;10929.672109836558;11012.127938250516;11026.944865275622;11271.09831213501;15279.159646219343;15290.541283761282;15294;15301.15843628611;15302.770458443132;8182.672693092329;8233.423660343838;8255.669773209125;8532.616032278562;8535.53054582004;7776.1057478389575;7927.4054445915635;8151.940247767752;8186.918130818734;8194.344452443791;6600.966995164993;6613.206415230203;6653.4677225821815;6682.799516014527;6724.52060546679;1775.7070083436347;1910.6847370184041;1990.2934458267396;1998.280618977167;1999.5101200206043;921.7741653294852;932.0399988012358;956.2827879090682;980.0836218136066;1046.8325067989085;19242;20713.27503537369;20836.687449688266;20987.16051357959;21073.789262107184;976.6288810252058;987.3187500624546;1009.8333774927202;1019.8572199892021;1048.444324508012;9467;11566.884331156582;11574.305541667527;11638.941805290919;11767.646970590897;453855;453963;470699;505385;512975;81312.59343703244;82063;84540;85853;87035.12964870397;983.1544871410581;988.1748739802202;1024.0476356987924;1028.8713910761155;1035.0580971316067;17827;17874.642507150045;18412.447626571226;18455.72316415228;18707.158177881756;33612;41751.1649767009;42493.362599560416;42743;44095;55686;57749.13376299443;57805.42194578169;59463.70268148631;59749.7012514952;8528.104549022264;8566.48601083937;8836.602352894006;8867.467951922905;8949.686760963335;12283.324417157142;13243.735125297633;13253.867461325262;13410.731785364043;13556.186628802303;11272.887271127513;11843.763124737628;11903.583374581847;12038.231206822305;12095.81856272139;11418.371989540656;11648.359340236368;11763.529458821893;11826.586069487526;11999.82000270014;11766.176367654181;11811.586594469496;11825.172237943261;12240.02079833629;12341.321227332583;8673.30613550927;8678.566071696516;8737;8877;9022.413543119676;10886.314561131634;11862.703432414259;11893.46479408446;11943.283402995501;12043.638690839289 05:31:20 INFO - PROCESS | 10265 | |3;mootools.html;1458.5697219320296;1478.13529085485;1634.8119966203883;1760.4894580571636;1799.1813724755239;8299.294559962404;8956.417832840869;8972.551372431377;8975;8977.371583989123;7429.331360177583;7654.158042615308;7657.425693073014;7823.139454659997;8803.427777194494;2336.696229490164;2414.082648593541;2423.4547226873988;2429.757024297574;2436.378723425533;2358.6344116661944;2406.759324067597;2421.2615152378517;2427.2111563741855;2430.4531480416854;2371.81025517959;2372.6796882420826;2373.4541055557233;2374.085976898889;2379.5716770981217;2302.3093072078395;2326.895289711967;2346.8709220992837;2346.8826558672085;2347.6243800991847;2220.5669894370603;2227.0757635581213;2254.2335605894073;2254.695616091823;2258.6837842702034;8779.912200877974;8853.69012084574;8866.556672166365;8969.865452018224;8985.640574377017;5148.742562871841;5301.1783173608155;5303.681779093266;5318.468153184689;5320.707361095138;3333.0834020644274;3453.7755045922186;3489.0405138587093;3511.683948444627;3515.2617950230483;1171.630936255077;1220.6765207220094;1233.0690328801697;1258.2764910176686;1268.1123213750411;12046.096542759327;12089.999999999913;12125.21186122908;12145.392730363536;12153.331566763825;4453.220686379936;4628.236341003764;4699.647526435463;4710.175719249118;4759.904801903943;10362.015608517178;10377.481125943672;10379.221558383151;10381.065704086668;10382.948085259677;5253.711045892513;5279.049771041249;5284.207368894619;5285.841424757186;5290.894182116336;4758.690685105457;4863.805447782049;4870.488598697157;4875.829345972874;4890.731009794496;3748.83130259139;3777.54669439669;3854.865079722252;3878.7284890057426;3928.508936382952;1155.8728539860608;1161.6747310753003;1165.8659254185707;1186.4364426897155;1192.1237890150733;788.8540619985321;960.9951950240204;968.1141755293827;969.9369540979814;990.8761404824397;5888.116782482576;5903.085021821625;5923.229980102559;6000.309964354068;6045.576809623284;468.2134014855008;480.8509362097761;499.1738672497023;499.9250112483084;508.36200568286563;3988.262171498282;4026.3960405939342;4037.2329257440992;4061.3704875744306;4135.462389889295;10482.790344193227;10732.839007414896;10789;10824.512896919658;10875.945620272007;10679.626213082505;10695.197860160519;10708.946455267675;10714.89285107139;10777.78444431107;8061.798455038671;8127.59362031896;8135.349172066339;8135.75592732219;8136.959315203504;5753.856153596026;5924;5956.225690660186;5973.611715238496;6025.939740602713;9609.471479068718;9951.402915825074;9974.850377244202;10024.949875250577;10148.695539133838;10140.797184056426;10221.74445638865;10244.385336879812;10264.897351026693;10281.485925703535;6506.3493650634555;7136.286371362822;7298.854022919618;7334.889976650248;7497.400207983457;8291.295239904435;8442.324614030986;8484.236418722345;8501;8508.659653614033;7596.506227095221;7615.885761713689;7643.426742994409;7824.569648669378;7838.451308408585;7591.43064270193;7668.079830420385;7671.309582137635;7682.731104411542;7716.922830771845;7038.366547010589;7144.714211431485;7146.785596432115;7147;7148.106486689164;4932.260160975882;4978.352814134139;5076.568491678101;5088.4148322941855;5311.68129912191;5084.084864724385;5093.821716240062;5175.120229561059;5220.295260139833;5305.098133317422 05:31:20 INFO - PROCESS | 10265 | |4;prototype.html;323.2016918212017;332.5112085234705;333.8581103031211;334.78071862929784;341.7539371652409;5402.540784033357;5403.78384864606;5452.754626041828;5452.809151679692;5460.481254280847;5000.224965130402;5098.719570423626;5241.449647786984;5247.160454327309;5339.385970613381;4134.214499245134;4139.4204811326435;4150.211459822639;4154.127633197032;4165.875023749277;4133.751974881517;4149.730267532601;4154.252234597771;4175.958240417603;4194.328907374821;3863.4708828030903;3971.940420893689;4005.7782376375194;4095.996480862193;4115.506139263278;4073.348264277701;4078.8368465261356;4095.201435720036;4109.609587089221;4113.979430102845;3629.89110326688;3636.345457817592;3652.9817350913077;3660.762050466711;3683.336999340118;5005.974970125162;5091.23631455281;5093.745312734385;5166.664166829144;5169.715665638389;4661.370714953472;4661.764632372424;4690.695104818176;4697.859064228079;4714.552117548862;5390.78436862525;5426.75579598919;5429.348478182644;5436.782528698847;5502.779888804443;3929.98035009826;3957.544882338539;3983;3988.5242460289587;4000.139969906455;5627.6904770237625;5632.746526406321;5653.773849046033;5656.66060036395;5675.404082571353;4083.224187404384;4085.325921222965;4110.712250142521;4127.33962565988;4130.731502452331;3964.997676173514;4158.792060397029;4166.708330416842;4173.64524015456;4183.937240941388;4711.387519622495;4713.222318317439;4728.550787675165;4772;4774.188387974053;5541.445855414507;5544.889102218014;5548.472895074961;5561.888762224814;5580.609357344947;1368.719412520431;1408.3803126624252;1425.4939496478808;1427.557457188275;1434.813474248362;891.7503099132254;926.2960150285696;929.2844509727472;936.3773090894615;940.722486866376;4807.759612019455;4812.927806082842;4818.638602104786;4864.489228631013;4896.534829191221;4752.500987396343;5061.569766569883;5080.755214972355;5143.305653736697;5160.225966105114;4724;4752.623688155922;4814.6870453420415;4818;4844.491328410466;2668.4929863325906;2674.8395096293893;2700.4464084862557;2721.5917612358303;2728.9044883428983;5042.873928151826;5185.818496352609;5192.027029728679;5211.713355765393;5217.58259339252;5119.820806271762;5134.306868572769;5134.589232861362;5137.818301790609;5176.877480233086;5190.299309593157;5205.557527610195;5206.245094461282;5230.921536176884;5277.604179686539;4748.976255118702;4927.926081108714;4986.429274778433;4995.852612853879;5002.374703162105;4939.234418665113;4973.955469351332;4984.526469985346;5015.448300686849;5019.67372120811;4992.650514463952;5088.363954505687;5177.404598471074;5178.326817513699;5207.973960130175;3689.4834723138247;3696.9630303696617;3712.1833196696684;3724.5530536335814;3736.981315093407;4955.2567114933045;5490.5607551395;5609.859753506195;5722.284714410699;5725.656460612377;5589.021921163699;5617.578681598815;5618.606697531297;5622.915656265078;5642.05349154541;5463.241063263185;5562.360328562268;5584.4136365681015;5597.63615365;5597.776088956396;5483.2323474712775;5521.585881058856;5523.447655234444;5525.182157099827;5598.21624972496;5541.307336582927;5607.607467477237;5641.605087643826;5643.407442218672;5648.85877853057;5237.297878189527;5491.395946445968;5501.972490137524;5530.142827861688;5567.777288908398 05:31:20 INFO - PROCESS | 10265 | |5;yui.html;692.7609974558778;710.6269208665451;734.7575300150951;780.40689076302;785.4030936488269;15670.294836732346;16523.58691032725;16727.330906763716;17209.311627534884;17258.741118883227;15795;15844.445444409448;15907.761383579256;15918.363265469396;15940.760888586681;795.6658203554521;813.259933460548;814.0922870998824;814.4543156085437;815.0545367373848;676.4453148418299;791.2206476620524;814.8533264012475;817.2685446525356;821.7411515372669;844.932405407569;845.4335595151264;845.7800971747359;846.1115828380176;847.5931552854634;844.2612713875359;844.4679851693394;844.8648216285364;844.9915500844975;845.5603086395071;508.5524738230334;518.3494714133776;523.5759035181516;601.6841158391835;617.3857012272772;14095.22476263805;14128.293585320796;14141.646458838612;14142;14158;13994.370253338624;14297;14733.189674567895;14844.851551484455;15002.474913377979;13256.734865302737;13448.663783405394;13542.119762215523;13634.727305453935;14358.64103397413;3729.664330210294;3815.3704638734575;3880.476135721698;3882.5535063467496;4020.9195816083516;79821.60089199517;80257.19742802613;80317.19682803156;80414.59792700999;82318.58840705818;10867.130629549618;11003.33979961205;11055.778884422427;11313.377764223047;11406.657800266004;19238.23047078101;19583.902080489508;19623.116959736846;19625;19679.704804427944;7768.922310776933;7781.883271750815;7800.297973182441;7920.683172673143;8165.550894700729;4597;4676.906461870743;4789.401324834395;4856.295837103671;4964.230544265644;3663.2124093320062;3813.637704418076;3817.1220619257724;3819.980900095482;3825.407061905409;631.6810010944444;632.7975047984601;637.0348921384107;639.2073828452778;639.4372951802384;584.5090124295613;589.949854262384;625.5402279324693;710.1229980973494;743.6988019851968;8998.910010899937;9685.612575497042;9798.167155791838;10345.43100129485;10623.20325975555;706.5654622407285;748.7940816275568;770.7032792374864;775.309974123031;783.115079959642;4928.260760885896;5001.674891132065;5086.567641750418;5090.287359769635;5170.40540337866;113855;119168;121748;122661;127006;90375.54812226028;92706;107229;108773.45613271883;108823.45588272008;84143;87451.56274218588;88302;88919;89856;9721.9027809721;9917.553710082919;10239.334443261165;10549.630762923261;10550.366977981346;48353.516464836306;49832.01343892408;49964.50035499599;51350.22974655458;52002.21996670129;70455;72109.27890721026;79307;80233.59883200546;80893.59553202197;12798.232106073665;13159.868401315864;13463.394147263201;13811.240381779098;13879.583612491642;15350.309236084626;15369.615759606098;15483.767743483631;15940.442084526985;16465.25906334194;14387.78418323705;14414.13515189092;15031.323590438675;15386.615334616725;15541.689166216838;12392.132550721724;12722.936385318015;12857.935710321388;13885.444582216598;14188.290585470892;13928.930355348564;14124.223167725462;14149.08030977983;15148.16685082331;15311.540653780405;11577.247478913843;11634.36011019402;11696.724917023246;12353.752924941271;12718.491260349852;9559.856602150834;11095.112391008539;11170;11182.608608698656;11435.885641143483 05:31:20 INFO - PROCESS | 10265 | __end_tp_report 05:31:20 INFO - PROCESS | 10265 | __start_cc_report 05:31:20 INFO - PROCESS | 10265 | _x_x_mozilla_cycle_collect,1007 05:31:20 INFO - PROCESS | 10265 | __end_cc_report 05:31:20 INFO - PROCESS | 10265 | __startTimestamp1471609880857__endTimestamp 05:31:20 INFO - PROCESS | 10265 | 05:31:20 INFO - PROCESS | 10265 | ------- Summary: start ------- 05:31:20 INFO - PROCESS | 10265 | Number of tests: 6 05:31:20 INFO - PROCESS | 10265 | 05:31:20 INFO - PROCESS | 10265 | [#0] dojo.html Cycles:165 Average:5305.98 Median:5543.92 stddev:609.94 (11.0%) stddev-sans-first:585.34 05:31:20 INFO - PROCESS | 10265 | Values: 3040.1 3057.1 3071.5 3079.2 3080.8 5134.4 5223.1 5302.8 5357.6 5424.3 5249.7 5253.2 5265.8 5329.7 5340.0 4733.4 4786.0 4794.4 4795.2 4949.6 4898.6 4918.5 4921.9 4935.3 4960.9 4718.6 4734.0 4858.9 4894.7 4920.1 4750.8 4770.4 4803.4 4849.2 4863.1 4736.2 4746.2 4764.8 4842.0 4880.2 5111.4 5309.6 5333.1 5350.5 5357.8 5593.2 5669.0 5679.3 5719.1 5771.2 5713.1 5759.7 5818.7 5821.0 5831.7 5196.2 5268.2 5385.2 5412.6 5414.9 5815.2 5862.1 5868.1 5919.8 5920.1 5626.8 5627.8 5676.0 5688.3 5693.1 5497.7 5585.0 5713.5 5752.8 5769.5 5644.5 5698.9 5708.1 5734.1 5744.9 5626.7 5706.2 5706.3 5709.3 5710.2 4272.6 4338.5 4368.8 4374.9 4414.6 3882.7 3928.3 3931.6 3952.4 4025.9 5441.3 5446.3 5447.3 5489.1 5492.7 5501.6 5809.4 5815.4 5815.5 5819.4 5320.9 5372.5 5391.5 5425.2 5462.9 4922.3 4934.0 4946.9 4953.9 4989.0 5732.3 5934.9 5961.1 5987.7 5995.6 5667.5 5677.6 5698.3 5698.4 5791.7 5890.4 5898.5 5899.1 5905.9 5911.5 5423.4 5547.5 5587.3 5588.8 5592.7 5643.1 5677.7 5695.7 5748.7 5780.5 5575.0 5614.8 5616.9 5620.7 5626.7 5376.9 5574.8 5580.7 5583.3 5590.5 5501.0 5553.1 5566.6 5626.3 5667.2 5668.0 5680.6 5721.6 5724.5 5726.2 5338.7 5533.9 5543.9 5545.3 5549.8 05:31:20 INFO - PROCESS | 10265 | 05:31:20 INFO - PROCESS | 10265 | [#1] ext.html Cycles:180 Average:56475.07 Median:3381.93 stddev:111650.85 (3301.4%) stddev-sans-first:111885.22 05:31:20 INFO - PROCESS | 10265 | Values: 592.0 609.8 626.5 634.1 641.8 32367.2 43853.0 44595.3 44608.8 44768.0 45751.8 46053.0 46146.5 46171.3 46199.3 7627.6 7716.1 7725.8 8169.5 10695.6 10559.3 10949.0 10952.2 10958.6 10960.1 10975.9 11018.6 11025.4 11033.4 11049.8 10928.3 10980.5 10989.5 10991.7 10998.2 10710.7 10779.5 10784.9 10789.6 10790.7 43065.6 43501.3 43520.0 43530.6 43629.1 3308.7 3379.7 3384.2 3430.7 3432.8 32675.0 32812.0 33026.3 34211.3 34382.1 4038.7 4039.9 4207.6 4249.9 4296.4 272910.0 273118.0 275226.0 275313.6 275541.0 56804.1 57182.1 57239.7 57304.0 57342.7 37020.8 37410.3 37605.6 39436.8 60429.7 17239.8 17351.5 17398.5 17402.6 17404.3 11246.0 11327.4 11414.6 12141.0 13249.8 18888.8 18994.6 19015.1 19084.9 19118.0 7064.6 7317.2 7355.0 7396.9 7435.6 967.6 993.2 1084.3 1098.1 1118.2 31934.5 31979.4 32053.0 32399.2 32618.5 1052.0 1078.0 1087.3 1095.3 1110.5 16380.6 16985.8 17165.4 17228.7 17265.4 578763.0 586205.0 586335.0 587750.0 589242.0 59928.7 60742.4 60901.3 60938.0 60968.7 1234.6 1292.9 1334.6 1336.5 1401.6 29183.0 33126.8 33179.7 33184.2 33550.2 49781.8 50151.5 50174.2 50302.5 50375.7 331086.0 331621.0 331742.0 333581.0 333603.0 28618.0 28700.6 28908.9 29135.1 29423.4 44624.0 44730.6 45264.8 46027.5 46514.1 40128.0 40640.2 41868.4 42875.8 42975.1 40251.8 40422.2 40491.0 40992.8 42105.0 40738.4 40956.4 41858.0 42479.0 42522.6 33200.8 37363.4 37634.4 38106.0 38314.0 28212.3 28804.7 29033.0 29051.0 29196.9 05:31:20 INFO - PROCESS | 10265 | 05:31:20 INFO - PROCESS | 10265 | [#2] jquery.html Cycles:180 Average:28759.88 Median:18134.49 stddev:78987.62 (435.6%) stddev-sans-first:79180.44 05:31:20 INFO - PROCESS | 10265 | Values: 369.9 379.3 398.8 399.4 417.3 14225.6 15131.2 16683.8 16713.9 16725.2 18684.6 19307.5 19455.3 19545.1 19573.5 4259.3 5543.7 5750.7 5758.3 5759.3 6032.8 6304.7 6382.5 6442.3 6442.8 6231.2 6406.0 6406.4 6435.4 6473.2 6312.9 6462.1 6488.4 6500.2 6501.3 5582.8 5687.9 5710.9 5722.5 5726.1 23073.5 23777.8 23995.3 24262.5 24398.5 2372.4 2385.7 2444.0 2607.0 2657.0 14214.0 14917.9 14968.9 15681.7 15685.0 1818.0 1853.3 1881.7 1919.2 1922.2 76426.6 80203.0 82290.8 82368.0 85043.0 10822.1 10929.7 11012.1 11026.9 11271.1 15279.2 15290.5 15294.0 15301.2 15302.8 8182.7 8233.4 8255.7 8532.6 8535.5 7776.1 7927.4 8151.9 8186.9 8194.3 6601.0 6613.2 6653.5 6682.8 6724.5 1775.7 1910.7 1990.3 1998.3 1999.5 921.8 932.0 956.3 980.1 1046.8 19242.0 20713.3 20836.7 20987.2 21073.8 976.6 987.3 1009.8 1019.9 1048.4 9467.0 11566.9 11574.3 11638.9 11767.6 453855.0 453963.0 470699.0 505385.0 512975.0 81312.6 82063.0 84540.0 85853.0 87035.1 983.2 988.2 1024.0 1028.9 1035.1 17827.0 17874.6 18412.4 18455.7 18707.2 33612.0 41751.2 42493.4 42743.0 44095.0 55686.0 57749.1 57805.4 59463.7 59749.7 8528.1 8566.5 8836.6 8867.5 8949.7 12283.3 13243.7 13253.9 13410.7 13556.2 11272.9 11843.8 11903.6 12038.2 12095.8 11418.4 11648.4 11763.5 11826.6 11999.8 11766.2 11811.6 11825.2 12240.0 12341.3 8673.3 8678.6 8737.0 8877.0 9022.4 10886.3 11862.7 11893.5 11943.3 12043.6 05:31:20 INFO - PROCESS | 10265 | 05:31:20 INFO - PROCESS | 10265 | [#3] mootools.html Cycles:180 Average:5741.57 Median:4867.15 stddev:3257.31 (66.9%) stddev-sans-first:3250.54 05:31:20 INFO - PROCESS | 10265 | Values: 1458.6 1478.1 1634.8 1760.5 1799.2 8299.3 8956.4 8972.6 8975.0 8977.4 7429.3 7654.2 7657.4 7823.1 8803.4 2336.7 2414.1 2423.5 2429.8 2436.4 2358.6 2406.8 2421.3 2427.2 2430.5 2371.8 2372.7 2373.5 2374.1 2379.6 2302.3 2326.9 2346.9 2346.9 2347.6 2220.6 2227.1 2254.2 2254.7 2258.7 8779.9 8853.7 8866.6 8969.9 8985.6 5148.7 5301.2 5303.7 5318.5 5320.7 3333.1 3453.8 3489.0 3511.7 3515.3 1171.6 1220.7 1233.1 1258.3 1268.1 12046.1 12090.0 12125.2 12145.4 12153.3 4453.2 4628.2 4699.6 4710.2 4759.9 10362.0 10377.5 10379.2 10381.1 10382.9 5253.7 5279.0 5284.2 5285.8 5290.9 4758.7 4863.8 4870.5 4875.8 4890.7 3748.8 3777.5 3854.9 3878.7 3928.5 1155.9 1161.7 1165.9 1186.4 1192.1 788.9 961.0 968.1 969.9 990.9 5888.1 5903.1 5923.2 6000.3 6045.6 468.2 480.9 499.2 499.9 508.4 3988.3 4026.4 4037.2 4061.4 4135.5 10482.8 10732.8 10789.0 10824.5 10875.9 10679.6 10695.2 10708.9 10714.9 10777.8 8061.8 8127.6 8135.3 8135.8 8137.0 5753.9 5924.0 5956.2 5973.6 6025.9 9609.5 9951.4 9974.9 10024.9 10148.7 10140.8 10221.7 10244.4 10264.9 10281.5 6506.3 7136.3 7298.9 7334.9 7497.4 8291.3 8442.3 8484.2 8501.0 8508.7 7596.5 7615.9 7643.4 7824.6 7838.5 7591.4 7668.1 7671.3 7682.7 7716.9 7038.4 7144.7 7146.8 7147.0 7148.1 4932.3 4978.4 5076.6 5088.4 5311.7 5084.1 5093.8 5175.1 5220.3 5305.1 05:31:20 INFO - PROCESS | 10265 | 05:31:20 INFO - PROCESS | 10265 | [#4] prototype.html Cycles:180 Average:4491.52 Median:5001.30 stddev:1295.45 (25.9%) stddev-sans-first:1260.74 05:31:20 INFO - PROCESS | 10265 | Values: 323.2 332.5 333.9 334.8 341.8 5402.5 5403.8 5452.8 5452.8 5460.5 5000.2 5098.7 5241.4 5247.2 5339.4 4134.2 4139.4 4150.2 4154.1 4165.9 4133.8 4149.7 4154.3 4176.0 4194.3 3863.5 3971.9 4005.8 4096.0 4115.5 4073.3 4078.8 4095.2 4109.6 4114.0 3629.9 3636.3 3653.0 3660.8 3683.3 5006.0 5091.2 5093.7 5166.7 5169.7 4661.4 4661.8 4690.7 4697.9 4714.6 5390.8 5426.8 5429.3 5436.8 5502.8 3930.0 3957.5 3983.0 3988.5 4000.1 5627.7 5632.7 5653.8 5656.7 5675.4 4083.2 4085.3 4110.7 4127.3 4130.7 3965.0 4158.8 4166.7 4173.6 4183.9 4711.4 4713.2 4728.6 4772.0 4774.2 5541.4 5544.9 5548.5 5561.9 5580.6 1368.7 1408.4 1425.5 1427.6 1434.8 891.8 926.3 929.3 936.4 940.7 4807.8 4812.9 4818.6 4864.5 4896.5 4752.5 5061.6 5080.8 5143.3 5160.2 4724.0 4752.6 4814.7 4818.0 4844.5 2668.5 2674.8 2700.4 2721.6 2728.9 5042.9 5185.8 5192.0 5211.7 5217.6 5119.8 5134.3 5134.6 5137.8 5176.9 5190.3 5205.6 5206.2 5230.9 5277.6 4749.0 4927.9 4986.4 4995.9 5002.4 4939.2 4974.0 4984.5 5015.4 5019.7 4992.7 5088.4 5177.4 5178.3 5208.0 3689.5 3697.0 3712.2 3724.6 3737.0 4955.3 5490.6 5609.9 5722.3 5725.7 5589.0 5617.6 5618.6 5622.9 5642.1 5463.2 5562.4 5584.4 5597.6 5597.8 5483.2 5521.6 5523.4 5525.2 5598.2 5541.3 5607.6 5641.6 5643.4 5648.9 5237.3 5491.4 5502.0 5530.1 5567.8 05:31:20 INFO - PROCESS | 10265 | 05:31:20 INFO - PROCESS | 10265 | [#5] yui.html Cycles:180 Average:21392.50 Median:3951.74 stddev:31020.49 (785.0%) stddev-sans-first:31068.57 05:31:20 INFO - PROCESS | 10265 | Values: 692.8 710.6 734.8 780.4 785.4 15670.3 16523.6 16727.3 17209.3 17258.7 15795.0 15844.4 15907.8 15918.4 15940.8 795.7 813.3 814.1 814.5 815.1 676.4 791.2 814.9 817.3 821.7 844.9 845.4 845.8 846.1 847.6 844.3 844.5 844.9 845.0 845.6 508.6 518.3 523.6 601.7 617.4 14095.2 14128.3 14141.6 14142.0 14158.0 13994.4 14297.0 14733.2 14844.9 15002.5 13256.7 13448.7 13542.1 13634.7 14358.6 3729.7 3815.4 3880.5 3882.6 4020.9 79821.6 80257.2 80317.2 80414.6 82318.6 10867.1 11003.3 11055.8 11313.4 11406.7 19238.2 19583.9 19623.1 19625.0 19679.7 7768.9 7781.9 7800.3 7920.7 8165.6 4597.0 4676.9 4789.4 4856.3 4964.2 3663.2 3813.6 3817.1 3820.0 3825.4 631.7 632.8 637.0 639.2 639.4 584.5 589.9 625.5 710.1 743.7 8998.9 9685.6 9798.2 10345.4 10623.2 706.6 748.8 770.7 775.3 783.1 4928.3 5001.7 5086.6 5090.3 5170.4 113855.0 119168.0 121748.0 122661.0 127006.0 90375.5 92706.0 107229.0 108773.5 108823.5 84143.0 87451.6 88302.0 88919.0 89856.0 9721.9 9917.6 10239.3 10549.6 10550.4 48353.5 49832.0 49964.5 51350.2 52002.2 70455.0 72109.3 79307.0 80233.6 80893.6 12798.2 13159.9 13463.4 13811.2 13879.6 15350.3 15369.6 15483.8 15940.4 16465.3 14387.8 14414.1 15031.3 15386.6 15541.7 12392.1 12722.9 12857.9 13885.4 14188.3 13928.9 14124.2 14149.1 15148.2 15311.5 11577.2 11634.4 11696.7 12353.8 12718.5 9559.9 11095.1 11170.0 11182.6 11435.9 05:31:20 INFO - PROCESS | 10265 | -------- Summary: end -------- 05:31:20 INFO - PROCESS | 10265 | 05:31:21 INFO - TEST-INFO | 10265: exit 0 05:31:21 INFO - TEST-OK | dromaeo_css | took 1115105ms 05:31:21 INFO - TEST-START | kraken 05:31:21 INFO - operating with platform_type : linux_ 05:31:21 INFO - Initialising browser for kraken test... 05:31:21 INFO - TEST-INFO | started process 10571 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsWrLzK/profile http://localhost:45966/getInfo.html) 05:31:25 INFO - PROCESS | 10571 | __metrics Screen width/height:1600/1200 05:31:25 INFO - PROCESS | 10571 | colorDepth:24 05:31:25 INFO - PROCESS | 10571 | Browser inner width/height: 1024/697 05:31:25 INFO - PROCESS | 10571 | __metrics 05:31:25 INFO - PROCESS | 10571 | 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" 05:31:25 INFO - PROCESS | 10571 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:31:26 INFO - PROCESS | 10571 | console.error: PushService: 05:31:26 INFO - PROCESS | 10571 | stateChangeProcessEnqueue: Error transitioning state 05:31:26 INFO - PROCESS | 10571 | UnknownError 05:31:27 INFO - TEST-INFO | 10571: exit 0 05:31:27 INFO - Browser initialized. 05:31:27 INFO - Running cycle 1/1 for kraken test... 05:31:27 INFO - TEST-INFO | started process 10654 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsWrLzK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:38 INFO - PROCESS | 10654 | RSS: Main: 155942912 05:31:38 INFO - PROCESS | 10654 | 05:31:41 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45966/tests/kraken/driver.html?testName=audio-beat-detection) 05:31:42 INFO - PROCESS | 10654 | RSS: Main: 204845056 05:31:42 INFO - PROCESS | 10654 | 05:31:44 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45966/tests/kraken/driver.html?testName=audio-dft) 05:31:45 INFO - PROCESS | 10654 | RSS: Main: 195866624 05:31:45 INFO - PROCESS | 10654 | 05:31:48 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45966/tests/kraken/driver.html?testName=audio-fft) 05:31:49 INFO - PROCESS | 10654 | RSS: Main: 276877312 05:31:49 INFO - PROCESS | 10654 | 05:31:51 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45966/tests/kraken/driver.html?testName=audio-oscillator) 05:31:51 INFO - PROCESS | 10654 | RSS: Main: 186339328 05:31:51 INFO - PROCESS | 10654 | 05:31:54 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45966/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:31:54 INFO - PROCESS | 10654 | RSS: Main: 191426560 05:31:54 INFO - PROCESS | 10654 | 05:31:57 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45966/tests/kraken/driver.html?testName=imaging-darkroom) 05:31:58 INFO - PROCESS | 10654 | RSS: Main: 226578432 05:31:58 INFO - PROCESS | 10654 | 05:32:01 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45966/tests/kraken/driver.html?testName=imaging-desaturate) 05:32:02 INFO - PROCESS | 10654 | RSS: Main: 227106816 05:32:02 INFO - PROCESS | 10654 | 05:32:05 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45966/tests/kraken/driver.html?testName=json-parse-financial) 05:32:05 INFO - PROCESS | 10654 | RSS: Main: 226418688 05:32:05 INFO - PROCESS | 10654 | 05:32:07 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45966/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:32:07 INFO - PROCESS | 10654 | RSS: Main: 189104128 05:32:07 INFO - PROCESS | 10654 | 05:32:09 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45966/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:32:10 INFO - PROCESS | 10654 | RSS: Main: 195637248 05:32:10 INFO - PROCESS | 10654 | 05:32:12 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45966/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:32:12 INFO - PROCESS | 10654 | RSS: Main: 194093056 05:32:12 INFO - PROCESS | 10654 | 05:32:15 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45966/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:32:16 INFO - PROCESS | 10654 | RSS: Main: 187486208 05:32:16 INFO - PROCESS | 10654 | 05:32:18 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45966/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:32:19 INFO - PROCESS | 10654 | RSS: Main: 191668224 05:32:19 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | Cycle 1(1): loaded http://localhost:45966/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45966/tests/kraken/driver.html?testName=ai-astar) 05:32:21 INFO - PROCESS | 10654 | RSS: Main: 243302400 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | __start_tp_report 05:32:21 INFO - PROCESS | 10654 | _x_x_mozilla_page_load 05:32:21 INFO - PROCESS | 10654 | _x_x_mozilla_page_load_details 05:32:21 INFO - PROCESS | 10654 | |i|pagename|runs| 05:32:21 INFO - PROCESS | 10654 | |0;ai-astar;102;111;111;111;107;111;111;110;111;110 05:32:21 INFO - PROCESS | 10654 | |1;audio-beat-detection;144;117;119;180;118;125;147;117;167;119 05:32:21 INFO - PROCESS | 10654 | |2;audio-dft;161;160;161;161;161;159;160;162;161;161 05:32:21 INFO - PROCESS | 10654 | |3;audio-fft;74;75;74;118;76;76;75;76;100;74 05:32:21 INFO - PROCESS | 10654 | |4;audio-oscillator;85;84;136;85;85;86;115;85;85;132 05:32:21 INFO - PROCESS | 10654 | |5;imaging-gaussian-blur;116;117;117;116;117;116;117;117;117;116 05:32:21 INFO - PROCESS | 10654 | |6;imaging-darkroom;146;145;146;146;146;146;146;147;146;145 05:32:21 INFO - PROCESS | 10654 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;96;95 05:32:21 INFO - PROCESS | 10654 | |8;json-parse-financial;51;48;51;50;51;52;53;51;52;50 05:32:21 INFO - PROCESS | 10654 | |9;json-stringify-tinderbox;61;61;61;62;62;61;62;62;62;62 05:32:21 INFO - PROCESS | 10654 | |10;stanford-crypto-aes;80;84;79;85;79;78;78;83;86;78 05:32:21 INFO - PROCESS | 10654 | |11;stanford-crypto-ccm;136;125;130;123;123;140;125;122;141;131 05:32:21 INFO - PROCESS | 10654 | |12;stanford-crypto-pbkdf2;161;167;163;163;165;165;160;162;164;171 05:32:21 INFO - PROCESS | 10654 | |13;stanford-crypto-sha256-iterative;65;64;65;67;65;66;64;63;63;63 05:32:21 INFO - PROCESS | 10654 | __end_tp_report 05:32:21 INFO - PROCESS | 10654 | __start_cc_report 05:32:21 INFO - PROCESS | 10654 | _x_x_mozilla_cycle_collect,1685 05:32:21 INFO - PROCESS | 10654 | __end_cc_report 05:32:21 INFO - PROCESS | 10654 | __startTimestamp1471609941312__endTimestamp 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | ------- Summary: start ------- 05:32:21 INFO - PROCESS | 10654 | Number of tests: 14 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:2.92 (2.6%) stddev-sans-first:1.32 05:32:21 INFO - PROCESS | 10654 | Values: 102.0 111.0 111.0 111.0 107.0 111.0 111.0 110.0 111.0 110.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:134.50 stddev:23.15 (17.2%) stddev-sans-first:24.34 05:32:21 INFO - PROCESS | 10654 | Values: 144.0 117.0 119.0 180.0 118.0 125.0 147.0 117.0 167.0 119.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 05:32:21 INFO - PROCESS | 10654 | Values: 161.0 160.0 161.0 161.0 161.0 159.0 160.0 162.0 161.0 161.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.00 stddev:14.97 (20.0%) stddev-sans-first:15.61 05:32:21 INFO - PROCESS | 10654 | Values: 74.0 75.0 74.0 118.0 76.0 76.0 75.0 76.0 100.0 74.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:85.00 stddev:21.27 (25.0%) stddev-sans-first:22.06 05:32:21 INFO - PROCESS | 10654 | Values: 85.0 84.0 136.0 85.0 85.0 86.0 115.0 85.0 85.0 132.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 05:32:21 INFO - PROCESS | 10654 | Values: 116.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 116.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 05:32:21 INFO - PROCESS | 10654 | Values: 146.0 145.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 145.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:32:21 INFO - PROCESS | 10654 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:1.37 (2.7%) stddev-sans-first:1.45 05:32:21 INFO - PROCESS | 10654 | Values: 51.0 48.0 51.0 50.0 51.0 52.0 53.0 51.0 52.0 50.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:62.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 05:32:21 INFO - PROCESS | 10654 | Values: 61.0 61.0 61.0 62.0 62.0 61.0 62.0 62.0 62.0 62.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:81.50 stddev:3.16 (3.9%) stddev-sans-first:3.33 05:32:21 INFO - PROCESS | 10654 | Values: 80.0 84.0 79.0 85.0 79.0 78.0 78.0 83.0 86.0 78.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#11] stanford-crypto-ccm Cycles:10 Average:129.60 Median:130.50 stddev:7.21 (5.5%) stddev-sans-first:7.27 05:32:21 INFO - PROCESS | 10654 | Values: 136.0 125.0 130.0 123.0 123.0 140.0 125.0 122.0 141.0 131.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:164.50 stddev:3.18 (1.9%) stddev-sans-first:3.17 05:32:21 INFO - PROCESS | 10654 | Values: 161.0 167.0 163.0 163.0 165.0 165.0 160.0 162.0 164.0 171.0 05:32:21 INFO - PROCESS | 10654 | 05:32:21 INFO - PROCESS | 10654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:65.00 stddev:1.35 (2.1%) stddev-sans-first:1.42 05:32:21 INFO - PROCESS | 10654 | Values: 65.0 64.0 65.0 67.0 65.0 66.0 64.0 63.0 63.0 63.0 05:32:21 INFO - PROCESS | 10654 | -------- Summary: end -------- 05:32:21 INFO - PROCESS | 10654 | 05:32:22 INFO - TEST-INFO | 10654: exit 0 05:32:22 INFO - TEST-OK | kraken | took 60665ms 05:32:22 INFO - SUITE-END | took 1175s 05:32:22 INFO - Completed test suite (00:19:35) 05:32:22 INFO - javascript benchmark 05:32:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3040.1183656739545, 3057.1439996800905, 3071.493203621403, 3079.153232860963, 3080.7843450958426, 5134.383873935124, 5223.059849227137, 5302.78788848446, 5357.598180136491, 5424.267723857283, 5249.685018898868, 5253.159494480884, 5265.76304066317, 5329.706866122362, 5340.0, 4733.431988161426, 4786.0, 4794.376731024962, 4795.2087905495555, 4949.628777841676, 4898.608111351101, 4918.483559226283, 4921.901561968759, 4935.333729946447, 4960.900781984358, 4718.598919091847, 4733.958529123587, 4858.922426064771, 4894.681845680019, 4920.065187614359, 4750.8337208197545, 4770.403699537558, 4803.375561177059, 4849.175640141185, 4863.10032643962, 4736.218523943545, 4746.240601503778, 4764.7617619119255, 4842.031593681278, 4880.219164933594, 5111.412187598399, 5309.62832601718, 5333.066713325192, 5350.464953504657, 5357.812476563302, 5593.216949627055, 5668.97165514174, 5679.290088738908, 5719.084946408555, 5771.192033115367, 5713.085906255021, 5759.712014399263, 5818.679972601505, 5820.9708951455395, 5831.650100993954, 5196.246544251138, 5268.1834315681135, 5385.165299378603, 5412.648177868426, 5414.91877621836, 5815.185873977646, 5862.062070068854, 5868.061110222429, 5919.763209471659, 5920.052791553417, 5626.803061892814, 5627.831165065055, 5676.0, 5688.288963879515, 5693.146028095736, 5497.697626630494, 5585.0, 5713.542916566747, 5752.798652047242, 5769.451902069297, 5644.491995720405, 5698.943010569841, 5708.086706127083, 5734.139879018097, 5744.913826292609, 5626.74679639409, 5706.201131841545, 5706.343770466449, 5709.25779648643, 5710.200571919934, 4272.636825869774, 4338.522762496123, 4368.759718215529, 4374.868753937387, 4414.602685758233, 3882.7282090253975, 3928.273269445162, 3931.5871833457645, 3952.3676211805973, 4025.939610905839, 5441.319835020622, 5446.319210098738, 5447.264619276426, 5489.066858633962, 5492.697901615449, 5501.559875209973, 5809.3609702932645, 5815.360310365856, 5815.534757219412, 5819.447152520504, 5320.866978325495, 5372.489613486712, 5391.541718953855, 5425.186222066722, 5462.918056229159, 4922.286268491049, 4933.975330123327, 4946.8763280918265, 4953.876153096201, 4988.9750551247735, 5732.283464566929, 5934.851628709231, 5961.105834124916, 5987.700614969234, 5995.61028533144, 5667.489925906688, 5677.630953987978, 5698.25922630072, 5698.401667824819, 5791.681457519877, 5890.410958904075, 5898.528117750484, 5899.144624029577, 5905.852353691021, 5911.497522710446, 5423.376311724044, 5547.528460080938, 5587.329520457572, 5588.804391846266, 5592.664440133442, 5643.125315576092, 5677.659340439422, 5695.686737229326, 5748.6838223898085, 5780.479756821906, 5575.0, 5614.831555053355, 5616.85957851057, 5620.662760234399, 5626.662400255997, 5376.865578360573, 5574.777008919597, 5580.720963951705, 5583.330000399978, 5590.468905454138, 5501.0, 5553.111502159635, 5566.582506311962, 5626.2967129108865, 5667.234923285304, 5667.971660141673, 5680.573956953328, 5721.628094173865, 5724.542036636975, 5726.198332233407, 5338.733063346895, 5533.861653458695, 5543.9168412474655, 5545.334559852843, 5549.805756798654], "value": 5264.436355093686, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.9822405327839, 609.8414412252814, 626.4925410417559, 634.0900807341468, 641.8459569703269, 32367.21980291083, 43853.0, 44595.33107003389, 44608.77695611517, 44767.999999999956, 45751.77124114383, 46053.0, 46146.53853461473, 46171.307430388486, 46199.30701039487, 7627.580483073429, 7716.112647045577, 7725.8068548286465, 8169.5098294102245, 10695.572177112945, 10559.313644613076, 10949.0, 10952.233343665946, 10958.616448424307, 10960.12319014481, 10975.890241097606, 11018.559257629686, 11025.393603351813, 11033.393163376011, 11049.834252486218, 10928.289661172039, 10980.505877235464, 10989.450527473595, 10991.725206869813, 10998.230123891371, 10710.732231694192, 10779.4610269487, 10784.946075269652, 10789.568417263301, 10790.73023174419, 43065.63139027352, 43501.34747978751, 43520.0, 43530.564694352965, 43629.12741745179, 3308.718758905496, 3379.662033796625, 3384.204711892728, 3430.6740859618294, 3432.7768695034993, 32675.0, 32812.0, 33026.33947321064, 34211.31577368463, 34382.14044648879, 4038.6970977176534, 4039.899002524931, 4207.64235040022, 4249.851255206053, 4296.398504209413, 272910.0, 273118.0, 275226.0, 275313.6234318836, 275541.0, 56804.14793778097, 57182.14226786602, 57239.71380143073, 57304.0, 57342.7132864333, 37020.81489592588, 37410.25179496395, 37605.62394376076, 39436.802815985735, 60429.69785151134, 17239.82760172382, 17351.47945561635, 17398.478045658652, 17402.564935876704, 17404.303827846743, 11246.0, 11327.376994265229, 11414.600488982845, 12141.0, 13249.801252981213, 18888.81111188898, 18994.620107598046, 19015.1443185057, 19084.900660927287, 19118.0, 7064.646767661597, 7317.158526769384, 7355.0, 7396.926030739732, 7435.591042492607, 967.5936106835146, 993.2004736187295, 1084.2952081147316, 1098.0831006109988, 1118.1557923767502, 31934.520982185284, 31979.360412791615, 32053.0, 32399.19002024921, 32618.510722339182, 1051.9737006574896, 1077.9892201078046, 1087.276960821061, 1095.2661716649864, 1110.4836251143256, 16380.590485237964, 16985.830141698672, 17165.399211027554, 17228.741568876478, 17265.39571115005, 578763.0, 586205.0, 586335.0, 587750.0, 589242.0, 59928.70035649794, 60742.392576074555, 60901.34226510343, 60938.0, 60968.695156523936, 1234.5864135514523, 1292.9030322725832, 1334.619633404475, 1336.5121730568274, 1401.6425811418023, 29183.0, 33126.83436582898, 33179.668203317655, 33184.1703957403, 33550.207650926764, 49781.759820808875, 50151.49848501614, 50174.22996355466, 50302.49697502978, 50375.74812125916, 331086.0, 331621.0, 331742.0, 333581.0, 333603.0, 28618.0, 28700.569491458064, 28908.855455722587, 29135.125946221648, 29423.411531768816, 44624.0, 44730.55269447394, 45264.77367613141, 46027.53972460233, 46514.069718606115, 40128.0, 40640.1871962565, 41868.37197442102, 42875.785621072944, 42975.14049719051, 40251.79874100611, 40422.1915561693, 40491.0, 40992.79503602463, 42105.0, 40738.38892416557, 40956.38565421462, 41858.0, 42479.0, 42522.57477425186, 33200.83399582987, 37363.439548406255, 37634.43548346832, 38106.0, 38314.0, 28212.330776229177, 28804.711952880203, 29033.0, 29051.0, 29196.854015729787], "value": 20141.61606786707, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.9408094704848, 379.3020841651359, 398.8344836892689, 399.3689969847641, 417.2947718355978, 14225.573232803019, 15131.243437828105, 16683.83316166838, 16713.916430417845, 16725.163741812892, 18684.62630747384, 19307.51731206717, 19455.31906383277, 19545.12046957887, 19573.510662233417, 4259.2759230930815, 5543.722813859311, 5750.712464376786, 5758.251427314443, 5759.308882934054, 6032.788852390167, 6304.684765761716, 6382.521310901678, 6442.323556026597, 6442.7745028924, 6231.22109736283, 6406.0, 6406.423421892029, 6435.420812126908, 6473.219864537266, 6312.895243332445, 6462.095306657048, 6488.416042556146, 6500.219973603151, 6501.349865013484, 5582.804601838957, 5687.857803554903, 5710.943475457054, 5722.513586345166, 5726.0838265877255, 23073.53852922949, 23777.7622223779, 23995.28014159578, 24262.514749705082, 24398.512029759484, 2372.4424760181346, 2385.7017872765905, 2443.9755602443925, 2606.973930260692, 2656.986715066412, 14214.0, 14917.925410372987, 14968.850311496855, 15681.686366272725, 15685.0, 1817.972730409045, 1853.342063567441, 1881.67070762616, 1919.1651631540324, 1922.1926790747918, 76426.61786691031, 80203.0, 82290.76563851607, 82368.0, 85043.0, 10822.134229261641, 10929.672109836558, 11012.127938250516, 11026.944865275622, 11271.09831213501, 15279.159646219343, 15290.541283761282, 15294.0, 15301.15843628611, 15302.770458443132, 8182.672693092329, 8233.423660343838, 8255.669773209125, 8532.616032278562, 8535.53054582004, 7776.1057478389575, 7927.4054445915635, 8151.940247767752, 8186.918130818734, 8194.344452443791, 6600.966995164993, 6613.206415230203, 6653.4677225821815, 6682.799516014527, 6724.52060546679, 1775.7070083436347, 1910.6847370184041, 1990.2934458267396, 1998.280618977167, 1999.5101200206043, 921.7741653294852, 932.0399988012358, 956.2827879090682, 980.0836218136066, 1046.8325067989085, 19242.0, 20713.27503537369, 20836.687449688266, 20987.16051357959, 21073.789262107184, 976.6288810252058, 987.3187500624546, 1009.8333774927202, 1019.8572199892021, 1048.444324508012, 9467.0, 11566.884331156582, 11574.305541667527, 11638.941805290919, 11767.646970590897, 453855.0, 453963.0, 470699.0, 505385.0, 512975.0, 81312.59343703244, 82063.0, 84540.0, 85853.0, 87035.12964870397, 983.1544871410581, 988.1748739802202, 1024.0476356987924, 1028.8713910761155, 1035.0580971316067, 17827.0, 17874.642507150045, 18412.447626571226, 18455.72316415228, 18707.158177881756, 33612.0, 41751.1649767009, 42493.362599560416, 42743.0, 44095.0, 55686.0, 57749.13376299443, 57805.42194578169, 59463.70268148631, 59749.7012514952, 8528.104549022264, 8566.48601083937, 8836.602352894006, 8867.467951922905, 8949.686760963335, 12283.324417157142, 13243.735125297633, 13253.867461325262, 13410.731785364043, 13556.186628802303, 11272.887271127513, 11843.763124737628, 11903.583374581847, 12038.231206822305, 12095.81856272139, 11418.371989540656, 11648.359340236368, 11763.529458821893, 11826.586069487526, 11999.82000270014, 11766.176367654181, 11811.586594469496, 11825.172237943261, 12240.02079833629, 12341.321227332583, 8673.30613550927, 8678.566071696516, 8737.0, 8877.0, 9022.413543119676, 10886.314561131634, 11862.703432414259, 11893.46479408446, 11943.283402995501, 12043.638690839289], "value": 9492.567540257562, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1458.5697219320296, 1478.13529085485, 1634.8119966203883, 1760.4894580571636, 1799.1813724755239, 8299.294559962404, 8956.417832840869, 8972.551372431377, 8975.0, 8977.371583989123, 7429.331360177583, 7654.158042615308, 7657.425693073014, 7823.139454659997, 8803.427777194494, 2336.696229490164, 2414.082648593541, 2423.4547226873988, 2429.757024297574, 2436.378723425533, 2358.6344116661944, 2406.759324067597, 2421.2615152378517, 2427.2111563741855, 2430.4531480416854, 2371.81025517959, 2372.6796882420826, 2373.4541055557233, 2374.085976898889, 2379.5716770981217, 2302.3093072078395, 2326.895289711967, 2346.8709220992837, 2346.8826558672085, 2347.6243800991847, 2220.5669894370603, 2227.0757635581213, 2254.2335605894073, 2254.695616091823, 2258.6837842702034, 8779.912200877974, 8853.69012084574, 8866.556672166365, 8969.865452018224, 8985.640574377017, 5148.742562871841, 5301.1783173608155, 5303.681779093266, 5318.468153184689, 5320.707361095138, 3333.0834020644274, 3453.7755045922186, 3489.0405138587093, 3511.683948444627, 3515.2617950230483, 1171.630936255077, 1220.6765207220094, 1233.0690328801697, 1258.2764910176686, 1268.1123213750411, 12046.096542759327, 12089.999999999913, 12125.21186122908, 12145.392730363536, 12153.331566763825, 4453.220686379936, 4628.236341003764, 4699.647526435463, 4710.175719249118, 4759.904801903943, 10362.015608517178, 10377.481125943672, 10379.221558383151, 10381.065704086668, 10382.948085259677, 5253.711045892513, 5279.049771041249, 5284.207368894619, 5285.841424757186, 5290.894182116336, 4758.690685105457, 4863.805447782049, 4870.488598697157, 4875.829345972874, 4890.731009794496, 3748.83130259139, 3777.54669439669, 3854.865079722252, 3878.7284890057426, 3928.508936382952, 1155.8728539860608, 1161.6747310753003, 1165.8659254185707, 1186.4364426897155, 1192.1237890150733, 788.8540619985321, 960.9951950240204, 968.1141755293827, 969.9369540979814, 990.8761404824397, 5888.116782482576, 5903.085021821625, 5923.229980102559, 6000.309964354068, 6045.576809623284, 468.2134014855008, 480.8509362097761, 499.1738672497023, 499.9250112483084, 508.36200568286563, 3988.262171498282, 4026.3960405939342, 4037.2329257440992, 4061.3704875744306, 4135.462389889295, 10482.790344193227, 10732.839007414896, 10789.0, 10824.512896919658, 10875.945620272007, 10679.626213082505, 10695.197860160519, 10708.946455267675, 10714.89285107139, 10777.78444431107, 8061.798455038671, 8127.59362031896, 8135.349172066339, 8135.75592732219, 8136.959315203504, 5753.856153596026, 5924.0, 5956.225690660186, 5973.611715238496, 6025.939740602713, 9609.471479068718, 9951.402915825074, 9974.850377244202, 10024.949875250577, 10148.695539133838, 10140.797184056426, 10221.74445638865, 10244.385336879812, 10264.897351026693, 10281.485925703535, 6506.3493650634555, 7136.286371362822, 7298.854022919618, 7334.889976650248, 7497.400207983457, 8291.295239904435, 8442.324614030986, 8484.236418722345, 8501.0, 8508.659653614033, 7596.506227095221, 7615.885761713689, 7643.426742994409, 7824.569648669378, 7838.451308408585, 7591.43064270193, 7668.079830420385, 7671.309582137635, 7682.731104411542, 7716.922830771845, 7038.366547010589, 7144.714211431485, 7146.785596432115, 7147.0, 7148.106486689164, 4932.260160975882, 4978.352814134139, 5076.568491678101, 5088.4148322941855, 5311.68129912191, 5084.084864724385, 5093.821716240062, 5175.120229561059, 5220.295260139833, 5305.098133317422], "value": 4542.440391627961, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.2016918212017, 332.5112085234705, 333.8581103031211, 334.78071862929784, 341.7539371652409, 5402.540784033357, 5403.78384864606, 5452.754626041828, 5452.809151679692, 5460.481254280847, 5000.224965130402, 5098.719570423626, 5241.449647786984, 5247.160454327309, 5339.385970613381, 4134.214499245134, 4139.4204811326435, 4150.211459822639, 4154.127633197032, 4165.875023749277, 4133.751974881517, 4149.730267532601, 4154.252234597771, 4175.958240417603, 4194.328907374821, 3863.4708828030903, 3971.940420893689, 4005.7782376375194, 4095.996480862193, 4115.506139263278, 4073.348264277701, 4078.8368465261356, 4095.201435720036, 4109.609587089221, 4113.979430102845, 3629.89110326688, 3636.345457817592, 3652.9817350913077, 3660.762050466711, 3683.336999340118, 5005.974970125162, 5091.23631455281, 5093.745312734385, 5166.664166829144, 5169.715665638389, 4661.370714953472, 4661.764632372424, 4690.695104818176, 4697.859064228079, 4714.552117548862, 5390.78436862525, 5426.75579598919, 5429.348478182644, 5436.782528698847, 5502.779888804443, 3929.98035009826, 3957.544882338539, 3983.0, 3988.5242460289587, 4000.139969906455, 5627.6904770237625, 5632.746526406321, 5653.773849046033, 5656.66060036395, 5675.404082571353, 4083.224187404384, 4085.325921222965, 4110.712250142521, 4127.33962565988, 4130.731502452331, 3964.997676173514, 4158.792060397029, 4166.708330416842, 4173.64524015456, 4183.937240941388, 4711.387519622495, 4713.222318317439, 4728.550787675165, 4772.0, 4774.188387974053, 5541.445855414507, 5544.889102218014, 5548.472895074961, 5561.888762224814, 5580.609357344947, 1368.719412520431, 1408.3803126624252, 1425.4939496478808, 1427.557457188275, 1434.813474248362, 891.7503099132254, 926.2960150285696, 929.2844509727472, 936.3773090894615, 940.722486866376, 4807.759612019455, 4812.927806082842, 4818.638602104786, 4864.489228631013, 4896.534829191221, 4752.500987396343, 5061.569766569883, 5080.755214972355, 5143.305653736697, 5160.225966105114, 4724.0, 4752.623688155922, 4814.6870453420415, 4818.0, 4844.491328410466, 2668.4929863325906, 2674.8395096293893, 2700.4464084862557, 2721.5917612358303, 2728.9044883428983, 5042.873928151826, 5185.818496352609, 5192.027029728679, 5211.713355765393, 5217.58259339252, 5119.820806271762, 5134.306868572769, 5134.589232861362, 5137.818301790609, 5176.877480233086, 5190.299309593157, 5205.557527610195, 5206.245094461282, 5230.921536176884, 5277.604179686539, 4748.976255118702, 4927.926081108714, 4986.429274778433, 4995.852612853879, 5002.374703162105, 4939.234418665113, 4973.955469351332, 4984.526469985346, 5015.448300686849, 5019.67372120811, 4992.650514463952, 5088.363954505687, 5177.404598471074, 5178.326817513699, 5207.973960130175, 3689.4834723138247, 3696.9630303696617, 3712.1833196696684, 3724.5530536335814, 3736.981315093407, 4955.2567114933045, 5490.5607551395, 5609.859753506195, 5722.284714410699, 5725.656460612377, 5589.021921163699, 5617.578681598815, 5618.606697531297, 5622.915656265078, 5642.05349154541, 5463.241063263185, 5562.360328562268, 5584.4136365681015, 5597.63615365, 5597.776088956396, 5483.2323474712775, 5521.585881058856, 5523.447655234444, 5525.182157099827, 5598.21624972496, 5541.307336582927, 5607.607467477237, 5641.605087643826, 5643.407442218672, 5648.85877853057, 5237.297878189527, 5491.395946445968, 5501.972490137524, 5530.142827861688, 5567.777288908398], "value": 4083.5048911555155, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.7609974558778, 710.6269208665451, 734.7575300150951, 780.40689076302, 785.4030936488269, 15670.294836732346, 16523.58691032725, 16727.330906763716, 17209.311627534884, 17258.741118883227, 15795.0, 15844.445444409448, 15907.761383579256, 15918.363265469396, 15940.760888586681, 795.6658203554521, 813.259933460548, 814.0922870998824, 814.4543156085437, 815.0545367373848, 676.4453148418299, 791.2206476620524, 814.8533264012475, 817.2685446525356, 821.7411515372669, 844.932405407569, 845.4335595151264, 845.7800971747359, 846.1115828380176, 847.5931552854634, 844.2612713875359, 844.4679851693394, 844.8648216285364, 844.9915500844975, 845.5603086395071, 508.5524738230334, 518.3494714133776, 523.5759035181516, 601.6841158391835, 617.3857012272772, 14095.22476263805, 14128.293585320796, 14141.646458838612, 14142.0, 14158.0, 13994.370253338624, 14297.0, 14733.189674567895, 14844.851551484455, 15002.474913377979, 13256.734865302737, 13448.663783405394, 13542.119762215523, 13634.727305453935, 14358.64103397413, 3729.664330210294, 3815.3704638734575, 3880.476135721698, 3882.5535063467496, 4020.9195816083516, 79821.60089199517, 80257.19742802613, 80317.19682803156, 80414.59792700999, 82318.58840705818, 10867.130629549618, 11003.33979961205, 11055.778884422427, 11313.377764223047, 11406.657800266004, 19238.23047078101, 19583.902080489508, 19623.116959736846, 19625.0, 19679.704804427944, 7768.922310776933, 7781.883271750815, 7800.297973182441, 7920.683172673143, 8165.550894700729, 4597.0, 4676.906461870743, 4789.401324834395, 4856.295837103671, 4964.230544265644, 3663.2124093320062, 3813.637704418076, 3817.1220619257724, 3819.980900095482, 3825.407061905409, 631.6810010944444, 632.7975047984601, 637.0348921384107, 639.2073828452778, 639.4372951802384, 584.5090124295613, 589.949854262384, 625.5402279324693, 710.1229980973494, 743.6988019851968, 8998.910010899937, 9685.612575497042, 9798.167155791838, 10345.43100129485, 10623.20325975555, 706.5654622407285, 748.7940816275568, 770.7032792374864, 775.309974123031, 783.115079959642, 4928.260760885896, 5001.674891132065, 5086.567641750418, 5090.287359769635, 5170.40540337866, 113855.0, 119168.0, 121748.0, 122661.0, 127006.0, 90375.54812226028, 92706.0, 107229.0, 108773.45613271883, 108823.45588272008, 84143.0, 87451.56274218588, 88302.0, 88919.0, 89856.0, 9721.9027809721, 9917.553710082919, 10239.334443261165, 10549.630762923261, 10550.366977981346, 48353.516464836306, 49832.01343892408, 49964.50035499599, 51350.22974655458, 52002.21996670129, 70455.0, 72109.27890721026, 79307.0, 80233.59883200546, 80893.59553202197, 12798.232106073665, 13159.868401315864, 13463.394147263201, 13811.240381779098, 13879.583612491642, 15350.309236084626, 15369.615759606098, 15483.767743483631, 15940.442084526985, 16465.25906334194, 14387.78418323705, 14414.13515189092, 15031.323590438675, 15386.615334616725, 15541.689166216838, 12392.132550721724, 12722.936385318015, 12857.935710321388, 13885.444582216598, 14188.290585470892, 13928.930355348564, 14124.223167725462, 14149.08030977983, 15148.16685082331, 15311.540653780405, 11577.247478913843, 11634.36011019402, 11696.724917023246, 12353.752924941271, 12718.491260349852, 9559.856602150834, 11095.112391008539, 11170.0, 11182.608608698656, 11435.885641143483], "value": 7669.8711740784265, "unit": "score"}], "name": "dromaeo_css", "value": 7233.21544557966, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 111.0, 107.0, 111.0, 111.0, 110.0, 111.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 119.0, 180.0, 118.0, 125.0, 147.0, 117.0, 167.0, 119.0], "value": 135.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 161.0, 159.0, 160.0, 162.0, 161.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 118.0, 76.0, 76.0, 75.0, 76.0, 100.0, 74.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 136.0, 85.0, 85.0, 86.0, 115.0, 85.0, 85.0, 132.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 51.0, 50.0, 51.0, 52.0, 53.0, 51.0, 52.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 62.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 79.0, 85.0, 79.0, 78.0, 78.0, 83.0, 86.0, 78.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 125.0, 130.0, 123.0, 123.0, 140.0, 125.0, 122.0, 141.0, 131.0], "value": 129.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 167.0, 163.0, 163.0, 165.0, 165.0, 160.0, 162.0, 164.0, 171.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 67.0, 65.0, 66.0, 64.0, 63.0, 63.0, 63.0], "value": 64.5, "unit": "score"}], "name": "kraken", "value": 1494.4999999999998, "alertThreshold": 2.0}]} 05:32:22 INFO - Return code: 0 05:32:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:32:22 INFO - # TBPL SUCCESS # 05:32:22 INFO - Running post-action listener: _resource_record_post_action 05:32:22 INFO - [mozharness: 2016-08-19 12:32:22.604122Z] Finished run-tests step (success) 05:32:22 INFO - Running post-run listener: _resource_record_post_run 05:32:22 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 410390528; Read time: 8; Write time: 336956 05:32:22 INFO - TinderboxPrint: CPU usage
13.1% 05:32:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 05:32:22 INFO - TinderboxPrint: I/O write bytes / time
410,390,528 / 336,956 05:32:22 INFO - TinderboxPrint: CPU idle
8,274.8 (86.9%) 05:32:22 INFO - TinderboxPrint: CPU user
1,202.4 (12.6%) 05:32:22 INFO - TinderboxPrint: Swap in / out
0 / 0 05:32:22 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 117387264; Read time: 0; Write time: 91624 05:32:22 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 292098048; Read time: 0; Write time: 245312 05:32:23 INFO - Running post-run listener: _upload_blobber_files 05:32:23 INFO - Blob upload gear active. 05:32:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:32:23 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 05:32:23 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'] 05:32:23 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 05:32:23 INFO - (blobuploader) - INFO - Open directory for files ... 05:32:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:32:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:32:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:32:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:32:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:32:26 INFO - (blobuploader) - INFO - Done attempting. 05:32:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:32:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:32:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:32:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:32:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:32:27 INFO - (blobuploader) - INFO - Done attempting. 05:32:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:32:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:32:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:32:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:32:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:32:28 INFO - (blobuploader) - INFO - Done attempting. 05:32:28 INFO - (blobuploader) - INFO - Iteration through files over. 05:32:28 INFO - Return code: 0 05:32:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:32:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:32:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d74230ff4bbd7adc53a7b32177a0101b17b5c657b64516523afbaa9850da485d552627801d886f8f1b840d9f9895781f7158941938a6d4747eab5f68a3f06d7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf6b695475647e2838c8568f66cf38409e88035c853e9d172ccb4e7aa0505cac859168ae385cca07aa35973cffa1611461ffc07420d996955f468066b36db5d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2796948c1c8ecf2f3c8c1764ed90164f0190d2a2e334fd97387e87ae6b28b3ec47c5b35ac5a3415517d3f1b4c32d68cf34f6fdae4330836434f766e0f0b2ccd9"} 05:32:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:32:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:32:28 INFO - Contents: 05:32:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d74230ff4bbd7adc53a7b32177a0101b17b5c657b64516523afbaa9850da485d552627801d886f8f1b840d9f9895781f7158941938a6d4747eab5f68a3f06d7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf6b695475647e2838c8568f66cf38409e88035c853e9d172ccb4e7aa0505cac859168ae385cca07aa35973cffa1611461ffc07420d996955f468066b36db5d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2796948c1c8ecf2f3c8c1764ed90164f0190d2a2e334fd97387e87ae6b28b3ec47c5b35ac5a3415517d3f1b4c32d68cf34f6fdae4330836434f766e0f0b2ccd9"} 05:32:28 INFO - Running post-run listener: copy_logs_to_upload_dir 05:32:28 INFO - Copying logs to upload dir... 05:32:28 INFO - mkdir: /builds/slave/test/build/upload/logs 05:32:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.489870 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-08-19 05:32:28.477719) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 05:32:28.481370) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550 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/d74230ff4bbd7adc53a7b32177a0101b17b5c657b64516523afbaa9850da485d552627801d886f8f1b840d9f9895781f7158941938a6d4747eab5f68a3f06d7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf6b695475647e2838c8568f66cf38409e88035c853e9d172ccb4e7aa0505cac859168ae385cca07aa35973cffa1611461ffc07420d996955f468066b36db5d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2796948c1c8ecf2f3c8c1764ed90164f0190d2a2e334fd97387e87ae6b28b3ec47c5b35ac5a3415517d3f1b4c32d68cf34f6fdae4330836434f766e0f0b2ccd9"} build_url:https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011401 build_url: 'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/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/d74230ff4bbd7adc53a7b32177a0101b17b5c657b64516523afbaa9850da485d552627801d886f8f1b840d9f9895781f7158941938a6d4747eab5f68a3f06d7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf6b695475647e2838c8568f66cf38409e88035c853e9d172ccb4e7aa0505cac859168ae385cca07aa35973cffa1611461ffc07420d996955f468066b36db5d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2796948c1c8ecf2f3c8c1764ed90164f0190d2a2e334fd97387e87ae6b28b3ec47c5b35ac5a3415517d3f1b4c32d68cf34f6fdae4330836434f766e0f0b2ccd9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 05:32:28.505975) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:32:28.506272) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471608162.922380-1929338550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:32:28.593612) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 05:32:28.594402) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 05:32:28.595106) ========= ========= Total master_lag: 0.18 =========