builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-021 starttime: 1470847122.63 results: success (0) buildid: 20160810083720 builduid: 3a7d8849ace84ed5ad99e146304dc065 revision: 91a40af7390dd185103d668ea53261c7f33c4df2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:42.630412) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:42.630933) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:42.631284) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:42.658296) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:42.658697) ========= 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=df17445c3590ff7897617a0e00000225-1470847121.840588-326218707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-10 09:38:42-- 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% 29.7M=0s 2016-08-10 09:38:42 (29.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109168 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:42.782184) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:42.782524) ========= 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=df17445c3590ff7897617a0e00000225-1470847121.840588-326218707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019486 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:42.832142) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 09:38:42.832515) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 91a40af7390dd185103d668ea53261c7f33c4df2 --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 91a40af7390dd185103d668ea53261c7f33c4df2 --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=df17445c3590ff7897617a0e00000225-1470847121.840588-326218707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-10 09:38:42,907 truncating revision to first 12 chars 2016-08-10 09:38:42,907 Setting DEBUG logging. 2016-08-10 09:38:42,907 attempt 1/10 2016-08-10 09:38:42,907 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/91a40af7390d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 09:38:43,943 unpacking tar archive at: fx-team-91a40af7390d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.350680 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 09:38:44.197419) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:44.197741) ========= script_repo_revision: 91a40af7390dd185103d668ea53261c7f33c4df2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:44.198462) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:44.198734) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 09:38:44.209673) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-08-10 09:38:44.210061) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1470847121.840588-326218707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:38:44 INFO - MultiFileLogger online at 20160810 09:38:44 in /builds/slave/test 09:38:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 09:38:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:38:44 INFO - {'append_to_log': False, 09:38:44 INFO - 'base_work_dir': '/builds/slave/test', 09:38:44 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:38:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:38:44 INFO - 'branch': 'Fx-Team-Non-PGO', 09:38:44 INFO - 'buildbot_json_path': 'buildprops.json', 09:38:44 INFO - 'config_files': ('talos/linux_config.py',), 09:38:44 INFO - 'default_actions': ('clobber', 09:38:44 INFO - 'read-buildbot-config', 09:38:44 INFO - 'download-and-extract', 09:38:44 INFO - 'populate-webroot', 09:38:44 INFO - 'create-virtualenv', 09:38:44 INFO - 'install', 09:38:44 INFO - 'run-tests'), 09:38:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:38:44 INFO - 'download_minidump_stackwalk': True, 09:38:44 INFO - 'download_symbols': 'ondemand', 09:38:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:38:44 INFO - 'tooltool.py': '/tools/tooltool.py', 09:38:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:38:44 INFO - '/tools/misc-python/virtualenv.py')}, 09:38:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:38:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:38:44 INFO - 'installer_path': 'installer.exe', 09:38:44 INFO - 'log_level': 'info', 09:38:44 INFO - 'log_name': 'talos', 09:38:44 INFO - 'log_to_console': True, 09:38:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:38:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:38:44 INFO - 'opt_config_files': (), 09:38:44 INFO - 'pip_index': False, 09:38:44 INFO - 'sps_profile': False, 09:38:44 INFO - 'sps_profile_interval': 0, 09:38:44 INFO - 'suite': 'dromaeojs', 09:38:44 INFO - 'system_bits': '32', 09:38:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:38:44 INFO - 'title': 'talos-linux64-ix-021', 09:38:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:38:44 INFO - 'use_talos_json': True, 09:38:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:38:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:38:44 INFO - 'work_dir': 'build'} 09:38:44 INFO - [mozharness: 2016-08-10 16:38:44.376135Z] Running clobber step. 09:38:44 INFO - Running pre-action listener: _resource_record_pre_action 09:38:44 INFO - Running main action method: clobber 09:38:44 INFO - rmtree: /builds/slave/test/build 09:38:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:38:45 INFO - Running post-action listener: _resource_record_post_action 09:38:45 INFO - [mozharness: 2016-08-10 16:38:45.037491Z] Finished clobber step (success) 09:38:45 INFO - [mozharness: 2016-08-10 16:38:45.037591Z] Running read-buildbot-config step. 09:38:45 INFO - Running pre-action listener: _resource_record_pre_action 09:38:45 INFO - Running main action method: read_buildbot_config 09:38:45 INFO - Using buildbot properties: 09:38:45 INFO - { 09:38:45 INFO - "project": "", 09:38:45 INFO - "product": "firefox", 09:38:45 INFO - "script_repo_revision": "production", 09:38:45 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:38:45 INFO - "repository": "", 09:38:45 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:38:45 INFO - "buildid": "20160810083720", 09:38:45 INFO - "pgo_build": "False", 09:38:45 INFO - "basedir": "/builds/slave/test", 09:38:45 INFO - "buildnumber": 2090, 09:38:45 INFO - "slavename": "talos-linux64-ix-021", 09:38:45 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:38:45 INFO - "platform": "ubuntu64_hw", 09:38:45 INFO - "scheduler": "tests-fx-team-linux64-talos-7-3600", 09:38:45 INFO - "branch": "fx-team", 09:38:45 INFO - "repo_path": "integration/fx-team", 09:38:45 INFO - "revision": "91a40af7390dd185103d668ea53261c7f33c4df2", 09:38:45 INFO - "stage_platform": "linux64", 09:38:45 INFO - "builduid": "3a7d8849ace84ed5ad99e146304dc065", 09:38:45 INFO - "slavebuilddir": "test" 09:38:45 INFO - } 09:38:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:38:45 INFO - Running post-action listener: _resource_record_post_action 09:38:45 INFO - [mozharness: 2016-08-10 16:38:45.040070Z] Finished read-buildbot-config step (success) 09:38:45 INFO - [mozharness: 2016-08-10 16:38:45.040160Z] Running download-and-extract step. 09:38:45 INFO - Running pre-action listener: _resource_record_pre_action 09:38:45 INFO - Running main action method: download_and_extract 09:38:45 INFO - mkdir: /builds/slave/test/build/tests 09:38:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:45 INFO - trying https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:38:45 INFO - Downloading https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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 09:38:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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 09:38:45 INFO - Downloaded 1361 bytes. 09:38:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:38:45 INFO - Using the following test package requirements: 09:38:45 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:38:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:38:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:38:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:38:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:38:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:38:45 INFO - u'jsshell-linux-x86_64.zip'], 09:38:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:38:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:38:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:38:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:38:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:38:45 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:38:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:38:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:38:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:38:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:38:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:38:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:38:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:45 INFO - trying https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:38:45 INFO - Downloading https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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 09:38:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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 09:38:46 INFO - Downloaded 20259477 bytes. 09:38:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:38:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:38:47 INFO - Return code: 0 09:38:47 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 09:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:47 INFO - trying https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:38:47 INFO - Downloading https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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 09:38:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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 09:38:48 INFO - Downloaded 20259477 bytes. 09:38:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:38:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:38:50 INFO - Return code: 0 09:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:50 INFO - trying https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:38:50 INFO - Downloading https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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 09:38:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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 09:38:50 INFO - Downloaded 14413719 bytes. 09:38:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:38:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:38:51 INFO - Return code: 0 09:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:51 INFO - trying https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:38:51 INFO - Downloading https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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 09:38:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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 09:38:52 INFO - Downloaded 55604814 bytes. 09:38:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:38:52 INFO - mkdir: /builds/slave/test/properties 09:38:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:38:52 INFO - Writing to file /builds/slave/test/properties/build_url 09:38:52 INFO - Contents: 09:38:52 INFO - build_url:https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:38:52 INFO - Running post-action listener: _resource_record_post_action 09:38:52 INFO - Running post-action listener: set_extra_try_arguments 09:38:52 INFO - [mozharness: 2016-08-10 16:38:52.767734Z] Finished download-and-extract step (success) 09:38:52 INFO - [mozharness: 2016-08-10 16:38:52.768001Z] Running populate-webroot step. 09:38:52 INFO - Running pre-action listener: _resource_record_pre_action 09:38:52 INFO - Running main action method: populate_webroot 09:38:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:38:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:38:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:38:52 INFO - '--tppagecycles', 09:38:52 INFO - '7'], 09:38:52 INFO - 'tests': ['tsvgm']}}, 09:38:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:38:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:38:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:38:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:38:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:52 INFO - 'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:52 INFO - 'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['damp', 'tps']}, 09:38:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:52 INFO - 'tests': ['damp', 'tps']}, 09:38:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['dromaeo_dom']}, 09:38:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:38:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['basic_compositor_video']}, 09:38:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:38:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['a11yr', 09:38:52 INFO - 'ts_paint', 09:38:52 INFO - 'tpaint', 09:38:52 INFO - 'sessionrestore', 09:38:52 INFO - 'sessionrestore_no_auto_restore', 09:38:52 INFO - 'tabpaint']}, 09:38:52 INFO - 'other-e10s': {'tests': ['a11yr', 09:38:52 INFO - 'ts_paint', 09:38:52 INFO - 'tpaint', 09:38:52 INFO - 'sessionrestore', 09:38:52 INFO - 'sessionrestore_no_auto_restore', 09:38:52 INFO - 'tabpaint']}, 09:38:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:38:52 INFO - 'ts_paint', 09:38:52 INFO - 'tpaint', 09:38:52 INFO - 'sessionrestore', 09:38:52 INFO - 'sessionrestore_no_auto_restore', 09:38:52 INFO - 'tabpaint']}, 09:38:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:38:52 INFO - 'ts_paint', 09:38:52 INFO - 'tpaint', 09:38:52 INFO - 'sessionrestore', 09:38:52 INFO - 'sessionrestore_no_auto_restore', 09:38:52 INFO - 'tabpaint']}, 09:38:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['a11yr', 09:38:52 INFO - 'ts_paint', 09:38:52 INFO - 'tpaint', 09:38:52 INFO - 'sessionrestore', 09:38:52 INFO - 'sessionrestore_no_auto_restore', 09:38:52 INFO - 'tabpaint']}, 09:38:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['a11yr', 09:38:52 INFO - 'ts_paint', 09:38:52 INFO - 'tpaint', 09:38:52 INFO - 'sessionrestore', 09:38:52 INFO - 'sessionrestore_no_auto_restore', 09:38:52 INFO - 'tabpaint']}, 09:38:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['tsvgx', 09:38:52 INFO - 'tsvgr_opacity', 09:38:52 INFO - 'tart', 09:38:52 INFO - 'tscrollx', 09:38:52 INFO - 'cart']}, 09:38:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:38:52 INFO - 'tsvgr_opacity', 09:38:52 INFO - 'tart', 09:38:52 INFO - 'tscrollx', 09:38:52 INFO - 'cart']}, 09:38:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:52 INFO - 'talos_options': ['--disable-e10s'], 09:38:52 INFO - 'tests': ['tp5o']}, 09:38:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:52 INFO - 'tests': ['tp5o']}, 09:38:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:52 INFO - 'talos_options': ['--disable-e10s', 09:38:52 INFO - '--xperf_path', 09:38:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:52 INFO - 'tests': ['tp5n']}, 09:38:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:52 INFO - 'talos_options': ['--xperf_path', 09:38:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:52 INFO - 'tests': ['tp5n']}}, 09:38:52 INFO - 'talos.zip': {'path': '', 09:38:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:38:52 INFO - Running post-action listener: _resource_record_post_action 09:38:52 INFO - [mozharness: 2016-08-10 16:38:52.783833Z] Finished populate-webroot step (success) 09:38:52 INFO - [mozharness: 2016-08-10 16:38:52.783928Z] Running create-virtualenv step. 09:38:52 INFO - Running pre-action listener: _resource_record_pre_action 09:38:52 INFO - Running main action method: create_virtualenv 09:38:52 INFO - Creating virtualenv /builds/slave/test/build/venv 09:38:52 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 09:38:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:38:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:38:52 INFO - Using real prefix '/usr' 09:38:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:38:53 INFO - Installing distribute.............................................................................................................................................................................................done. 09:38:58 INFO - Installing pip.................done. 09:38:58 INFO - Return code: 0 09:38:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, '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': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', '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 09:38:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:38:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:38:58 INFO - Using env: {'DISPLAY': ':0', 09:38:58 INFO - 'HOME': '/home/cltbld', 09:38:58 INFO - 'LANG': 'en_US.UTF-8', 09:38:58 INFO - 'LANGUAGE': 'en_US:en', 09:38:58 INFO - 'LOGNAME': 'cltbld', 09:38:58 INFO - 'MAIL': '/var/mail/cltbld', 09:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:58 INFO - 'MOZ_NO_REMOTE': '1', 09:38:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:58 INFO - 'NO_EM_RESTART': '1', 09:38:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:58 INFO - 'PWD': '/builds/slave/test', 09:38:58 INFO - 'SHELL': '/bin/bash', 09:38:58 INFO - 'SHLVL': '1', 09:38:58 INFO - 'TERM': 'linux', 09:38:58 INFO - 'TMOUT': '86400', 09:38:58 INFO - 'USER': 'cltbld', 09:38:58 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:58 INFO - '_': '/tools/buildbot/bin/python'} 09:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:58 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 09:38:58 INFO - Cleaning up... 09:38:58 INFO - Return code: 0 09:38:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d66030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, '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': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', '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 09:38:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:38:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:38:58 INFO - Using env: {'DISPLAY': ':0', 09:38:58 INFO - 'HOME': '/home/cltbld', 09:38:58 INFO - 'LANG': 'en_US.UTF-8', 09:38:58 INFO - 'LANGUAGE': 'en_US:en', 09:38:58 INFO - 'LOGNAME': 'cltbld', 09:38:58 INFO - 'MAIL': '/var/mail/cltbld', 09:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:58 INFO - 'MOZ_NO_REMOTE': '1', 09:38:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:58 INFO - 'NO_EM_RESTART': '1', 09:38:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:58 INFO - 'PWD': '/builds/slave/test', 09:38:58 INFO - 'SHELL': '/bin/bash', 09:38:58 INFO - 'SHLVL': '1', 09:38:58 INFO - 'TERM': 'linux', 09:38:58 INFO - 'TMOUT': '86400', 09:38:58 INFO - 'USER': 'cltbld', 09:38:58 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:58 INFO - '_': '/tools/buildbot/bin/python'} 09:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:58 INFO - Downloading/unpacking psutil>=3.1.1 09:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:39:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:00 INFO - Installing collected packages: psutil 09:39:00 INFO - Running setup.py install for psutil 09:39:00 INFO - building 'psutil._psutil_linux' extension 09:39:00 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 09:39:00 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 09:39:00 INFO - building 'psutil._psutil_posix' extension 09:39:00 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 09:39:00 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 09:39:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:01 INFO - Successfully installed psutil 09:39:01 INFO - Cleaning up... 09:39:01 INFO - Return code: 0 09:39:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, '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': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', '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 09:39:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:39:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:39:01 INFO - Using env: {'DISPLAY': ':0', 09:39:01 INFO - 'HOME': '/home/cltbld', 09:39:01 INFO - 'LANG': 'en_US.UTF-8', 09:39:01 INFO - 'LANGUAGE': 'en_US:en', 09:39:01 INFO - 'LOGNAME': 'cltbld', 09:39:01 INFO - 'MAIL': '/var/mail/cltbld', 09:39:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:01 INFO - 'MOZ_NO_REMOTE': '1', 09:39:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:01 INFO - 'NO_EM_RESTART': '1', 09:39:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:01 INFO - 'PWD': '/builds/slave/test', 09:39:01 INFO - 'SHELL': '/bin/bash', 09:39:01 INFO - 'SHLVL': '1', 09:39:01 INFO - 'TERM': 'linux', 09:39:01 INFO - 'TMOUT': '86400', 09:39:01 INFO - 'USER': 'cltbld', 09:39:01 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:39:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:01 INFO - '_': '/tools/buildbot/bin/python'} 09:39:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:39:01 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 09:39:01 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 09:39:01 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 09:39:01 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 09:39:01 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 09:39:01 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 09:39:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:39:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:39:03 INFO - Installing collected packages: mozsystemmonitor 09:39:03 INFO - Running setup.py install for mozsystemmonitor 09:39:03 INFO - Successfully installed mozsystemmonitor 09:39:03 INFO - Cleaning up... 09:39:03 INFO - Return code: 0 09:39:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, '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': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', '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 09:39:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:39:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:39:03 INFO - Using env: {'DISPLAY': ':0', 09:39:03 INFO - 'HOME': '/home/cltbld', 09:39:03 INFO - 'LANG': 'en_US.UTF-8', 09:39:03 INFO - 'LANGUAGE': 'en_US:en', 09:39:03 INFO - 'LOGNAME': 'cltbld', 09:39:03 INFO - 'MAIL': '/var/mail/cltbld', 09:39:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:03 INFO - 'MOZ_NO_REMOTE': '1', 09:39:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:03 INFO - 'NO_EM_RESTART': '1', 09:39:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:03 INFO - 'PWD': '/builds/slave/test', 09:39:03 INFO - 'SHELL': '/bin/bash', 09:39:03 INFO - 'SHLVL': '1', 09:39:03 INFO - 'TERM': 'linux', 09:39:03 INFO - 'TMOUT': '86400', 09:39:03 INFO - 'USER': 'cltbld', 09:39:03 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:39:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:03 INFO - '_': '/tools/buildbot/bin/python'} 09:39:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:03 INFO - Downloading/unpacking jsonschema==2.5.1 09:39:03 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 09:39:03 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 09:39:03 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 09:39:03 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 09:39:03 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 09:39:03 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 09:39:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:39:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:07 INFO - Downloading functools32-3.2.3-2.tar.gz 09:39:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:39:07 INFO - warning: no files found matching '*.txt' 09:39:07 INFO - no previously-included directories found matching 'build' 09:39:07 INFO - no previously-included directories found matching 'dist' 09:39:07 INFO - no previously-included directories found matching '.git*' 09:39:07 INFO - Installing collected packages: jsonschema, functools32 09:39:07 INFO - Running setup.py install for functools32 09:39:07 INFO - warning: no files found matching '*.txt' 09:39:07 INFO - no previously-included directories found matching 'build' 09:39:07 INFO - no previously-included directories found matching 'dist' 09:39:07 INFO - no previously-included directories found matching '.git*' 09:39:07 INFO - Successfully installed jsonschema functools32 09:39:07 INFO - Cleaning up... 09:39:07 INFO - Return code: 0 09:39:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:39:07 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')]} 09:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:07 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')]} 09:39:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:07 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 0x1d66030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, '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': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', '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 09:39:07 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 09:39:07 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 09:39:07 INFO - Using env: {'DISPLAY': ':0', 09:39:07 INFO - 'HOME': '/home/cltbld', 09:39:07 INFO - 'LANG': 'en_US.UTF-8', 09:39:07 INFO - 'LANGUAGE': 'en_US:en', 09:39:07 INFO - 'LOGNAME': 'cltbld', 09:39:07 INFO - 'MAIL': '/var/mail/cltbld', 09:39:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:07 INFO - 'MOZ_NO_REMOTE': '1', 09:39:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:07 INFO - 'NO_EM_RESTART': '1', 09:39:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:07 INFO - 'PWD': '/builds/slave/test', 09:39:07 INFO - 'SHELL': '/bin/bash', 09:39:07 INFO - 'SHLVL': '1', 09:39:07 INFO - 'TERM': 'linux', 09:39:07 INFO - 'TMOUT': '86400', 09:39:07 INFO - 'USER': 'cltbld', 09:39:07 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:39:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:07 INFO - '_': '/tools/buildbot/bin/python'} 09:39:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:39:08 INFO - Cleaning up... 09:39:08 INFO - Return code: 0 09:39:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:08 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 0x1d66030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, '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': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', '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 09:39:08 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 09:39:08 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 09:39:08 INFO - Using env: {'DISPLAY': ':0', 09:39:08 INFO - 'HOME': '/home/cltbld', 09:39:08 INFO - 'LANG': 'en_US.UTF-8', 09:39:08 INFO - 'LANGUAGE': 'en_US:en', 09:39:08 INFO - 'LOGNAME': 'cltbld', 09:39:08 INFO - 'MAIL': '/var/mail/cltbld', 09:39:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:08 INFO - 'MOZ_NO_REMOTE': '1', 09:39:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:08 INFO - 'NO_EM_RESTART': '1', 09:39:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:08 INFO - 'PWD': '/builds/slave/test', 09:39:08 INFO - 'SHELL': '/bin/bash', 09:39:08 INFO - 'SHLVL': '1', 09:39:08 INFO - 'TERM': 'linux', 09:39:08 INFO - 'TMOUT': '86400', 09:39:08 INFO - 'USER': 'cltbld', 09:39:08 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:39:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:08 INFO - '_': '/tools/buildbot/bin/python'} 09:39:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:08 INFO - Downloading/unpacking blobuploader==1.2.4 09:39:08 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 09:39:08 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 09:39:08 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 09:39:08 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 09:39:08 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 09:39:08 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 09:39:10 INFO - Downloading blobuploader-1.2.4.tar.gz 09:39:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:39:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:39:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39:10 INFO - Downloading docopt-0.6.1.tar.gz 09:39:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:39:10 INFO - Installing collected packages: blobuploader, requests, docopt 09:39:10 INFO - Running setup.py install for blobuploader 09:39:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:39:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:39:10 INFO - Running setup.py install for requests 09:39:11 INFO - Running setup.py install for docopt 09:39:11 INFO - Successfully installed blobuploader requests docopt 09:39:11 INFO - Cleaning up... 09:39:11 INFO - Return code: 0 09:39:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:11 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 0x1d66030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, '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': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', '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 09:39:11 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 09:39:11 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 09:39:11 INFO - Using env: {'DISPLAY': ':0', 09:39:11 INFO - 'HOME': '/home/cltbld', 09:39:11 INFO - 'LANG': 'en_US.UTF-8', 09:39:11 INFO - 'LANGUAGE': 'en_US:en', 09:39:11 INFO - 'LOGNAME': 'cltbld', 09:39:11 INFO - 'MAIL': '/var/mail/cltbld', 09:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:11 INFO - 'MOZ_NO_REMOTE': '1', 09:39:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:11 INFO - 'NO_EM_RESTART': '1', 09:39:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:11 INFO - 'PWD': '/builds/slave/test', 09:39:11 INFO - 'SHELL': '/bin/bash', 09:39:11 INFO - 'SHLVL': '1', 09:39:11 INFO - 'TERM': 'linux', 09:39:11 INFO - 'TMOUT': '86400', 09:39:11 INFO - 'USER': 'cltbld', 09:39:11 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:11 INFO - '_': '/tools/buildbot/bin/python'} 09:39:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:39:11 INFO - Running setup.py (path:/tmp/pip-tp4uUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:39:11 INFO - Running setup.py (path:/tmp/pip-1Fcu23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:39:11 INFO - Running setup.py (path:/tmp/pip-eLR7yC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:39:11 INFO - Running setup.py (path:/tmp/pip-lFIReD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:39:12 INFO - Running setup.py (path:/tmp/pip-rkGZHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:39:12 INFO - Running setup.py (path:/tmp/pip-e3h7KE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:39:12 INFO - Running setup.py (path:/tmp/pip-9MVvPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:39:12 INFO - Running setup.py (path:/tmp/pip-gAQyPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:39:12 INFO - Running setup.py (path:/tmp/pip-ttKjvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:39:12 INFO - Running setup.py (path:/tmp/pip-Go1Av_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:39:12 INFO - Running setup.py (path:/tmp/pip-YTQU05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:39:12 INFO - Running setup.py (path:/tmp/pip-xWWfbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:39:12 INFO - Running setup.py (path:/tmp/pip-rycheV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:39:12 INFO - Running setup.py (path:/tmp/pip-BJ_K2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:12 INFO - Running setup.py (path:/tmp/pip-qjai7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:39:12 INFO - Running setup.py (path:/tmp/pip-FSUv63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:39:12 INFO - Running setup.py (path:/tmp/pip-ZQ5QD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:39:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:39:12 INFO - Running setup.py install for manifestparser 09:39:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:39:13 INFO - Running setup.py install for mozcrash 09:39:13 INFO - Running setup.py install for mozdebug 09:39:13 INFO - Running setup.py install for mozdevice 09:39:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:39:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:39:13 INFO - Running setup.py install for mozfile 09:39:13 INFO - Running setup.py install for mozhttpd 09:39:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:39:13 INFO - Running setup.py install for mozinfo 09:39:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:39:13 INFO - Running setup.py install for mozInstall 09:39:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:39:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:39:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:39:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:39:13 INFO - Running setup.py install for mozleak 09:39:13 INFO - Running setup.py install for mozlog 09:39:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:39:14 INFO - Running setup.py install for moznetwork 09:39:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:39:14 INFO - Running setup.py install for mozprocess 09:39:14 INFO - Running setup.py install for mozprofile 09:39:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:39:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:39:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:39:14 INFO - Running setup.py install for mozrunner 09:39:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:39:14 INFO - Running setup.py install for mozscreenshot 09:39:14 INFO - Running setup.py install for moztest 09:39:14 INFO - Running setup.py install for mozversion 09:39:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:39:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:39:14 INFO - Cleaning up... 09:39:14 INFO - Return code: 0 09:39:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:14 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')]} 09:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:14 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')]} 09:39:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:14 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 0x1d66030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, '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': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', '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 09:39:14 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 09:39:14 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 09:39:14 INFO - Using env: {'DISPLAY': ':0', 09:39:14 INFO - 'HOME': '/home/cltbld', 09:39:14 INFO - 'LANG': 'en_US.UTF-8', 09:39:14 INFO - 'LANGUAGE': 'en_US:en', 09:39:14 INFO - 'LOGNAME': 'cltbld', 09:39:14 INFO - 'MAIL': '/var/mail/cltbld', 09:39:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:14 INFO - 'MOZ_NO_REMOTE': '1', 09:39:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:14 INFO - 'NO_EM_RESTART': '1', 09:39:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:14 INFO - 'PWD': '/builds/slave/test', 09:39:14 INFO - 'SHELL': '/bin/bash', 09:39:14 INFO - 'SHLVL': '1', 09:39:14 INFO - 'TERM': 'linux', 09:39:14 INFO - 'TMOUT': '86400', 09:39:14 INFO - 'USER': 'cltbld', 09:39:14 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:14 INFO - '_': '/tools/buildbot/bin/python'} 09:39:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:39:14 INFO - Running setup.py (path:/tmp/pip-tbgm2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:39:15 INFO - Running setup.py (path:/tmp/pip-CAZodo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:39:15 INFO - Running setup.py (path:/tmp/pip-zT0yX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:39:15 INFO - Running setup.py (path:/tmp/pip-feYFUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:39:15 INFO - Running setup.py (path:/tmp/pip-8JhHUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:39:15 INFO - Running setup.py (path:/tmp/pip-lvCfUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:39:15 INFO - Running setup.py (path:/tmp/pip-2ndOLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:39:15 INFO - Running setup.py (path:/tmp/pip-j_5_if-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:39:15 INFO - Running setup.py (path:/tmp/pip-xA6c4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:39:15 INFO - Running setup.py (path:/tmp/pip-s9QN6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:39:15 INFO - Running setup.py (path:/tmp/pip-VBKi8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:39:15 INFO - Running setup.py (path:/tmp/pip-EM33N3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:39:15 INFO - Running setup.py (path:/tmp/pip-ZXcLGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:39:15 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)) 09:39:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:39:15 INFO - Running setup.py (path:/tmp/pip-byWmws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:39:16 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)) 09:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:16 INFO - Running setup.py (path:/tmp/pip-snZbtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:16 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)) 09:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:39:16 INFO - Running setup.py (path:/tmp/pip-VfQQV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:39:16 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)) 09:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:39:16 INFO - Running setup.py (path:/tmp/pip-YgitAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:39:16 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)) 09:39:16 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)) 09:39:16 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)) 09:39:16 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)) 09:39:16 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)) 09:39:16 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)) 09:39:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:39:16 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 09:39:16 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 09:39:16 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 09:39:16 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 09:39:16 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 09:39:16 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 09:39:18 INFO - Downloading blessings-1.6.tar.gz 09:39:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:39:18 INFO - Installing collected packages: blessings 09:39:18 INFO - Running setup.py install for blessings 09:39:18 INFO - Successfully installed blessings 09:39:18 INFO - Cleaning up... 09:39:18 INFO - Return code: 0 09:39:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:39:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:39:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:39:18 INFO - Reading from file tmpfile_stdout 09:39:18 INFO - Current package versions: 09:39:18 INFO - argparse == 1.2.1 09:39:18 INFO - blessings == 1.6 09:39:18 INFO - blobuploader == 1.2.4 09:39:18 INFO - docopt == 0.6.1 09:39:18 INFO - functools32 == 3.2.3-2 09:39:18 INFO - jsonschema == 2.5.1 09:39:18 INFO - manifestparser == 1.1 09:39:18 INFO - mozInstall == 1.12 09:39:18 INFO - mozcrash == 0.17 09:39:18 INFO - mozdebug == 0.1 09:39:18 INFO - mozdevice == 0.48 09:39:18 INFO - mozfile == 1.2 09:39:18 INFO - mozhttpd == 0.7 09:39:18 INFO - mozinfo == 0.9 09:39:18 INFO - mozleak == 0.1 09:39:18 INFO - mozlog == 3.3 09:39:18 INFO - moznetwork == 0.27 09:39:18 INFO - mozprocess == 0.23 09:39:18 INFO - mozprofile == 0.28 09:39:18 INFO - mozrunner == 6.12 09:39:18 INFO - mozscreenshot == 0.1 09:39:18 INFO - mozsystemmonitor == 0.3 09:39:18 INFO - moztest == 0.7 09:39:18 INFO - mozversion == 1.4 09:39:18 INFO - psutil == 3.1.1 09:39:18 INFO - requests == 1.2.3 09:39:18 INFO - wsgiref == 0.1.2 09:39:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39: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')]} 09:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39: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')]} 09:39:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:18 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 0x1d66030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, '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': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', '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 09:39:18 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 09:39:18 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 09:39:18 INFO - Using env: {'DISPLAY': ':0', 09:39:18 INFO - 'HOME': '/home/cltbld', 09:39:18 INFO - 'LANG': 'en_US.UTF-8', 09:39:18 INFO - 'LANGUAGE': 'en_US:en', 09:39:18 INFO - 'LOGNAME': 'cltbld', 09:39:18 INFO - 'MAIL': '/var/mail/cltbld', 09:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:18 INFO - 'MOZ_NO_REMOTE': '1', 09:39:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:18 INFO - 'NO_EM_RESTART': '1', 09:39:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:18 INFO - 'PWD': '/builds/slave/test', 09:39:18 INFO - 'SHELL': '/bin/bash', 09:39:18 INFO - 'SHLVL': '1', 09:39:18 INFO - 'TERM': 'linux', 09:39:18 INFO - 'TMOUT': '86400', 09:39:18 INFO - 'USER': 'cltbld', 09:39:18 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:18 INFO - '_': '/tools/buildbot/bin/python'} 09:39:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:18 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)) 09:39:18 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)) 09:39:18 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)) 09:39:18 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)) 09:39:18 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)) 09:39:18 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)) 09:39:18 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)) 09:39:18 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)) 09:39:18 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)) 09:39:18 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)) 09:39:18 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)) 09:39:18 INFO - Cleaning up... 09:39:18 INFO - Return code: 0 09:39:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:39: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')]} 09:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39: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')]} 09:39:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, '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': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', '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 09:39: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', 'jsonschema'] in /builds/slave/test/build 09:39: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 jsonschema 09:39:18 INFO - Using env: {'DISPLAY': ':0', 09:39:18 INFO - 'HOME': '/home/cltbld', 09:39:18 INFO - 'LANG': 'en_US.UTF-8', 09:39:18 INFO - 'LANGUAGE': 'en_US:en', 09:39:18 INFO - 'LOGNAME': 'cltbld', 09:39:18 INFO - 'MAIL': '/var/mail/cltbld', 09:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:18 INFO - 'MOZ_NO_REMOTE': '1', 09:39:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:18 INFO - 'NO_EM_RESTART': '1', 09:39:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:18 INFO - 'PWD': '/builds/slave/test', 09:39:18 INFO - 'SHELL': '/bin/bash', 09:39:18 INFO - 'SHLVL': '1', 09:39:18 INFO - 'TERM': 'linux', 09:39:18 INFO - 'TMOUT': '86400', 09:39:18 INFO - 'USER': 'cltbld', 09:39:18 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:18 INFO - '_': '/tools/buildbot/bin/python'} 09:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:39:19 INFO - Cleaning up... 09:39:19 INFO - Return code: 0 09:39:19 INFO - Running post-action listener: _resource_record_post_action 09:39:19 INFO - Running post-action listener: _start_resource_monitoring 09:39:19 INFO - Starting resource monitoring. 09:39:19 INFO - [mozharness: 2016-08-10 16:39:19.053536Z] Finished create-virtualenv step (success) 09:39:19 INFO - [mozharness: 2016-08-10 16:39:19.053975Z] Running install step. 09:39:19 INFO - Running pre-action listener: _resource_record_pre_action 09:39:19 INFO - Running main action method: install 09:39:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:39:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:39:19 INFO - Reading from file tmpfile_stdout 09:39:19 INFO - Detecting whether we're running mozinstall >=1.0... 09:39:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:39:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:39:19 INFO - Reading from file tmpfile_stdout 09:39:19 INFO - Output received: 09:39:19 INFO - Usage: mozinstall [options] installer 09:39:19 INFO - Options: 09:39:19 INFO - -h, --help show this help message and exit 09:39:19 INFO - -d DEST, --destination=DEST 09:39:19 INFO - Directory to install application into. [default: 09:39:19 INFO - "/builds/slave/test"] 09:39:19 INFO - --app=APP Application being installed. [default: firefox] 09:39:19 INFO - mkdir: /builds/slave/test/build/application 09:39:19 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'] 09:39:19 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 09:39:35 INFO - Reading from file tmpfile_stdout 09:39:35 INFO - Output received: 09:39:35 INFO - /builds/slave/test/build/application/firefox/firefox 09:39:35 INFO - Running post-action listener: _resource_record_post_action 09:39:35 INFO - [mozharness: 2016-08-10 16:39:35.407506Z] Finished install step (success) 09:39:35 INFO - [mozharness: 2016-08-10 16:39:35.407754Z] Running run-tests step. 09:39:35 INFO - Running pre-action listener: _resource_record_pre_action 09:39:35 INFO - Running main action method: run_tests 09:39:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:39:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:39:35 INFO - Python 2.7.3 09:39:35 INFO - Return code: 0 09:39:35 INFO - grabbing minidump binary from tooltool 09:39:35 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')]} 09:39:35 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 0x20253b0>, '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 0x20262b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:39:35 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 09:39:35 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 09:39:35 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 09:39:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:39:35 INFO - Return code: 0 09:39:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:39:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:39:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:39:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:39:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:39:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 09:39:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 09:39:35 INFO - Using env: {'DISPLAY': ':0', 09:39:35 INFO - 'HOME': '/home/cltbld', 09:39:35 INFO - 'LANG': 'en_US.UTF-8', 09:39:35 INFO - 'LANGUAGE': 'en_US:en', 09:39:35 INFO - 'LOGNAME': 'cltbld', 09:39:35 INFO - 'MAIL': '/var/mail/cltbld', 09:39:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:39:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:39:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:35 INFO - 'MOZ_NO_REMOTE': '1', 09:39:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:39:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:35 INFO - 'NO_EM_RESTART': '1', 09:39:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:35 INFO - 'PWD': '/builds/slave/test', 09:39:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:39:35 INFO - 'SHELL': '/bin/bash', 09:39:35 INFO - 'SHLVL': '1', 09:39:35 INFO - 'TERM': 'linux', 09:39:35 INFO - 'TMOUT': '86400', 09:39:35 INFO - 'USER': 'cltbld', 09:39:35 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470847121.840588-326218707', 09:39:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:35 INFO - '_': '/tools/buildbot/bin/python'} 09:39:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 09:39:35 INFO - mozversion application_buildid: 20160810083720 09:39:35 INFO - mozversion application_changeset: 91a40af7390dd185103d668ea53261c7f33c4df2 09:39:35 INFO - mozversion application_display_name: Nightly 09:39:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:39:35 INFO - mozversion application_name: Firefox 09:39:35 INFO - mozversion application_remotingname: firefox 09:39:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:39:35 INFO - mozversion application_vendor: Mozilla 09:39:35 INFO - mozversion application_version: 51.0a1 09:39:35 INFO - mozversion platform_buildid: 20160810083720 09:39:35 INFO - mozversion platform_changeset: 91a40af7390dd185103d668ea53261c7f33c4df2 09:39:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:39:35 INFO - mozversion platform_version: 51.0a1 09:39:35 INFO - using testdate: 1470847175 09:39:35 INFO - actual date: 1470847175 09:39:35 INFO - starting webserver on 'localhost:43685' 09:39:35 INFO - SUITE-START | Running 2 tests 09:39:35 INFO - TEST-START | dromaeo_css 09:39:35 INFO - operating with platform_type : linux_ 09:39:35 INFO - Initialising browser for dromaeo_css test... 09:39:35 INFO - TEST-INFO | started process 4226 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjoOTk5/profile http://localhost:43685/getInfo.html) 09:39:40 INFO - PROCESS | 4226 | __metrics Screen width/height:1600/1200 09:39:40 INFO - PROCESS | 4226 | colorDepth:24 09:39:40 INFO - PROCESS | 4226 | Browser inner width/height: 1024/697 09:39:40 INFO - PROCESS | 4226 | __metrics 09:39:41 INFO - PROCESS | 4226 | 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" 09:39:41 INFO - PROCESS | 4226 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:39:43 INFO - TEST-INFO | 4226: exit 0 09:39:43 INFO - Browser initialized. 09:39:43 INFO - Running cycle 1/1 for dromaeo_css test... 09:39:43 INFO - TEST-INFO | started process 4307 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjoOTk5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:55 INFO - PROCESS | 4307 | RSS: Main: 157364224 09:39:55 INFO - PROCESS | 4307 | 09:40:47 INFO - PROCESS | 4307 | 1470847247084 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] 09:43:00 INFO - PROCESS | 4307 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43685/tests/dromaeo/cssquery-ext.html) 09:43:01 INFO - PROCESS | 4307 | RSS: Main: 272752640 09:43:01 INFO - PROCESS | 4307 | 09:46:03 INFO - PROCESS | 4307 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-ext.html (next: http://localhost:43685/tests/dromaeo/cssquery-jquery.html) 09:46:04 INFO - PROCESS | 4307 | RSS: Main: 244015104 09:46:04 INFO - PROCESS | 4307 | 09:49:06 INFO - PROCESS | 4307 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43685/tests/dromaeo/cssquery-mootools.html) 09:49:07 INFO - PROCESS | 4307 | RSS: Main: 188149760 09:49:07 INFO - PROCESS | 4307 | 09:52:09 INFO - PROCESS | 4307 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43685/tests/dromaeo/cssquery-prototype.html) 09:52:10 INFO - PROCESS | 4307 | RSS: Main: 206864384 09:52:10 INFO - PROCESS | 4307 | 09:55:13 INFO - PROCESS | 4307 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43685/tests/dromaeo/cssquery-yui.html) 09:55:14 INFO - PROCESS | 4307 | RSS: Main: 189542400 09:55:14 INFO - PROCESS | 4307 | 09:58:17 INFO - PROCESS | 4307 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-yui.html (next: http://localhost:43685/tests/dromaeo/cssquery-dojo.html) 09:58:17 INFO - PROCESS | 4307 | RSS: Main: 279506944 09:58:17 INFO - PROCESS | 4307 | 09:58:17 INFO - PROCESS | 4307 | __start_tp_report 09:58:17 INFO - PROCESS | 4307 | _x_x_mozilla_page_load 09:58:17 INFO - PROCESS | 4307 | _x_x_mozilla_page_load_details 09:58:17 INFO - PROCESS | 4307 | |i|pagename|runs| 09:58:17 INFO - PROCESS | 4307 | |0;dojo.html;2637.77578905793;2643.405233822389;2664.72020437854;2675.5050315691587;2682.114902082313;4624.352590637305;4695.976520117403;4705.9529404705945;4798.040391921612;4828.951710482894;4650.302454631798;4694.037722266936;4716.10394025135;4752.429708434984;4754.738489383082;4224.028473451101;4280.764557949312;4290.227759003378;4324.6107850293465;4498.752568608726;4396.428464299636;4444.355568442574;4468.285074388098;4475.619572336339;4490.550944905516;4251.447311849471;4286.57134286572;4296.871093867189;4345.109252603209;4349.565043495657;4343.04453020335;4347.3261644445165;4347.3696314034605;4347.630451411634;4347.739135651871;4132.648724858361;4172.395002724618;4311.827526898952;4319.244132276839;4322.0707547877355;4534.705244159152;4571.977140114312;4607.539246075399;4620.607248383892;4651.558101980307;4955.60355171589;4991.700497970097;4993.7503124843615;4998.075356059141;5003.924941125886;4882.1456245157315;4954.603631709455;4997.550220480138;5020.497950204986;5021.748912554358;4695.5069717679835;4713.929291060636;4746.572808447257;4764.14245435818;4766.666333356632;5164.767585458663;5198.714070726146;5202.92195617066;5225.921611175835;5232.058229518723;4734.526547345238;4952.331435256195;5000.774965126578;5004.824831130893;5018.824341148115;5107.514786095315;5135.35808023997;5168.870778230575;5179.61152913533;5189.792408303701;5074.035933172685;5074.4671809460215;5075.5178258065425;5076.18780995039;5114.820981265638;4867.148249056401;4926.605871530341;4955.752212389366;5057.974710126426;5099.464556221617;4285.8285668573535;4286.292761694285;4324.113556720865;4324.956750432519;4335.826566937287;3735.701143908481;3743.794091325003;3753.6246375362243;3787.3561494545993;3788.6021967693546;4264.616184543406;4299.07569872479;4338.0239446125;4339.934900976488;4345.717528360647;5100.056489549445;5115.283860259567;5135.229715542699;5177;5184.559312458483;4365.192439398721;4375.365571992043;4380.649548036149;4380.8685739427865;4421.977890110529;4459.286514157784;4528.728276303366;4599.701019433726;4608.953910460919;4643.2802915548145;4771.260454629538;4810.59109975642;4825.879353016062;4875.756212189447;4876.731779752147;5103.10695628271;5117.181250999822;5119.820806271762;5124.615653825904;5171.198464238049;4635.374224479787;4763.428388593391;4816.181249187576;4825.8069677213925;4878.121938051043;5014.774335155;5034.370703662043;5056.165732654218;5097.133487307241;5112.258722485293;5123.5644970177955;5129.410117836496;5136.897262054813;5139.665921715226;5154.329937108152;4700;4844.781984810763;4983.053219888209;4993.400791904995;5002.374703162105;4844.636652251166;4903;4916.213405855046;4934.50654934518;4946.9364086721525;4838.540338667821;4874.926876096791;4876.878078048077;4879.560839524318;4885.413750349981;4793.089313030652;4850.563449289581;4932.383452068491;4945.035718034948;4945.307656927961;4765.69023013503;4790.4251489821445;4802.375691160127;4846.224604063333;4857.757112144449 09:58:17 INFO - PROCESS | 4307 | |1;ext.html;558.3355806590157;568.8293511946415;569.3310360326615;578.2222910185801;580.5964854426173;28647.570286445694;39265.60734392655;40474.59525404753;40567.18865622689;44588.3311750324;40001;40646.18707625846;40675;40694.186116277655;40737.79631101848;7723.266289702483;7854.607269636495;7861.960690196541;7861.977942867447;7864.4888082274465;7455.813604659873;9828.36115652484;10112.848307275397;10116.848247276297;10438.843417348708;9759.95120024399;9766.36518626287;9828.361156524803;9832.360896541737;9833.557489912935;9994.90005099947;10065.396076235449;10069.79860402788;10070.798584028278;10071.345362551485;9809.313348065638;9912.950435247849;9913.405195688281;9915.305928585001;9915.405075695482;37781.81109094465;38089;38102.61897381018;38104.618953810386;38160.61839381598;2988;3026.6821983691834;3097.38052389523;3236.91907702307;3645.0887278180453;29319.853400733075;29380.266984860828;29446.411071778446;30407.391852163055;30699.539506907415;3437.5874895012507;3498.2175795874373;3504.56192975878;3508.438649816017;3581.015220814271;262931;264830;266740;266907;267446;51566.226506602434;51695;52103.65533550999;52152.739236303576;52205;34513.65486345155;35413.82293088518;35456.64543354585;54329.456705432436;54578;13655.726885462434;13746;13750.106243094167;13759.656008599864;13766.24285664278;9767.072128147815;10786.892131078746;10969.23215374916;11136.665900023012;11156.386398748147;18078;18222.180001899946;18224.453266401764;18229.7265541017;18402.171902264432;6925.6190909499455;6968.756093536702;6978.825529361807;6980.2883053938385;7025.367716905503;798.3214267872378;825.3892119831281;899.9595018224196;911.7994041310901;976.5751897924425;26980.32549186286;28044.71955280421;28073.719262807517;28417.857910710314;28748.856255718587;986.8618393424927;993.9254555908336;998.7153661206521;1026.9691909242736;1031.4791030529684;14156.787648185285;14984.70030599382;15115.470958516618;15209.239538023276;15264.007839490398;414353;415357;416476;417554;418110;48154;48756.5124348752;48905.755471222416;49005.50994490009;49090;1077.49357801833;1131.9490622922153;1166.859976802789;1194.164085140388;1214.9939250303692;27179.184624461297;29220;29501.704982949897;29641.5553766698;30049.549256761602;41367;41732.1653566933;41734.58265417307;41740.791296043324;41820.79089604654;298241;298532;300932;301449;302031;24571.38571535726;25023.624645629967;25391.11131110402;25426.745732542437;26901.192964211106;40793.796031020844;41543;41803;42145;42167.36748948829;35278.47082293716;35548.46677299791;35642.28715425729;36074.45888311625;36411.27177456489;34996.47505287474;35056.82471587728;35721.1069723259;36018.29862760356;36024.63975360318;34943.65056349404;35482.467762984095;35562.82218588891;35929.28141437209;36135.45796812997;30702.385952280383;32710.672893271716;32926.34147317088;33003.339933201685;33030.5045424314;24800.379990500383;25371.87314063418;25418.364540886625;25642.48715025652;25846.22461326163 09:58:17 INFO - PROCESS | 4307 | |2;jquery.html;342.2715287796626;367.199505078928;371.16117574282123;372.1236488070594;392.99017524561884;12798.808017879739;16225.107619080945;16437.178141092958;16453.917730411333;17420;15952.84047159528;16224.10767407792;16370.83629163708;16374.918125409386;16580.83419165808;4779.808807647699;4973.229149481836;5225.216217567377;5450.91823622646;5491.450854914517;3429.6055953565283;5202.401723801754;5463.043967305725;5502.174673798922;5509.338879334486;5443.265159203496;5507.091329930557;5519.889602207953;5522.696251706155;5524.033294173523;5502.889942201154;5523.613347065727;5552.694601796899;6040.214772079646;6087.269527656709;5410.648307860016;5412.756425960842;5413.83758487246;5416.322959630046;5418.864528386822;12065;17154.88493247935;18671.159797808996;19167.904160479247;19365.322213722593;1814.047624996874;1886.9150888210063;1912.913918873654;1931.5847092874958;1974.7827738948704;8997.820043599091;9401.153896149312;9488.620455181785;9585.952070239604;12484.75030499385;1614.079974414584;1685.4101064627405;1694.9745753813702;1709.2991873331873;1721.0534206186546;43437.34843977343;59899;65767;69029.3097069033;69181.3081869185;9837.163841073589;10097.495125243855;10100;10457.424841633783;10568.313059651098;14056.648583785283;14073.85926140746;14092.506762263272;14092.788608170886;14110.294485275695;7367.115946086504;7404.814879628052;7486.401087912954;7577.16651168371;7596.164421913585;7061.540999835097;7089.432845372357;7116.359527642434;7266.5466906618885;7266.745663901738;4345.804438800262;4425.822967081281;4426.291793313055;4436.312371582345;4480.305552639346;1843.3824668735856;1907.2275728330048;1916.2909723402151;1926.2391355414534;1930.1314408515943;765.5138986743347;810.1817164663614;819.9180081991753;863.4862256957226;902.2331018634109;16613;17111.315547377966;17230.396936107427;17290.567735806704;17680.646387072185;791.7505985614513;816.2735165702529;835.1690068381998;862.5299211929513;877.5743764274322;5734.684592347461;6051.122587224828;6192.102145188923;6292.716827742763;8836.248918841971;391530;408278;409076;415687;418207;56425.71787141038;67772;69124.3087569128;69568;75228.24771752322;403.24995508354436;423.2867618063573;423.6293243412014;424.14746359816377;426.0541597653205;9761.511924403894;10047.547860346449;10297.639582614573;10383.376997380181;10521.105706015074;37266.44100338552;37279.44080838736;37524.812375937945;37885;38298.617013829506;49442.75278623584;50628;51063;52259.47740522546;53291.4670853302;7644;7777.72777952769;7988.241117093867;8000.199980001953;8187.3040791531;11259.211855170059;12281.263124212575;12314.876851231373;12535.749285014432;12622.55821046259;9349.813003740022;10201.897981020094;10443.373397596168;10700.143988481059;11200.495977680861;10185.643502477378;10313.690589282334;10528.157747380345;10546.314489558154;10583.894161058292;7792.922070779219;8317;10750.247482676137;10774.407407592344;10878.347299161758;7755.72854950074;7843.254890785366;7886.093099293655;7994.960025199837;8095.83808323842;7646.9617651911385;9942.204623629948;10102.69691909244;10203.132733717717;10344.37933723979 09:58:17 INFO - PROCESS | 4307 | |3;mootools.html;1384.8268966379198;1430.4206796247518;1482.9555113346603;1694;1698.2357938927485;7713.884291735621;8165.2243036911395;8218.260356567907;8232.711855085074;8237.464564803284;6435.581687190341;7616.695332186706;7870.409719271049;7907.565083920396;8036.2365575270405;2150.8817015064164;2169.360038788553;2172.1528603844513;2173.521825198454;2178.172294528085;2124.851260411772;2152.806247437738;2219.8668079915255;2220.600291947449;2221.3224966385246;2181.879996600186;2195.83531235158;2217.9778202218013;2223.5219427823013;2228.710267665201;2153.655415133579;2156.406988078291;2156.6333723266926;2163.826893848496;2164.794344537282;2128.521082756383;2160.5678864227216;2165.761766205716;2167.295628920607;2169.39257008038;7881.0936742274225;7980.8403831923615;8045.718399855977;8067.878981815277;8078.757637270891;4432.180046691373;4552.180607490651;4555.977220113911;4559.954400456019;4563.498015218323;2892.783041271913;2970.85145742712;3042.224232820639;3056.3734434440885;3056.419280336751;911.4030310146865;958.4488918871607;959.9856002159974;963.036963036963;1002.448653240722;11008.119350451945;11076.224664273504;11092.223544351898;11120.61077862279;11135.164862635334;3843.1545060086737;4053.7365071270274;4168.416421700964;4208.36874468826;4211.515675697273;9346.439213647203;9372.578233979488;9373.859392109123;9376.015518370474;9388.389754665925;4609.124266389375;4616.792244349012;4624.606908412823;4625.074985003012;4627.120847039052;3416.299658570037;4032.475778148822;4160.4591403118;4180.540140584533;4213.536510983789;3551.7691350062164;3591.533100696945;3691.7231207659534;3737.177820879402;3748.681362084199;1134.6482590397004;1167.3754541320523;1175.5826681528106;1175.9118066145074;1182.8284898689642;634.6572850660684;660.7588230295908;682.3415404134935;813.2193094629104;813.8819871118773;5064.518385990912;5365.517103460629;5405.648632838853;5421.5391691706645;5452.618316717871;812.7846120777998;829.0755807274845;829.5727700234385;832.8917240758663;890.6081324217324;3545.556805399325;3618.565772107364;3630.891073267753;3650.470681751131;3733.4586484960073;9370;9662.275329350327;9677.129058384638;9680.612775489042;9731.41611503312;9619.903800961902;9634;9665.71002869915;9668.75828104303;9674.951625241829;7488.288612581796;7622.96188519065;7623.123340815766;7630.313271805564;7637.809054773675;5191.402988656202;5231.163013917755;5354.143337066051;5370.2213179089595;5381.434949330264;9156.221721153777;9168.220701240469;9253.398529095588;9368;9412.858807118037;8678.392512524062;9422.57598408087;9477.620895164391;9505.714828555154;9520.666776662783;5882.558808089325;6521.380468855451;6525.902111468236;6742.9662851685425;6754.3921047106;7509.098908131059;7543.585102819178;7559.508631938907;7566.621668916642;7569.243075692607;6539.901901471387;6618.569792963442;6728.394444500019;6742.292059333699;6904.723811047502;6373.171487706569;6407.871842563216;6471.546991710535;6499.707513161825;6564.770233041821;6268.435840774352;6326.2092238470195;6358.586691865014;6361.650109244036;6365.90451143224;4529.614982726505;4541.114482675846;4656.092062047868;4841.8547443576745;4882.096812089775;4668.953310466988;4705.647076469347;4786.234202527578;4896.608271338213;4944.975275123601 09:58:17 INFO - PROCESS | 4307 | |4;prototype.html;266.9946601067979;270.44288765143807;274.0927529876111;284.48511675328876;285.8070802208509;4826.589739872111;4842.733649649273;4855.465898751139;4878.731669758162;4881.975590122044;4560.498345182027;4652.6510511711585;4697.224957881946;4759.4764575896625;4799.376081109451;3745.34456470118;3764.1154328732728;3790.848366065354;3792.601776813436;3797.6012518685557;3747.850085996557;3758.9624103759024;3771.3211621908044;3804.90487737806;3818.205443441578;3541.6458354164633;3619.6561326673923;3740.962590374102;3745.8127093645344;3763.604821493745;3682.1531047858875;3703.5555733311908;3744.7753134811996;3749.325121478133;3766.1902690921447;3265.804051756902;3268.869245230188;3275.2958114005605;3276.5904261967253;3281.671832816723;4503.819847206108;4735.313379559979;4740.525947405266;4751.287306903958;4767.952320476786;4173.39485774564;4211.894702632428;4244.3633454982;4247.702660813744;4251.702380833343;5074.847754567369;5079.822206222764;5096.184610462308;5133.897322053575;5142.408623008327;3538.239278555123;3548.769329993542;3549.165946002687;3551.893443196708;3559.75081744278;5173.611979101583;5228;5230.599802562711;5262.184361423947;5281.735913204363;3543.9291214175573;3544.3442963051916;3547.9645203548152;3553.218291975761;3568.411212149966;3600.7037466512033;3608.6210947850623;3612.313660404515;3613.6567026132475;3623.691986181204;3983.880483585497;4210.873673789792;4220.282551966112;4228.429162063144;4230.915381692349;4999.250112483157;5010.549050585465;5029.446760856303;5030.974845125824;5073.416557095908;1250.7617458715806;1262.4129779652505;1262.8547717012477;1263.1031967303318;1265.3230521670862;789.2659826361539;815.2051749544265;843.2579330189394;843.6878355008563;857.2541888556996;4420.889477763018;4422.889427764268;4428.180786554498;4454.331850222427;4458.308962110878;5176.171812510056;5184.651990482502;5211.1922651989;5211.7915283389;5214.296070030573;4273.273813090474;4363.825446982149;4382.517923028464;4439.334099884978;4453.376527286183;2738.1785464360614;2739.465804168208;2742.437800250944;2744.19046381314;2750.3674154944474;4856.2958371036;4871.95128048715;4875.731834749123;4881.217705907598;4907.803687852446;4983.700977941335;5163.896722065613;5168.948310516922;5271.815486457956;5272.420033796279;4819.445763737145;4830.8792280193275;4831.323614693875;4840.878978025577;4850.296706977469;5085.4914508548845;5092.159793634009;5128.948710512847;5139.640225184201;5154.381474223117;4889.315495830658;4940.7035577865445;4967.379077615298;4991.700497970133;5055.7219352935945;4812.7968007998;4817.229243321051;4817.301491283814;4820.807167713252;4824.638152138674;3554.2536067425094;3582.229820588586;3583.229605634801;3584.802835844054;3585.408407612819;5005.311069858005;5040.420351659606;5072.771725272298;5154.118746657518;5154.768035438339;4892.217245240744;4896.2410826321975;4904.656674032926;4922.237053256751;4933.407991041098;4940.456549779593;4958.429780575136;4959.280904268789;4962.5533701967;4993.275974983534;4674.680976506718;4882.584050625421;4894.4616092230535;4901.90196196067;4906;4880.41435027799;4933.901321973469;5028.969061342489;5073.365829271341;5080.974595127001;4957.7273249971595;4984.800607975641;5008.37227900941;5015.398152221756;5048.217526283432 09:58:17 INFO - PROCESS | 4307 | |5;yui.html;618.789611532079;637.9872402551946;662.7746566167502;719.2016861283973;738.287552511826;12955.676108097303;13571.796423053638;13585.592432227024;13601.31993400331;13605.183688978668;13331.200127992328;13442.39509222085;13451.192928424301;13487.527936522223;13494.257815820127;614.8217017065045;745.7240820896276;752.1575835064722;767.2979224010052;774.7133560582564;765.9272369124924;776.8718161503346;777.110208810914;777.2887807655987;777.3237283563279;821.2321479416735;824.6371596497522;824.773187373471;826.277007618334;826.4380221449412;654.2998991079547;787.9921200787976;812.7196117339508;818.3575892924056;819.2585709932521;600.393602461517;600.8858316919772;601.0593421295658;601.1493736363041;601.6209787833636;13016.41426135826;13069.477220911152;13076.86923130766;13090.541831035964;13116.737665246737;12272.631821045383;12794.168379055332;12907.67730806728;13046.934765326112;13486.258255795927;11823.763524729457;12074.879251207463;12271.815922761165;12457.065720070941;12485.625431237077;4123.484564429446;4177.352510360899;4308.310670292738;4308.82764689412;4311.568843115663;66985.3301466984;67501.32498675;67589.66205168943;67741.66129169322;68643.65678171627;10080.14318782897;10363.06732394088;10400.531976061095;10540.947295263628;11015.449227538751;16139.677206455804;16781.748273775902;19792.109355078766;19965.20139194416;20049.298274560322;7043.436525077981;7110.608916509538;7118.074650295428;7150.284971502912;7353.889691654629;3684.9447258291148;3763.0592351912023;3767.6232376762105;3801.1827457096856;3901.1612503311926;2897.6233089698626;3012.4426981008583;3015.336625942289;3017.411604737099;3023.486007378751;513.0610981903109;523.1081006883331;528.6854321678595;532.4090259811607;534.2120372450604;512.5822454699409;584.243404790797;585.8652509922741;606.672396905666;636.6434796513967;8245.321935339627;8433.915660843313;8537.317014638815;8572.271356934605;8703.521306328086;494.5153749325681;506.73143234085256;550.7218854478543;612.4640939178219;619.8295468746131;4031.274370613318;4177.811998460076;4245.61789438952;4260.744355338689;4286.635635970915;102929;103570;103656;108071;108316.45841770741;54021.72989135029;68849.3115068853;70876.64561677261;71164;78622.6068869652;44086;52191.217131743055;66246;71621.6418917902;73234.63382683053;8243.752687419386;8711.346649001223;8833.249173820297;8907.376483646083;9239;44385.33421998737;45758.73859615064;45925.54074459346;47239.055218896116;47702.04595907993;57384.71307643435;63805;64956.67521662361;68035;68070;11445.542178312775;11455.942720286344;11512.827307590225;11723.472443740222;11957.820632690691;13395.866041339463;13581.185128892299;13636.181829090287;13985.160890346206;14322.355494002586;7826.647800849089;12200.267983920992;12381.938090309492;12580.245185288912;13130.934345328213;12162.817557736464;12341.321227332224;12380.81428778551;12506.874931250572;13298.135621184592;11090.944545277222;11980.940095299467;12969.027322950627;12986.805197921849;13083.803742943674;10025.248106392195;10316.27786054969;10679.786404272027;10694.19793515474;10698.03717665414;9835.60657573689;10690;10743.516541755796;10765;10862.891371086187 09:58:17 INFO - PROCESS | 4307 | __end_tp_report 09:58:17 INFO - PROCESS | 4307 | __start_cc_report 09:58:17 INFO - PROCESS | 4307 | _x_x_mozilla_cycle_collect,1092 09:58:17 INFO - PROCESS | 4307 | __end_cc_report 09:58:17 INFO - PROCESS | 4307 | __startTimestamp1470848297452__endTimestamp 09:58:17 INFO - PROCESS | 4307 | 09:58:17 INFO - PROCESS | 4307 | ------- Summary: start ------- 09:58:17 INFO - PROCESS | 4307 | Number of tests: 6 09:58:17 INFO - PROCESS | 4307 | 09:58:17 INFO - PROCESS | 4307 | [#0] dojo.html Cycles:165 Average:4674.95 Median:4802.38 stddev:494.31 (10.3%) stddev-sans-first:469.28 09:58:17 INFO - PROCESS | 4307 | Values: 2637.8 2643.4 2664.7 2675.5 2682.1 4624.4 4696.0 4706.0 4798.0 4829.0 4650.3 4694.0 4716.1 4752.4 4754.7 4224.0 4280.8 4290.2 4324.6 4498.8 4396.4 4444.4 4468.3 4475.6 4490.6 4251.4 4286.6 4296.9 4345.1 4349.6 4343.0 4347.3 4347.4 4347.6 4347.7 4132.6 4172.4 4311.8 4319.2 4322.1 4534.7 4572.0 4607.5 4620.6 4651.6 4955.6 4991.7 4993.8 4998.1 5003.9 4882.1 4954.6 4997.6 5020.5 5021.7 4695.5 4713.9 4746.6 4764.1 4766.7 5164.8 5198.7 5202.9 5225.9 5232.1 4734.5 4952.3 5000.8 5004.8 5018.8 5107.5 5135.4 5168.9 5179.6 5189.8 5074.0 5074.5 5075.5 5076.2 5114.8 4867.1 4926.6 4955.8 5058.0 5099.5 4285.8 4286.3 4324.1 4325.0 4335.8 3735.7 3743.8 3753.6 3787.4 3788.6 4264.6 4299.1 4338.0 4339.9 4345.7 5100.1 5115.3 5135.2 5177.0 5184.6 4365.2 4375.4 4380.6 4380.9 4422.0 4459.3 4528.7 4599.7 4609.0 4643.3 4771.3 4810.6 4825.9 4875.8 4876.7 5103.1 5117.2 5119.8 5124.6 5171.2 4635.4 4763.4 4816.2 4825.8 4878.1 5014.8 5034.4 5056.2 5097.1 5112.3 5123.6 5129.4 5136.9 5139.7 5154.3 4700.0 4844.8 4983.1 4993.4 5002.4 4844.6 4903.0 4916.2 4934.5 4946.9 4838.5 4874.9 4876.9 4879.6 4885.4 4793.1 4850.6 4932.4 4945.0 4945.3 4765.7 4790.4 4802.4 4846.2 4857.8 09:58:17 INFO - PROCESS | 4307 | 09:58:17 INFO - PROCESS | 4307 | [#1] ext.html Cycles:180 Average:47948.08 Median:35555.64 stddev:87892.71 (247.2%) stddev-sans-first:88067.25 09:58:17 INFO - PROCESS | 4307 | Values: 558.3 568.8 569.3 578.2 580.6 28647.6 39265.6 40474.6 40567.2 44588.3 40001.0 40646.2 40675.0 40694.2 40737.8 7723.3 7854.6 7862.0 7862.0 7864.5 7455.8 9828.4 10112.8 10116.8 10438.8 9760.0 9766.4 9828.4 9832.4 9833.6 9994.9 10065.4 10069.8 10070.8 10071.3 9809.3 9913.0 9913.4 9915.3 9915.4 37781.8 38089.0 38102.6 38104.6 38160.6 2988.0 3026.7 3097.4 3236.9 3645.1 29319.9 29380.3 29446.4 30407.4 30699.5 3437.6 3498.2 3504.6 3508.4 3581.0 262931.0 264830.0 266740.0 266907.0 267446.0 51566.2 51695.0 52103.7 52152.7 52205.0 34513.7 35413.8 35456.6 54329.5 54578.0 13655.7 13746.0 13750.1 13759.7 13766.2 9767.1 10786.9 10969.2 11136.7 11156.4 18078.0 18222.2 18224.5 18229.7 18402.2 6925.6 6968.8 6978.8 6980.3 7025.4 798.3 825.4 900.0 911.8 976.6 26980.3 28044.7 28073.7 28417.9 28748.9 986.9 993.9 998.7 1027.0 1031.5 14156.8 14984.7 15115.5 15209.2 15264.0 414353.0 415357.0 416476.0 417554.0 418110.0 48154.0 48756.5 48905.8 49005.5 49090.0 1077.5 1131.9 1166.9 1194.2 1215.0 27179.2 29220.0 29501.7 29641.6 30049.5 41367.0 41732.2 41734.6 41740.8 41820.8 298241.0 298532.0 300932.0 301449.0 302031.0 24571.4 25023.6 25391.1 25426.7 26901.2 40793.8 41543.0 41803.0 42145.0 42167.4 35278.5 35548.5 35642.3 36074.5 36411.3 34996.5 35056.8 35721.1 36018.3 36024.6 34943.7 35482.5 35562.8 35929.3 36135.5 30702.4 32710.7 32926.3 33003.3 33030.5 24800.4 25371.9 25418.4 25642.5 25846.2 09:58:17 INFO - PROCESS | 4307 | 09:58:17 INFO - PROCESS | 4307 | [#2] jquery.html Cycles:180 Average:24068.46 Median:23891.58 stddev:67129.27 (281.0%) stddev-sans-first:67293.94 09:58:17 INFO - PROCESS | 4307 | Values: 342.3 367.2 371.2 372.1 393.0 12798.8 16225.1 16437.2 16453.9 17420.0 15952.8 16224.1 16370.8 16374.9 16580.8 4779.8 4973.2 5225.2 5450.9 5491.5 3429.6 5202.4 5463.0 5502.2 5509.3 5443.3 5507.1 5519.9 5522.7 5524.0 5502.9 5523.6 5552.7 6040.2 6087.3 5410.6 5412.8 5413.8 5416.3 5418.9 12065.0 17154.9 18671.2 19167.9 19365.3 1814.0 1886.9 1912.9 1931.6 1974.8 8997.8 9401.2 9488.6 9586.0 12484.8 1614.1 1685.4 1695.0 1709.3 1721.1 43437.3 59899.0 65767.0 69029.3 69181.3 9837.2 10097.5 10100.0 10457.4 10568.3 14056.6 14073.9 14092.5 14092.8 14110.3 7367.1 7404.8 7486.4 7577.2 7596.2 7061.5 7089.4 7116.4 7266.5 7266.7 4345.8 4425.8 4426.3 4436.3 4480.3 1843.4 1907.2 1916.3 1926.2 1930.1 765.5 810.2 819.9 863.5 902.2 16613.0 17111.3 17230.4 17290.6 17680.6 791.8 816.3 835.2 862.5 877.6 5734.7 6051.1 6192.1 6292.7 8836.2 391530.0 408278.0 409076.0 415687.0 418207.0 56425.7 67772.0 69124.3 69568.0 75228.2 403.2 423.3 423.6 424.1 426.1 9761.5 10047.5 10297.6 10383.4 10521.1 37266.4 37279.4 37524.8 37885.0 38298.6 49442.8 50628.0 51063.0 52259.5 53291.5 7644.0 7777.7 7988.2 8000.2 8187.3 11259.2 12281.3 12314.9 12535.7 12622.6 9349.8 10201.9 10443.4 10700.1 11200.5 10185.6 10313.7 10528.2 10546.3 10583.9 7792.9 8317.0 10750.2 10774.4 10878.3 7755.7 7843.3 7886.1 7995.0 8095.8 7647.0 9942.2 10102.7 10203.1 10344.4 09:58:17 INFO - PROCESS | 4307 | 09:58:17 INFO - PROCESS | 4307 | [#3] mootools.html Cycles:180 Average:5193.56 Median:5367.87 stddev:2951.87 (55.0%) stddev-sans-first:2946.27 09:58:17 INFO - PROCESS | 4307 | Values: 1384.8 1430.4 1483.0 1694.0 1698.2 7713.9 8165.2 8218.3 8232.7 8237.5 6435.6 7616.7 7870.4 7907.6 8036.2 2150.9 2169.4 2172.2 2173.5 2178.2 2124.9 2152.8 2219.9 2220.6 2221.3 2181.9 2195.8 2218.0 2223.5 2228.7 2153.7 2156.4 2156.6 2163.8 2164.8 2128.5 2160.6 2165.8 2167.3 2169.4 7881.1 7980.8 8045.7 8067.9 8078.8 4432.2 4552.2 4556.0 4560.0 4563.5 2892.8 2970.9 3042.2 3056.4 3056.4 911.4 958.4 960.0 963.0 1002.4 11008.1 11076.2 11092.2 11120.6 11135.2 3843.2 4053.7 4168.4 4208.4 4211.5 9346.4 9372.6 9373.9 9376.0 9388.4 4609.1 4616.8 4624.6 4625.1 4627.1 3416.3 4032.5 4160.5 4180.5 4213.5 3551.8 3591.5 3691.7 3737.2 3748.7 1134.6 1167.4 1175.6 1175.9 1182.8 634.7 660.8 682.3 813.2 813.9 5064.5 5365.5 5405.6 5421.5 5452.6 812.8 829.1 829.6 832.9 890.6 3545.6 3618.6 3630.9 3650.5 3733.5 9370.0 9662.3 9677.1 9680.6 9731.4 9619.9 9634.0 9665.7 9668.8 9675.0 7488.3 7623.0 7623.1 7630.3 7637.8 5191.4 5231.2 5354.1 5370.2 5381.4 9156.2 9168.2 9253.4 9368.0 9412.9 8678.4 9422.6 9477.6 9505.7 9520.7 5882.6 6521.4 6525.9 6743.0 6754.4 7509.1 7543.6 7559.5 7566.6 7569.2 6539.9 6618.6 6728.4 6742.3 6904.7 6373.2 6407.9 6471.5 6499.7 6564.8 6268.4 6326.2 6358.6 6361.7 6365.9 4529.6 4541.1 4656.1 4841.9 4882.1 4669.0 4705.6 4786.2 4896.6 4945.0 09:58:17 INFO - PROCESS | 4307 | 09:58:17 INFO - PROCESS | 4307 | [#4] prototype.html Cycles:180 Average:4146.93 Median:4806.09 stddev:1211.82 (25.2%) stddev-sans-first:1179.71 09:58:17 INFO - PROCESS | 4307 | Values: 267.0 270.4 274.1 284.5 285.8 4826.6 4842.7 4855.5 4878.7 4882.0 4560.5 4652.7 4697.2 4759.5 4799.4 3745.3 3764.1 3790.8 3792.6 3797.6 3747.9 3759.0 3771.3 3804.9 3818.2 3541.6 3619.7 3741.0 3745.8 3763.6 3682.2 3703.6 3744.8 3749.3 3766.2 3265.8 3268.9 3275.3 3276.6 3281.7 4503.8 4735.3 4740.5 4751.3 4768.0 4173.4 4211.9 4244.4 4247.7 4251.7 5074.8 5079.8 5096.2 5133.9 5142.4 3538.2 3548.8 3549.2 3551.9 3559.8 5173.6 5228.0 5230.6 5262.2 5281.7 3543.9 3544.3 3548.0 3553.2 3568.4 3600.7 3608.6 3612.3 3613.7 3623.7 3983.9 4210.9 4220.3 4228.4 4230.9 4999.3 5010.5 5029.4 5031.0 5073.4 1250.8 1262.4 1262.9 1263.1 1265.3 789.3 815.2 843.3 843.7 857.3 4420.9 4422.9 4428.2 4454.3 4458.3 5176.2 5184.7 5211.2 5211.8 5214.3 4273.3 4363.8 4382.5 4439.3 4453.4 2738.2 2739.5 2742.4 2744.2 2750.4 4856.3 4872.0 4875.7 4881.2 4907.8 4983.7 5163.9 5168.9 5271.8 5272.4 4819.4 4830.9 4831.3 4840.9 4850.3 5085.5 5092.2 5128.9 5139.6 5154.4 4889.3 4940.7 4967.4 4991.7 5055.7 4812.8 4817.2 4817.3 4820.8 4824.6 3554.3 3582.2 3583.2 3584.8 3585.4 5005.3 5040.4 5072.8 5154.1 5154.8 4892.2 4896.2 4904.7 4922.2 4933.4 4940.5 4958.4 4959.3 4962.6 4993.3 4674.7 4882.6 4894.5 4901.9 4906.0 4880.4 4933.9 5029.0 5073.4 5081.0 4957.7 4984.8 5008.4 5015.4 5048.2 09:58:17 INFO - PROCESS | 4307 | 09:58:17 INFO - PROCESS | 4307 | [#5] yui.html Cycles:180 Average:17687.01 Median:4308.57 stddev:24781.00 (575.2%) stddev-sans-first:24817.37 09:58:17 INFO - PROCESS | 4307 | Values: 618.8 638.0 662.8 719.2 738.3 12955.7 13571.8 13585.6 13601.3 13605.2 13331.2 13442.4 13451.2 13487.5 13494.3 614.8 745.7 752.2 767.3 774.7 765.9 776.9 777.1 777.3 777.3 821.2 824.6 824.8 826.3 826.4 654.3 788.0 812.7 818.4 819.3 600.4 600.9 601.1 601.1 601.6 13016.4 13069.5 13076.9 13090.5 13116.7 12272.6 12794.2 12907.7 13046.9 13486.3 11823.8 12074.9 12271.8 12457.1 12485.6 4123.5 4177.4 4308.3 4308.8 4311.6 66985.3 67501.3 67589.7 67741.7 68643.7 10080.1 10363.1 10400.5 10540.9 11015.4 16139.7 16781.7 19792.1 19965.2 20049.3 7043.4 7110.6 7118.1 7150.3 7353.9 3684.9 3763.1 3767.6 3801.2 3901.2 2897.6 3012.4 3015.3 3017.4 3023.5 513.1 523.1 528.7 532.4 534.2 512.6 584.2 585.9 606.7 636.6 8245.3 8433.9 8537.3 8572.3 8703.5 494.5 506.7 550.7 612.5 619.8 4031.3 4177.8 4245.6 4260.7 4286.6 102929.0 103570.0 103656.0 108071.0 108316.5 54021.7 68849.3 70876.6 71164.0 78622.6 44086.0 52191.2 66246.0 71621.6 73234.6 8243.8 8711.3 8833.2 8907.4 9239.0 44385.3 45758.7 45925.5 47239.1 47702.0 57384.7 63805.0 64956.7 68035.0 68070.0 11445.5 11455.9 11512.8 11723.5 11957.8 13395.9 13581.2 13636.2 13985.2 14322.4 7826.6 12200.3 12381.9 12580.2 13130.9 12162.8 12341.3 12380.8 12506.9 13298.1 11090.9 11980.9 12969.0 12986.8 13083.8 10025.2 10316.3 10679.8 10694.2 10698.0 9835.6 10690.0 10743.5 10765.0 10862.9 09:58:17 INFO - PROCESS | 4307 | -------- Summary: end -------- 09:58:17 INFO - PROCESS | 4307 | 09:58:18 INFO - TEST-INFO | 4307: exit 0 09:58:18 INFO - TEST-OK | dromaeo_css | took 1122679ms 09:58:18 INFO - TEST-START | kraken 09:58:18 INFO - operating with platform_type : linux_ 09:58:18 INFO - Initialising browser for kraken test... 09:58:18 INFO - TEST-INFO | started process 4496 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsC0i6l/profile http://localhost:43685/getInfo.html) 09:58:23 INFO - PROCESS | 4496 | __metrics Screen width/height:1600/1200 09:58:23 INFO - PROCESS | 4496 | colorDepth:24 09:58:23 INFO - PROCESS | 4496 | Browser inner width/height: 1024/697 09:58:23 INFO - PROCESS | 4496 | __metrics 09:58:24 INFO - PROCESS | 4496 | 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" 09:58:24 INFO - PROCESS | 4496 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:58:25 INFO - TEST-INFO | 4496: exit 0 09:58:25 INFO - Browser initialized. 09:58:25 INFO - Running cycle 1/1 for kraken test... 09:58:25 INFO - TEST-INFO | started process 4576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsC0i6l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:58:37 INFO - PROCESS | 4576 | RSS: Main: 157233152 09:58:37 INFO - PROCESS | 4576 | 09:58:39 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43685/tests/kraken/driver.html?testName=audio-beat-detection) 09:58:40 INFO - PROCESS | 4576 | RSS: Main: 212738048 09:58:40 INFO - PROCESS | 4576 | 09:58:43 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43685/tests/kraken/driver.html?testName=audio-dft) 09:58:44 INFO - PROCESS | 4576 | RSS: Main: 188248064 09:58:44 INFO - PROCESS | 4576 | 09:58:47 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43685/tests/kraken/driver.html?testName=audio-fft) 09:58:48 INFO - PROCESS | 4576 | RSS: Main: 270553088 09:58:48 INFO - PROCESS | 4576 | 09:58:50 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43685/tests/kraken/driver.html?testName=audio-oscillator) 09:58:50 INFO - PROCESS | 4576 | RSS: Main: 179601408 09:58:50 INFO - PROCESS | 4576 | 09:58:53 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43685/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:58:53 INFO - PROCESS | 4576 | RSS: Main: 184561664 09:58:53 INFO - PROCESS | 4576 | 09:58:56 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43685/tests/kraken/driver.html?testName=imaging-darkroom) 09:58:57 INFO - PROCESS | 4576 | RSS: Main: 220680192 09:58:57 INFO - PROCESS | 4576 | 09:59:00 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43685/tests/kraken/driver.html?testName=imaging-desaturate) 09:59:01 INFO - PROCESS | 4576 | RSS: Main: 214552576 09:59:01 INFO - PROCESS | 4576 | 09:59:04 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43685/tests/kraken/driver.html?testName=json-parse-financial) 09:59:05 INFO - PROCESS | 4576 | RSS: Main: 217620480 09:59:05 INFO - PROCESS | 4576 | 09:59:06 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43685/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:59:07 INFO - PROCESS | 4576 | RSS: Main: 183554048 09:59:07 INFO - PROCESS | 4576 | 09:59:09 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:59:09 INFO - PROCESS | 4576 | RSS: Main: 189079552 09:59:09 INFO - PROCESS | 4576 | 09:59:12 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:59:12 INFO - PROCESS | 4576 | RSS: Main: 193638400 09:59:12 INFO - PROCESS | 4576 | 09:59:15 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:59:16 INFO - PROCESS | 4576 | RSS: Main: 188334080 09:59:16 INFO - PROCESS | 4576 | 09:59:19 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:59:19 INFO - PROCESS | 4576 | RSS: Main: 192073728 09:59:19 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43685/tests/kraken/driver.html?testName=ai-astar) 09:59:21 INFO - PROCESS | 4576 | RSS: Main: 243040256 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | __start_tp_report 09:59:21 INFO - PROCESS | 4576 | _x_x_mozilla_page_load 09:59:21 INFO - PROCESS | 4576 | _x_x_mozilla_page_load_details 09:59:21 INFO - PROCESS | 4576 | |i|pagename|runs| 09:59:21 INFO - PROCESS | 4576 | |0;ai-astar;104;116;113;112;112;121;113;112;112;112 09:59:21 INFO - PROCESS | 4576 | |1;audio-beat-detection;145;120;122;173;123;122;148;127;188;120 09:59:21 INFO - PROCESS | 4576 | |2;audio-dft;163;161;161;160;161;161;161;160;160;158 09:59:21 INFO - PROCESS | 4576 | |3;audio-fft;75;76;75;122;77;77;74;76;98;74 09:59:21 INFO - PROCESS | 4576 | |4;audio-oscillator;85;85;123;86;85;85;123;87;85;125 09:59:21 INFO - PROCESS | 4576 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 09:59:21 INFO - PROCESS | 4576 | |6;imaging-darkroom;147;146;146;147;146;146;147;146;146;146 09:59:21 INFO - PROCESS | 4576 | |7;imaging-desaturate;95;95;96;96;95;95;96;95;95;95 09:59:21 INFO - PROCESS | 4576 | |8;json-parse-financial;64;63;63;71;63;63;65;63;64;63 09:59:21 INFO - PROCESS | 4576 | |9;json-stringify-tinderbox;65;65;65;64;65;66;66;65;71;66 09:59:21 INFO - PROCESS | 4576 | |10;stanford-crypto-aes;79;79;81;91;80;81;80;80;80;84 09:59:21 INFO - PROCESS | 4576 | |11;stanford-crypto-ccm;132;132;143;131;131;135;143;136;155;128 09:59:21 INFO - PROCESS | 4576 | |12;stanford-crypto-pbkdf2;171;168;170;168;167;168;165;167;169;166 09:59:21 INFO - PROCESS | 4576 | |13;stanford-crypto-sha256-iterative;68;67;72;68;68;67;70;70;67;68 09:59:21 INFO - PROCESS | 4576 | __end_tp_report 09:59:21 INFO - PROCESS | 4576 | __start_cc_report 09:59:21 INFO - PROCESS | 4576 | _x_x_mozilla_cycle_collect,1944 09:59:21 INFO - PROCESS | 4576 | __end_cc_report 09:59:21 INFO - PROCESS | 4576 | __startTimestamp1470848361664__endTimestamp 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | ------- Summary: start ------- 09:59:21 INFO - PROCESS | 4576 | Number of tests: 14 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#0] ai-astar Cycles:10 Average:112.70 Median:112.50 stddev:4.19 (3.7%) stddev-sans-first:3.04 09:59:21 INFO - PROCESS | 4576 | Values: 104.0 116.0 113.0 112.0 112.0 121.0 113.0 112.0 112.0 112.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:136.00 stddev:24.43 (18.0%) stddev-sans-first:25.81 09:59:21 INFO - PROCESS | 4576 | Values: 145.0 120.0 122.0 173.0 123.0 122.0 148.0 127.0 188.0 120.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.26 (0.8%) stddev-sans-first:1.00 09:59:21 INFO - PROCESS | 4576 | Values: 163.0 161.0 161.0 160.0 161.0 161.0 161.0 160.0 160.0 158.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:15.64 (20.6%) stddev-sans-first:16.36 09:59:21 INFO - PROCESS | 4576 | Values: 75.0 76.0 75.0 122.0 77.0 77.0 74.0 76.0 98.0 74.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:85.00 stddev:18.49 (21.8%) stddev-sans-first:19.10 09:59:21 INFO - PROCESS | 4576 | Values: 85.0 85.0 123.0 86.0 85.0 85.0 123.0 87.0 85.0 125.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:59:21 INFO - PROCESS | 4576 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 09:59:21 INFO - PROCESS | 4576 | Values: 147.0 146.0 146.0 147.0 146.0 146.0 147.0 146.0 146.0 146.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:59:21 INFO - PROCESS | 4576 | Values: 95.0 95.0 96.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:63.50 stddev:2.49 (3.9%) stddev-sans-first:2.64 09:59:21 INFO - PROCESS | 4576 | Values: 64.0 63.0 63.0 71.0 63.0 63.0 65.0 63.0 64.0 63.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:65.50 stddev:1.93 (2.9%) stddev-sans-first:2.03 09:59:21 INFO - PROCESS | 4576 | Values: 65.0 65.0 65.0 64.0 65.0 66.0 66.0 65.0 71.0 66.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:80.50 stddev:3.63 (4.5%) stddev-sans-first:3.73 09:59:21 INFO - PROCESS | 4576 | Values: 79.0 79.0 81.0 91.0 80.0 81.0 80.0 80.0 80.0 84.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#11] stanford-crypto-ccm Cycles:10 Average:136.60 Median:135.50 stddev:8.18 (6.0%) stddev-sans-first:8.51 09:59:21 INFO - PROCESS | 4576 | Values: 132.0 132.0 143.0 131.0 131.0 135.0 143.0 136.0 155.0 128.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.00 stddev:1.79 (1.1%) stddev-sans-first:1.51 09:59:21 INFO - PROCESS | 4576 | Values: 171.0 168.0 170.0 168.0 167.0 168.0 165.0 167.0 169.0 166.0 09:59:21 INFO - PROCESS | 4576 | 09:59:21 INFO - PROCESS | 4576 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:1.65 (2.4%) stddev-sans-first:1.74 09:59:21 INFO - PROCESS | 4576 | Values: 68.0 67.0 72.0 68.0 68.0 67.0 70.0 70.0 67.0 68.0 09:59:21 INFO - PROCESS | 4576 | -------- Summary: end -------- 09:59:21 INFO - PROCESS | 4576 | 09:59:22 INFO - TEST-INFO | 4576: exit 0 09:59:22 INFO - TEST-OK | kraken | took 64531ms 09:59:22 INFO - SUITE-END | took 1187s 09:59:23 INFO - Completed test suite (00:19:47) 09:59:23 INFO - javascript benchmark 09:59:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2637.77578905793, 2643.405233822389, 2664.72020437854, 2675.5050315691587, 2682.114902082313, 4624.352590637305, 4695.976520117403, 4705.9529404705945, 4798.040391921612, 4828.951710482894, 4650.302454631798, 4694.037722266936, 4716.10394025135, 4752.429708434984, 4754.738489383082, 4224.028473451101, 4280.764557949312, 4290.227759003378, 4324.6107850293465, 4498.752568608726, 4396.428464299636, 4444.355568442574, 4468.285074388098, 4475.619572336339, 4490.550944905516, 4251.447311849471, 4286.57134286572, 4296.871093867189, 4345.109252603209, 4349.565043495657, 4343.04453020335, 4347.3261644445165, 4347.3696314034605, 4347.630451411634, 4347.739135651871, 4132.648724858361, 4172.395002724618, 4311.827526898952, 4319.244132276839, 4322.0707547877355, 4534.705244159152, 4571.977140114312, 4607.539246075399, 4620.607248383892, 4651.558101980307, 4955.60355171589, 4991.700497970097, 4993.7503124843615, 4998.075356059141, 5003.924941125886, 4882.1456245157315, 4954.603631709455, 4997.550220480138, 5020.497950204986, 5021.748912554358, 4695.5069717679835, 4713.929291060636, 4746.572808447257, 4764.14245435818, 4766.666333356632, 5164.767585458663, 5198.714070726146, 5202.92195617066, 5225.921611175835, 5232.058229518723, 4734.526547345238, 4952.331435256195, 5000.774965126578, 5004.824831130893, 5018.824341148115, 5107.514786095315, 5135.35808023997, 5168.870778230575, 5179.61152913533, 5189.792408303701, 5074.035933172685, 5074.4671809460215, 5075.5178258065425, 5076.18780995039, 5114.820981265638, 4867.148249056401, 4926.605871530341, 4955.752212389366, 5057.974710126426, 5099.464556221617, 4285.8285668573535, 4286.292761694285, 4324.113556720865, 4324.956750432519, 4335.826566937287, 3735.701143908481, 3743.794091325003, 3753.6246375362243, 3787.3561494545993, 3788.6021967693546, 4264.616184543406, 4299.07569872479, 4338.0239446125, 4339.934900976488, 4345.717528360647, 5100.056489549445, 5115.283860259567, 5135.229715542699, 5177.0, 5184.559312458483, 4365.192439398721, 4375.365571992043, 4380.649548036149, 4380.8685739427865, 4421.977890110529, 4459.286514157784, 4528.728276303366, 4599.701019433726, 4608.953910460919, 4643.2802915548145, 4771.260454629538, 4810.59109975642, 4825.879353016062, 4875.756212189447, 4876.731779752147, 5103.10695628271, 5117.181250999822, 5119.820806271762, 5124.615653825904, 5171.198464238049, 4635.374224479787, 4763.428388593391, 4816.181249187576, 4825.8069677213925, 4878.121938051043, 5014.774335155, 5034.370703662043, 5056.165732654218, 5097.133487307241, 5112.258722485293, 5123.5644970177955, 5129.410117836496, 5136.897262054813, 5139.665921715226, 5154.329937108152, 4700.0, 4844.781984810763, 4983.053219888209, 4993.400791904995, 5002.374703162105, 4844.636652251166, 4903.0, 4916.213405855046, 4934.50654934518, 4946.9364086721525, 4838.540338667821, 4874.926876096791, 4876.878078048077, 4879.560839524318, 4885.413750349981, 4793.089313030652, 4850.563449289581, 4932.383452068491, 4945.035718034948, 4945.307656927961, 4765.69023013503, 4790.4251489821445, 4802.375691160127, 4846.224604063333, 4857.757112144449], "value": 4643.505185524355, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.3355806590157, 568.8293511946415, 569.3310360326615, 578.2222910185801, 580.5964854426173, 28647.570286445694, 39265.60734392655, 40474.59525404753, 40567.18865622689, 44588.3311750324, 40001.0, 40646.18707625846, 40675.0, 40694.186116277655, 40737.79631101848, 7723.266289702483, 7854.607269636495, 7861.960690196541, 7861.977942867447, 7864.4888082274465, 7455.813604659873, 9828.36115652484, 10112.848307275397, 10116.848247276297, 10438.843417348708, 9759.95120024399, 9766.36518626287, 9828.361156524803, 9832.360896541737, 9833.557489912935, 9994.90005099947, 10065.396076235449, 10069.79860402788, 10070.798584028278, 10071.345362551485, 9809.313348065638, 9912.950435247849, 9913.405195688281, 9915.305928585001, 9915.405075695482, 37781.81109094465, 38089.0, 38102.61897381018, 38104.618953810386, 38160.61839381598, 2988.0, 3026.6821983691834, 3097.38052389523, 3236.91907702307, 3645.0887278180453, 29319.853400733075, 29380.266984860828, 29446.411071778446, 30407.391852163055, 30699.539506907415, 3437.5874895012507, 3498.2175795874373, 3504.56192975878, 3508.438649816017, 3581.015220814271, 262931.0, 264830.0, 266740.0, 266907.0, 267446.0, 51566.226506602434, 51695.0, 52103.65533550999, 52152.739236303576, 52205.0, 34513.65486345155, 35413.82293088518, 35456.64543354585, 54329.456705432436, 54578.0, 13655.726885462434, 13746.0, 13750.106243094167, 13759.656008599864, 13766.24285664278, 9767.072128147815, 10786.892131078746, 10969.23215374916, 11136.665900023012, 11156.386398748147, 18078.0, 18222.180001899946, 18224.453266401764, 18229.7265541017, 18402.171902264432, 6925.6190909499455, 6968.756093536702, 6978.825529361807, 6980.2883053938385, 7025.367716905503, 798.3214267872378, 825.3892119831281, 899.9595018224196, 911.7994041310901, 976.5751897924425, 26980.32549186286, 28044.71955280421, 28073.719262807517, 28417.857910710314, 28748.856255718587, 986.8618393424927, 993.9254555908336, 998.7153661206521, 1026.9691909242736, 1031.4791030529684, 14156.787648185285, 14984.70030599382, 15115.470958516618, 15209.239538023276, 15264.007839490398, 414353.0, 415357.0, 416476.0, 417554.0, 418110.0, 48154.0, 48756.5124348752, 48905.755471222416, 49005.50994490009, 49090.0, 1077.49357801833, 1131.9490622922153, 1166.859976802789, 1194.164085140388, 1214.9939250303692, 27179.184624461297, 29220.0, 29501.704982949897, 29641.5553766698, 30049.549256761602, 41367.0, 41732.1653566933, 41734.58265417307, 41740.791296043324, 41820.79089604654, 298241.0, 298532.0, 300932.0, 301449.0, 302031.0, 24571.38571535726, 25023.624645629967, 25391.11131110402, 25426.745732542437, 26901.192964211106, 40793.796031020844, 41543.0, 41803.0, 42145.0, 42167.36748948829, 35278.47082293716, 35548.46677299791, 35642.28715425729, 36074.45888311625, 36411.27177456489, 34996.47505287474, 35056.82471587728, 35721.1069723259, 36018.29862760356, 36024.63975360318, 34943.65056349404, 35482.467762984095, 35562.82218588891, 35929.28141437209, 36135.45796812997, 30702.385952280383, 32710.672893271716, 32926.34147317088, 33003.339933201685, 33030.5045424314, 24800.379990500383, 25371.87314063418, 25418.364540886625, 25642.48715025652, 25846.22461326163], "value": 17867.039493372897, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.2715287796626, 367.199505078928, 371.16117574282123, 372.1236488070594, 392.99017524561884, 12798.808017879739, 16225.107619080945, 16437.178141092958, 16453.917730411333, 17420.0, 15952.84047159528, 16224.10767407792, 16370.83629163708, 16374.918125409386, 16580.83419165808, 4779.808807647699, 4973.229149481836, 5225.216217567377, 5450.91823622646, 5491.450854914517, 3429.6055953565283, 5202.401723801754, 5463.043967305725, 5502.174673798922, 5509.338879334486, 5443.265159203496, 5507.091329930557, 5519.889602207953, 5522.696251706155, 5524.033294173523, 5502.889942201154, 5523.613347065727, 5552.694601796899, 6040.214772079646, 6087.269527656709, 5410.648307860016, 5412.756425960842, 5413.83758487246, 5416.322959630046, 5418.864528386822, 12065.0, 17154.88493247935, 18671.159797808996, 19167.904160479247, 19365.322213722593, 1814.047624996874, 1886.9150888210063, 1912.913918873654, 1931.5847092874958, 1974.7827738948704, 8997.820043599091, 9401.153896149312, 9488.620455181785, 9585.952070239604, 12484.75030499385, 1614.079974414584, 1685.4101064627405, 1694.9745753813702, 1709.2991873331873, 1721.0534206186546, 43437.34843977343, 59899.0, 65767.0, 69029.3097069033, 69181.3081869185, 9837.163841073589, 10097.495125243855, 10100.0, 10457.424841633783, 10568.313059651098, 14056.648583785283, 14073.85926140746, 14092.506762263272, 14092.788608170886, 14110.294485275695, 7367.115946086504, 7404.814879628052, 7486.401087912954, 7577.16651168371, 7596.164421913585, 7061.540999835097, 7089.432845372357, 7116.359527642434, 7266.5466906618885, 7266.745663901738, 4345.804438800262, 4425.822967081281, 4426.291793313055, 4436.312371582345, 4480.305552639346, 1843.3824668735856, 1907.2275728330048, 1916.2909723402151, 1926.2391355414534, 1930.1314408515943, 765.5138986743347, 810.1817164663614, 819.9180081991753, 863.4862256957226, 902.2331018634109, 16613.0, 17111.315547377966, 17230.396936107427, 17290.567735806704, 17680.646387072185, 791.7505985614513, 816.2735165702529, 835.1690068381998, 862.5299211929513, 877.5743764274322, 5734.684592347461, 6051.122587224828, 6192.102145188923, 6292.716827742763, 8836.248918841971, 391530.0, 408278.0, 409076.0, 415687.0, 418207.0, 56425.71787141038, 67772.0, 69124.3087569128, 69568.0, 75228.24771752322, 403.24995508354436, 423.2867618063573, 423.6293243412014, 424.14746359816377, 426.0541597653205, 9761.511924403894, 10047.547860346449, 10297.639582614573, 10383.376997380181, 10521.105706015074, 37266.44100338552, 37279.44080838736, 37524.812375937945, 37885.0, 38298.617013829506, 49442.75278623584, 50628.0, 51063.0, 52259.47740522546, 53291.4670853302, 7644.0, 7777.72777952769, 7988.241117093867, 8000.199980001953, 8187.3040791531, 11259.211855170059, 12281.263124212575, 12314.876851231373, 12535.749285014432, 12622.55821046259, 9349.813003740022, 10201.897981020094, 10443.373397596168, 10700.143988481059, 11200.495977680861, 10185.643502477378, 10313.690589282334, 10528.157747380345, 10546.314489558154, 10583.894161058292, 7792.922070779219, 8317.0, 10750.247482676137, 10774.407407592344, 10878.347299161758, 7755.72854950074, 7843.254890785366, 7886.093099293655, 7994.960025199837, 8095.83808323842, 7646.9617651911385, 9942.204623629948, 10102.69691909244, 10203.132733717717, 10344.37933723979], "value": 7804.572585582265, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.8268966379198, 1430.4206796247518, 1482.9555113346603, 1694.0, 1698.2357938927485, 7713.884291735621, 8165.2243036911395, 8218.260356567907, 8232.711855085074, 8237.464564803284, 6435.581687190341, 7616.695332186706, 7870.409719271049, 7907.565083920396, 8036.2365575270405, 2150.8817015064164, 2169.360038788553, 2172.1528603844513, 2173.521825198454, 2178.172294528085, 2124.851260411772, 2152.806247437738, 2219.8668079915255, 2220.600291947449, 2221.3224966385246, 2181.879996600186, 2195.83531235158, 2217.9778202218013, 2223.5219427823013, 2228.710267665201, 2153.655415133579, 2156.406988078291, 2156.6333723266926, 2163.826893848496, 2164.794344537282, 2128.521082756383, 2160.5678864227216, 2165.761766205716, 2167.295628920607, 2169.39257008038, 7881.0936742274225, 7980.8403831923615, 8045.718399855977, 8067.878981815277, 8078.757637270891, 4432.180046691373, 4552.180607490651, 4555.977220113911, 4559.954400456019, 4563.498015218323, 2892.783041271913, 2970.85145742712, 3042.224232820639, 3056.3734434440885, 3056.419280336751, 911.4030310146865, 958.4488918871607, 959.9856002159974, 963.036963036963, 1002.448653240722, 11008.119350451945, 11076.224664273504, 11092.223544351898, 11120.61077862279, 11135.164862635334, 3843.1545060086737, 4053.7365071270274, 4168.416421700964, 4208.36874468826, 4211.515675697273, 9346.439213647203, 9372.578233979488, 9373.859392109123, 9376.015518370474, 9388.389754665925, 4609.124266389375, 4616.792244349012, 4624.606908412823, 4625.074985003012, 4627.120847039052, 3416.299658570037, 4032.475778148822, 4160.4591403118, 4180.540140584533, 4213.536510983789, 3551.7691350062164, 3591.533100696945, 3691.7231207659534, 3737.177820879402, 3748.681362084199, 1134.6482590397004, 1167.3754541320523, 1175.5826681528106, 1175.9118066145074, 1182.8284898689642, 634.6572850660684, 660.7588230295908, 682.3415404134935, 813.2193094629104, 813.8819871118773, 5064.518385990912, 5365.517103460629, 5405.648632838853, 5421.5391691706645, 5452.618316717871, 812.7846120777998, 829.0755807274845, 829.5727700234385, 832.8917240758663, 890.6081324217324, 3545.556805399325, 3618.565772107364, 3630.891073267753, 3650.470681751131, 3733.4586484960073, 9370.0, 9662.275329350327, 9677.129058384638, 9680.612775489042, 9731.41611503312, 9619.903800961902, 9634.0, 9665.71002869915, 9668.75828104303, 9674.951625241829, 7488.288612581796, 7622.96188519065, 7623.123340815766, 7630.313271805564, 7637.809054773675, 5191.402988656202, 5231.163013917755, 5354.143337066051, 5370.2213179089595, 5381.434949330264, 9156.221721153777, 9168.220701240469, 9253.398529095588, 9368.0, 9412.858807118037, 8678.392512524062, 9422.57598408087, 9477.620895164391, 9505.714828555154, 9520.666776662783, 5882.558808089325, 6521.380468855451, 6525.902111468236, 6742.9662851685425, 6754.3921047106, 7509.098908131059, 7543.585102819178, 7559.508631938907, 7566.621668916642, 7569.243075692607, 6539.901901471387, 6618.569792963442, 6728.394444500019, 6742.292059333699, 6904.723811047502, 6373.171487706569, 6407.871842563216, 6471.546991710535, 6499.707513161825, 6564.770233041821, 6268.435840774352, 6326.2092238470195, 6358.586691865014, 6361.650109244036, 6365.90451143224, 4529.614982726505, 4541.114482675846, 4656.092062047868, 4841.8547443576745, 4882.096812089775, 4668.953310466988, 4705.647076469347, 4786.234202527578, 4896.608271338213, 4944.975275123601], "value": 4156.3804560792805, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.9946601067979, 270.44288765143807, 274.0927529876111, 284.48511675328876, 285.8070802208509, 4826.589739872111, 4842.733649649273, 4855.465898751139, 4878.731669758162, 4881.975590122044, 4560.498345182027, 4652.6510511711585, 4697.224957881946, 4759.4764575896625, 4799.376081109451, 3745.34456470118, 3764.1154328732728, 3790.848366065354, 3792.601776813436, 3797.6012518685557, 3747.850085996557, 3758.9624103759024, 3771.3211621908044, 3804.90487737806, 3818.205443441578, 3541.6458354164633, 3619.6561326673923, 3740.962590374102, 3745.8127093645344, 3763.604821493745, 3682.1531047858875, 3703.5555733311908, 3744.7753134811996, 3749.325121478133, 3766.1902690921447, 3265.804051756902, 3268.869245230188, 3275.2958114005605, 3276.5904261967253, 3281.671832816723, 4503.819847206108, 4735.313379559979, 4740.525947405266, 4751.287306903958, 4767.952320476786, 4173.39485774564, 4211.894702632428, 4244.3633454982, 4247.702660813744, 4251.702380833343, 5074.847754567369, 5079.822206222764, 5096.184610462308, 5133.897322053575, 5142.408623008327, 3538.239278555123, 3548.769329993542, 3549.165946002687, 3551.893443196708, 3559.75081744278, 5173.611979101583, 5228.0, 5230.599802562711, 5262.184361423947, 5281.735913204363, 3543.9291214175573, 3544.3442963051916, 3547.9645203548152, 3553.218291975761, 3568.411212149966, 3600.7037466512033, 3608.6210947850623, 3612.313660404515, 3613.6567026132475, 3623.691986181204, 3983.880483585497, 4210.873673789792, 4220.282551966112, 4228.429162063144, 4230.915381692349, 4999.250112483157, 5010.549050585465, 5029.446760856303, 5030.974845125824, 5073.416557095908, 1250.7617458715806, 1262.4129779652505, 1262.8547717012477, 1263.1031967303318, 1265.3230521670862, 789.2659826361539, 815.2051749544265, 843.2579330189394, 843.6878355008563, 857.2541888556996, 4420.889477763018, 4422.889427764268, 4428.180786554498, 4454.331850222427, 4458.308962110878, 5176.171812510056, 5184.651990482502, 5211.1922651989, 5211.7915283389, 5214.296070030573, 4273.273813090474, 4363.825446982149, 4382.517923028464, 4439.334099884978, 4453.376527286183, 2738.1785464360614, 2739.465804168208, 2742.437800250944, 2744.19046381314, 2750.3674154944474, 4856.2958371036, 4871.95128048715, 4875.731834749123, 4881.217705907598, 4907.803687852446, 4983.700977941335, 5163.896722065613, 5168.948310516922, 5271.815486457956, 5272.420033796279, 4819.445763737145, 4830.8792280193275, 4831.323614693875, 4840.878978025577, 4850.296706977469, 5085.4914508548845, 5092.159793634009, 5128.948710512847, 5139.640225184201, 5154.381474223117, 4889.315495830658, 4940.7035577865445, 4967.379077615298, 4991.700497970133, 5055.7219352935945, 4812.7968007998, 4817.229243321051, 4817.301491283814, 4820.807167713252, 4824.638152138674, 3554.2536067425094, 3582.229820588586, 3583.229605634801, 3584.802835844054, 3585.408407612819, 5005.311069858005, 5040.420351659606, 5072.771725272298, 5154.118746657518, 5154.768035438339, 4892.217245240744, 4896.2410826321975, 4904.656674032926, 4922.237053256751, 4933.407991041098, 4940.456549779593, 4958.429780575136, 4959.280904268789, 4962.5533701967, 4993.275974983534, 4674.680976506718, 4882.584050625421, 4894.4616092230535, 4901.90196196067, 4906.0, 4880.41435027799, 4933.901321973469, 5028.969061342489, 5073.365829271341, 5080.974595127001, 4957.7273249971595, 4984.800607975641, 5008.37227900941, 5015.398152221756, 5048.217526283432], "value": 3754.0783400798186, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.789611532079, 637.9872402551946, 662.7746566167502, 719.2016861283973, 738.287552511826, 12955.676108097303, 13571.796423053638, 13585.592432227024, 13601.31993400331, 13605.183688978668, 13331.200127992328, 13442.39509222085, 13451.192928424301, 13487.527936522223, 13494.257815820127, 614.8217017065045, 745.7240820896276, 752.1575835064722, 767.2979224010052, 774.7133560582564, 765.9272369124924, 776.8718161503346, 777.110208810914, 777.2887807655987, 777.3237283563279, 821.2321479416735, 824.6371596497522, 824.773187373471, 826.277007618334, 826.4380221449412, 654.2998991079547, 787.9921200787976, 812.7196117339508, 818.3575892924056, 819.2585709932521, 600.393602461517, 600.8858316919772, 601.0593421295658, 601.1493736363041, 601.6209787833636, 13016.41426135826, 13069.477220911152, 13076.86923130766, 13090.541831035964, 13116.737665246737, 12272.631821045383, 12794.168379055332, 12907.67730806728, 13046.934765326112, 13486.258255795927, 11823.763524729457, 12074.879251207463, 12271.815922761165, 12457.065720070941, 12485.625431237077, 4123.484564429446, 4177.352510360899, 4308.310670292738, 4308.82764689412, 4311.568843115663, 66985.3301466984, 67501.32498675, 67589.66205168943, 67741.66129169322, 68643.65678171627, 10080.14318782897, 10363.06732394088, 10400.531976061095, 10540.947295263628, 11015.449227538751, 16139.677206455804, 16781.748273775902, 19792.109355078766, 19965.20139194416, 20049.298274560322, 7043.436525077981, 7110.608916509538, 7118.074650295428, 7150.284971502912, 7353.889691654629, 3684.9447258291148, 3763.0592351912023, 3767.6232376762105, 3801.1827457096856, 3901.1612503311926, 2897.6233089698626, 3012.4426981008583, 3015.336625942289, 3017.411604737099, 3023.486007378751, 513.0610981903109, 523.1081006883331, 528.6854321678595, 532.4090259811607, 534.2120372450604, 512.5822454699409, 584.243404790797, 585.8652509922741, 606.672396905666, 636.6434796513967, 8245.321935339627, 8433.915660843313, 8537.317014638815, 8572.271356934605, 8703.521306328086, 494.5153749325681, 506.73143234085256, 550.7218854478543, 612.4640939178219, 619.8295468746131, 4031.274370613318, 4177.811998460076, 4245.61789438952, 4260.744355338689, 4286.635635970915, 102929.0, 103570.0, 103656.0, 108071.0, 108316.45841770741, 54021.72989135029, 68849.3115068853, 70876.64561677261, 71164.0, 78622.6068869652, 44086.0, 52191.217131743055, 66246.0, 71621.6418917902, 73234.63382683053, 8243.752687419386, 8711.346649001223, 8833.249173820297, 8907.376483646083, 9239.0, 44385.33421998737, 45758.73859615064, 45925.54074459346, 47239.055218896116, 47702.04595907993, 57384.71307643435, 63805.0, 64956.67521662361, 68035.0, 68070.0, 11445.542178312775, 11455.942720286344, 11512.827307590225, 11723.472443740222, 11957.820632690691, 13395.866041339463, 13581.185128892299, 13636.181829090287, 13985.160890346206, 14322.355494002586, 7826.647800849089, 12200.267983920992, 12381.938090309492, 12580.245185288912, 13130.934345328213, 12162.817557736464, 12341.321227332224, 12380.81428778551, 12506.874931250572, 13298.135621184592, 11090.944545277222, 11980.940095299467, 12969.027322950627, 12986.805197921849, 13083.803742943674, 10025.248106392195, 10316.27786054969, 10679.786404272027, 10694.19793515474, 10698.03717665414, 9835.60657573689, 10690.0, 10743.516541755796, 10765.0, 10862.891371086187], "value": 6709.826793764717, "unit": "score"}], "name": "dromaeo_css", "value": 6385.643196556953, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 113.0, 112.0, 112.0, 121.0, 113.0, 112.0, 112.0, 112.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 120.0, 122.0, 173.0, 123.0, 122.0, 148.0, 127.0, 188.0, 120.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 160.0, 161.0, 161.0, 161.0, 160.0, 160.0, 158.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 122.0, 77.0, 77.0, 74.0, 76.0, 98.0, 74.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 123.0, 86.0, 85.0, 85.0, 123.0, 87.0, 85.0, 125.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 71.0, 63.0, 63.0, 65.0, 63.0, 64.0, 63.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 65.0, 66.0, 66.0, 65.0, 71.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 81.0, 91.0, 80.0, 81.0, 80.0, 80.0, 80.0, 84.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 132.0, 143.0, 131.0, 131.0, 135.0, 143.0, 136.0, 155.0, 128.0], "value": 136.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 170.0, 168.0, 167.0, 168.0, 165.0, 167.0, 169.0, 166.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 72.0, 68.0, 68.0, 67.0, 70.0, 70.0, 67.0, 68.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1534.5, "alertThreshold": 2.0}]} 09:59:23 INFO - Return code: 0 09:59:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:59:23 INFO - # TBPL SUCCESS # 09:59:23 INFO - Running post-action listener: _resource_record_post_action 09:59:23 INFO - [mozharness: 2016-08-10 16:59:23.447244Z] Finished run-tests step (success) 09:59:23 INFO - Running post-run listener: _resource_record_post_run 09:59:23 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 303845376; Read time: 16; Write time: 702176 09:59:23 INFO - TinderboxPrint: CPU usage
13.0% 09:59:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 09:59:23 INFO - TinderboxPrint: I/O write bytes / time
303,845,376 / 702,176 09:59:23 INFO - TinderboxPrint: CPU idle
8,357.3 (87.0%) 09:59:23 INFO - TinderboxPrint: CPU user
1,202.3 (12.5%) 09:59:23 INFO - TinderboxPrint: Swap in / out
0 / 0 09:59:23 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 13565952; Read time: 0; Write time: 73904 09:59:23 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 290279424; Read time: 0; Write time: 628272 09:59:24 INFO - Running post-run listener: _upload_blobber_files 09:59:24 INFO - Blob upload gear active. 09:59:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:59:24 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 09:59:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:59:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:59:24 INFO - (blobuploader) - INFO - Open directory for files ... 09:59:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:59:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:59:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:32 INFO - (blobuploader) - INFO - Done attempting. 09:59:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:59:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:59:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:33 INFO - (blobuploader) - INFO - Done attempting. 09:59:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:59:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:59:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:34 INFO - (blobuploader) - INFO - Done attempting. 09:59:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:59:34 INFO - Return code: 0 09:59:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:59:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:59:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4763bc0008ac259b855dfec395d76023161b5571c59b0e611ee62ad7fe3be050706de76534fbff2f087cce423f421a3a9db622b6796c5f7c59674b5b35fe1ec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/86e73d70089594a8fcb238ca9b84afaa1efcedffba63ba30e9762b0ccd9b33d4a1a322d87a3e9dd348b7162616cf50d3de279d222d8c66cb5929706a3bbcadb7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/96b840d70bc071c281e56862c2ef059c67dcd20a0bc4e471b080080431f38302515f3a400ef4a6dce26a99ee1d4afc4d2c5bae69120689ffa95cd38ec63e3d69"} 09:59:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:59:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:59:34 INFO - Contents: 09:59:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4763bc0008ac259b855dfec395d76023161b5571c59b0e611ee62ad7fe3be050706de76534fbff2f087cce423f421a3a9db622b6796c5f7c59674b5b35fe1ec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/86e73d70089594a8fcb238ca9b84afaa1efcedffba63ba30e9762b0ccd9b33d4a1a322d87a3e9dd348b7162616cf50d3de279d222d8c66cb5929706a3bbcadb7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/96b840d70bc071c281e56862c2ef059c67dcd20a0bc4e471b080080431f38302515f3a400ef4a6dce26a99ee1d4afc4d2c5bae69120689ffa95cd38ec63e3d69"} 09:59:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:59:34 INFO - Copying logs to upload dir... 09:59:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:59:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.660765 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-08-10 09:59:34.886395) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 09:59:34.889703) ========= 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=df17445c3590ff7897617a0e00000225-1470847121.840588-326218707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4763bc0008ac259b855dfec395d76023161b5571c59b0e611ee62ad7fe3be050706de76534fbff2f087cce423f421a3a9db622b6796c5f7c59674b5b35fe1ec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/86e73d70089594a8fcb238ca9b84afaa1efcedffba63ba30e9762b0ccd9b33d4a1a322d87a3e9dd348b7162616cf50d3de279d222d8c66cb5929706a3bbcadb7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/96b840d70bc071c281e56862c2ef059c67dcd20a0bc4e471b080080431f38302515f3a400ef4a6dce26a99ee1d4afc4d2c5bae69120689ffa95cd38ec63e3d69"} build_url:https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011371 build_url: 'https://queue.taskcluster.net/v1/task/JBGvwixgTWK9MC4_O5jZIg/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-Non-PGO/sha512/4763bc0008ac259b855dfec395d76023161b5571c59b0e611ee62ad7fe3be050706de76534fbff2f087cce423f421a3a9db622b6796c5f7c59674b5b35fe1ec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/86e73d70089594a8fcb238ca9b84afaa1efcedffba63ba30e9762b0ccd9b33d4a1a322d87a3e9dd348b7162616cf50d3de279d222d8c66cb5929706a3bbcadb7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/96b840d70bc071c281e56862c2ef059c67dcd20a0bc4e471b080080431f38302515f3a400ef4a6dce26a99ee1d4afc4d2c5bae69120689ffa95cd38ec63e3d69"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 09:59:34.918730) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 09:59:34.919076) ========= 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=df17445c3590ff7897617a0e00000225-1470847121.840588-326218707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004361 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 09:59:34.998991) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 09:59:34.999316) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 09:59:34.999615) ========= ========= Total master_lag: 0.17 =========