builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-029 starttime: 1470886545.34 results: success (0) buildid: 20160810195121 builduid: 791f40c2093942c1961622511e0eebeb revision: 6b8517876729c3a2fd289df3acc5dc7fa6cc956a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:45.337070) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:45.337937) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:45.338227) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:45.378681) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:45.378958) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-10 20:35:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2016-08-10 20:35:45 (46.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105216 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:45.506052) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:45.506395) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021047 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:45.556430) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 20:35:45.556797) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 6b8517876729c3a2fd289df3acc5dc7fa6cc956a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 6b8517876729c3a2fd289df3acc5dc7fa6cc956a --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=9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-10 20:35:45,687 truncating revision to first 12 chars 2016-08-10 20:35:45,687 Setting DEBUG logging. 2016-08-10 20:35:45,687 attempt 1/10 2016-08-10 20:35:45,687 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/6b8517876729?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 20:35:46,835 unpacking tar archive at: autoland-6b8517876729/testing/mozharness/ program finished with exit code 0 elapsedTime=1.502715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 20:35:47.075202) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:47.075714) ========= script_repo_revision: 6b8517876729c3a2fd289df3acc5dc7fa6cc956a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:47.076362) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:47.076812) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 20:35:47.089579) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-08-10 20:35:47.090169) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:35:47 INFO - MultiFileLogger online at 20160810 20:35:47 in /builds/slave/test 20:35:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 20:35:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:35:47 INFO - {'append_to_log': False, 20:35:47 INFO - 'base_work_dir': '/builds/slave/test', 20:35:47 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 20:35:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:35:47 INFO - 'branch': 'Autoland-Non-PGO', 20:35:47 INFO - 'buildbot_json_path': 'buildprops.json', 20:35:47 INFO - 'config_files': ('talos/linux_config.py',), 20:35:47 INFO - 'default_actions': ('clobber', 20:35:47 INFO - 'read-buildbot-config', 20:35:47 INFO - 'download-and-extract', 20:35:47 INFO - 'populate-webroot', 20:35:47 INFO - 'create-virtualenv', 20:35:47 INFO - 'install', 20:35:47 INFO - 'run-tests'), 20:35:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:35:47 INFO - 'download_minidump_stackwalk': True, 20:35:47 INFO - 'download_symbols': 'ondemand', 20:35:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:35:47 INFO - 'tooltool.py': '/tools/tooltool.py', 20:35:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:35:47 INFO - '/tools/misc-python/virtualenv.py')}, 20:35:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:35:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:35:47 INFO - 'installer_path': 'installer.exe', 20:35:47 INFO - 'log_level': 'info', 20:35:47 INFO - 'log_name': 'talos', 20:35:47 INFO - 'log_to_console': True, 20:35:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:35:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:35:47 INFO - 'opt_config_files': (), 20:35:47 INFO - 'pip_index': False, 20:35:47 INFO - 'sps_profile': False, 20:35:47 INFO - 'sps_profile_interval': 0, 20:35:47 INFO - 'suite': 'dromaeojs', 20:35:47 INFO - 'system_bits': '32', 20:35:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:35:47 INFO - 'title': 'talos-linux64-ix-029', 20:35:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:35:47 INFO - 'use_talos_json': True, 20:35:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:35:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:35:47 INFO - 'work_dir': 'build'} 20:35:47 INFO - [mozharness: 2016-08-11 03:35:47.305616Z] Running clobber step. 20:35:47 INFO - Running pre-action listener: _resource_record_pre_action 20:35:47 INFO - Running main action method: clobber 20:35:47 INFO - rmtree: /builds/slave/test/build 20:35:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:35:47 INFO - Running post-action listener: _resource_record_post_action 20:35:47 INFO - [mozharness: 2016-08-11 03:35:47.758596Z] Finished clobber step (success) 20:35:47 INFO - [mozharness: 2016-08-11 03:35:47.758698Z] Running read-buildbot-config step. 20:35:47 INFO - Running pre-action listener: _resource_record_pre_action 20:35:47 INFO - Running main action method: read_buildbot_config 20:35:47 INFO - Using buildbot properties: 20:35:47 INFO - { 20:35:47 INFO - "project": "", 20:35:47 INFO - "product": "firefox", 20:35:47 INFO - "script_repo_revision": "production", 20:35:47 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 20:35:47 INFO - "repository": "", 20:35:47 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 20:35:47 INFO - "buildid": "20160810195121", 20:35:47 INFO - "pgo_build": "False", 20:35:47 INFO - "basedir": "/builds/slave/test", 20:35:47 INFO - "buildnumber": 254, 20:35:47 INFO - "slavename": "talos-linux64-ix-029", 20:35:47 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:35:47 INFO - "platform": "ubuntu64_hw", 20:35:47 INFO - "scheduler": "tests-autoland-linux64-talos", 20:35:47 INFO - "branch": "autoland", 20:35:47 INFO - "repo_path": "integration/autoland", 20:35:47 INFO - "revision": "6b8517876729c3a2fd289df3acc5dc7fa6cc956a", 20:35:47 INFO - "stage_platform": "linux64", 20:35:47 INFO - "builduid": "791f40c2093942c1961622511e0eebeb", 20:35:47 INFO - "slavebuilddir": "test" 20:35:47 INFO - } 20:35:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 20:35:47 INFO - Running post-action listener: _resource_record_post_action 20:35:47 INFO - [mozharness: 2016-08-11 03:35:47.761140Z] Finished read-buildbot-config step (success) 20:35:47 INFO - [mozharness: 2016-08-11 03:35:47.761230Z] Running download-and-extract step. 20:35:47 INFO - Running pre-action listener: _resource_record_pre_action 20:35:47 INFO - Running main action method: download_and_extract 20:35:47 INFO - mkdir: /builds/slave/test/build/tests 20:35: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')]} 20:35:47 INFO - trying https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:35:47 INFO - Downloading https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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 20:35:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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 20:35:48 INFO - Downloaded 1361 bytes. 20:35:48 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:35:48 INFO - Using the following test package requirements: 20:35:48 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:35:48 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:35:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:35:48 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:35:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:35:48 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:35:48 INFO - u'jsshell-linux-x86_64.zip'], 20:35:48 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:35:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:35:48 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:35:48 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:35:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:35:48 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:35:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:35:48 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:35:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:35:48 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:35:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:35:48 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:35:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:35:48 INFO - trying https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:35:48 INFO - Downloading https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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 20:35:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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 20:35:50 INFO - Downloaded 20262327 bytes. 20:35:50 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'] 20:35:50 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 20:35:51 INFO - Return code: 0 20:35:51 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 20:35: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')]} 20:35:51 INFO - trying https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:35:51 INFO - Downloading https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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 20:35:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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 20:35:52 INFO - Downloaded 20262327 bytes. 20:35:52 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'] 20:35:52 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 20:35:53 INFO - Return code: 0 20:35:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:35:53 INFO - trying https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:35:53 INFO - Downloading https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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 20:35:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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 20:35:55 INFO - Downloaded 14413719 bytes. 20:35:55 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'] 20:35:55 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 20:35:56 INFO - Return code: 0 20:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:35:56 INFO - trying https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:35:56 INFO - Downloading https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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 20:35:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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 20:36:01 INFO - Downloaded 55627756 bytes. 20:36:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:36:01 INFO - mkdir: /builds/slave/test/properties 20:36:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:36:01 INFO - Writing to file /builds/slave/test/properties/build_url 20:36:01 INFO - Contents: 20:36:01 INFO - build_url:https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:36:02 INFO - Running post-action listener: _resource_record_post_action 20:36:02 INFO - Running post-action listener: set_extra_try_arguments 20:36:02 INFO - [mozharness: 2016-08-11 03:36:02.204038Z] Finished download-and-extract step (success) 20:36:02 INFO - [mozharness: 2016-08-11 03:36:02.204271Z] Running populate-webroot step. 20:36:02 INFO - Running pre-action listener: _resource_record_pre_action 20:36:02 INFO - Running main action method: populate_webroot 20:36:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:36:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:36:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:36:02 INFO - '--tppagecycles', 20:36:02 INFO - '7'], 20:36:02 INFO - 'tests': ['tsvgm']}}, 20:36:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:36:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:36:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:36:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:36:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:02 INFO - 'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:36:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:36:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:02 INFO - 'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['damp', 'tps']}, 20:36:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:02 INFO - 'tests': ['damp', 'tps']}, 20:36:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['dromaeo_dom']}, 20:36:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:36:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['basic_compositor_video']}, 20:36:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:36:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['a11yr', 20:36:02 INFO - 'ts_paint', 20:36:02 INFO - 'tpaint', 20:36:02 INFO - 'sessionrestore', 20:36:02 INFO - 'sessionrestore_no_auto_restore', 20:36:02 INFO - 'tabpaint']}, 20:36:02 INFO - 'other-e10s': {'tests': ['a11yr', 20:36:02 INFO - 'ts_paint', 20:36:02 INFO - 'tpaint', 20:36:02 INFO - 'sessionrestore', 20:36:02 INFO - 'sessionrestore_no_auto_restore', 20:36:02 INFO - 'tabpaint']}, 20:36:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:36:02 INFO - 'ts_paint', 20:36:02 INFO - 'tpaint', 20:36:02 INFO - 'sessionrestore', 20:36:02 INFO - 'sessionrestore_no_auto_restore', 20:36:02 INFO - 'tabpaint']}, 20:36:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:36:02 INFO - 'ts_paint', 20:36:02 INFO - 'tpaint', 20:36:02 INFO - 'sessionrestore', 20:36:02 INFO - 'sessionrestore_no_auto_restore', 20:36:02 INFO - 'tabpaint']}, 20:36:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['a11yr', 20:36:02 INFO - 'ts_paint', 20:36:02 INFO - 'tpaint', 20:36:02 INFO - 'sessionrestore', 20:36:02 INFO - 'sessionrestore_no_auto_restore', 20:36:02 INFO - 'tabpaint']}, 20:36:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['a11yr', 20:36:02 INFO - 'ts_paint', 20:36:02 INFO - 'tpaint', 20:36:02 INFO - 'sessionrestore', 20:36:02 INFO - 'sessionrestore_no_auto_restore', 20:36:02 INFO - 'tabpaint']}, 20:36:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['tsvgx', 20:36:02 INFO - 'tsvgr_opacity', 20:36:02 INFO - 'tart', 20:36:02 INFO - 'tscrollx', 20:36:02 INFO - 'cart']}, 20:36:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:36:02 INFO - 'tsvgr_opacity', 20:36:02 INFO - 'tart', 20:36:02 INFO - 'tscrollx', 20:36:02 INFO - 'cart']}, 20:36:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:02 INFO - 'talos_options': ['--disable-e10s'], 20:36:02 INFO - 'tests': ['tp5o']}, 20:36:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:02 INFO - 'tests': ['tp5o']}, 20:36:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:02 INFO - 'talos_options': ['--disable-e10s', 20:36:02 INFO - '--xperf_path', 20:36:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:36:02 INFO - 'tests': ['tp5n']}, 20:36:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:02 INFO - 'talos_options': ['--xperf_path', 20:36:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:36:02 INFO - 'tests': ['tp5n']}}, 20:36:02 INFO - 'talos.zip': {'path': '', 20:36:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:36:02 INFO - Running post-action listener: _resource_record_post_action 20:36:02 INFO - [mozharness: 2016-08-11 03:36:02.225586Z] Finished populate-webroot step (success) 20:36:02 INFO - [mozharness: 2016-08-11 03:36:02.225674Z] Running create-virtualenv step. 20:36:02 INFO - Running pre-action listener: _resource_record_pre_action 20:36:02 INFO - Running main action method: create_virtualenv 20:36:02 INFO - Creating virtualenv /builds/slave/test/build/venv 20:36:02 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 20:36:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:36:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:36:02 INFO - Using real prefix '/usr' 20:36:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:36:02 INFO - Installing distribute.............................................................................................................................................................................................done. 20:36:04 INFO - Installing pip.................done. 20:36:04 INFO - Return code: 0 20:36:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:36:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:36:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:36:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:04 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 0x1dd9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', '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 20:36:04 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 20:36:04 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 20:36:04 INFO - Using env: {'DISPLAY': ':0', 20:36:04 INFO - 'HOME': '/home/cltbld', 20:36:04 INFO - 'LANG': 'en_US.UTF-8', 20:36:04 INFO - 'LANGUAGE': 'en_US:en', 20:36:04 INFO - 'LOGNAME': 'cltbld', 20:36:04 INFO - 'MAIL': '/var/mail/cltbld', 20:36:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:04 INFO - 'MOZ_NO_REMOTE': '1', 20:36:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:04 INFO - 'NO_EM_RESTART': '1', 20:36:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:04 INFO - 'PWD': '/builds/slave/test', 20:36:04 INFO - 'SHELL': '/bin/bash', 20:36:04 INFO - 'SHLVL': '1', 20:36:04 INFO - 'TERM': 'linux', 20:36:04 INFO - 'TMOUT': '86400', 20:36:04 INFO - 'USER': 'cltbld', 20:36:04 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:04 INFO - '_': '/tools/buildbot/bin/python'} 20:36:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:36:04 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 20:36:04 INFO - Cleaning up... 20:36:05 INFO - Return code: 0 20:36:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:36:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:36:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:36:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:05 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 0x1dd9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', '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 20:36:05 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 20:36:05 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 20:36:05 INFO - Using env: {'DISPLAY': ':0', 20:36:05 INFO - 'HOME': '/home/cltbld', 20:36:05 INFO - 'LANG': 'en_US.UTF-8', 20:36:05 INFO - 'LANGUAGE': 'en_US:en', 20:36:05 INFO - 'LOGNAME': 'cltbld', 20:36:05 INFO - 'MAIL': '/var/mail/cltbld', 20:36:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:05 INFO - 'MOZ_NO_REMOTE': '1', 20:36:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:05 INFO - 'NO_EM_RESTART': '1', 20:36:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:05 INFO - 'PWD': '/builds/slave/test', 20:36:05 INFO - 'SHELL': '/bin/bash', 20:36:05 INFO - 'SHLVL': '1', 20:36:05 INFO - 'TERM': 'linux', 20:36:05 INFO - 'TMOUT': '86400', 20:36:05 INFO - 'USER': 'cltbld', 20:36:05 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:05 INFO - '_': '/tools/buildbot/bin/python'} 20:36:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:36:05 INFO - Downloading/unpacking psutil>=3.1.1 20:36:05 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 20:36:05 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 20:36:05 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 20:36:05 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 20:36:05 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 20:36:05 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 20:36:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:36:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:36:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:36:07 INFO - Installing collected packages: psutil 20:36:07 INFO - Running setup.py install for psutil 20:36:07 INFO - building 'psutil._psutil_linux' extension 20:36:07 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 20:36:07 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 20:36:07 INFO - building 'psutil._psutil_posix' extension 20:36:07 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 20:36:07 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 20:36:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:36:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:36:07 INFO - Successfully installed psutil 20:36:07 INFO - Cleaning up... 20:36:07 INFO - Return code: 0 20:36:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:36: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')]} 20:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:36:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36: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')]} 20:36:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:36:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', '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 20:36: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:36: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 mozsystemmonitor==0.3 20:36:07 INFO - Using env: {'DISPLAY': ':0', 20:36:07 INFO - 'HOME': '/home/cltbld', 20:36:07 INFO - 'LANG': 'en_US.UTF-8', 20:36:07 INFO - 'LANGUAGE': 'en_US:en', 20:36:07 INFO - 'LOGNAME': 'cltbld', 20:36:07 INFO - 'MAIL': '/var/mail/cltbld', 20:36:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:07 INFO - 'MOZ_NO_REMOTE': '1', 20:36:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:07 INFO - 'NO_EM_RESTART': '1', 20:36:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:07 INFO - 'PWD': '/builds/slave/test', 20:36:07 INFO - 'SHELL': '/bin/bash', 20:36:07 INFO - 'SHLVL': '1', 20:36:07 INFO - 'TERM': 'linux', 20:36:07 INFO - 'TMOUT': '86400', 20:36:07 INFO - 'USER': 'cltbld', 20:36:07 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:07 INFO - '_': '/tools/buildbot/bin/python'} 20:36:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:36:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:36: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 20:36: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 20:36: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 20:36: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 20:36: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 20:36: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 20:36:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:36:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:36:09 INFO - Installing collected packages: mozsystemmonitor 20:36:09 INFO - Running setup.py install for mozsystemmonitor 20:36:09 INFO - Successfully installed mozsystemmonitor 20:36:09 INFO - Cleaning up... 20:36:10 INFO - Return code: 0 20:36:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:36:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:36:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:36:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1dd9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', '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 20:36:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:36:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:36:10 INFO - Using env: {'DISPLAY': ':0', 20:36:10 INFO - 'HOME': '/home/cltbld', 20:36:10 INFO - 'LANG': 'en_US.UTF-8', 20:36:10 INFO - 'LANGUAGE': 'en_US:en', 20:36:10 INFO - 'LOGNAME': 'cltbld', 20:36:10 INFO - 'MAIL': '/var/mail/cltbld', 20:36:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:10 INFO - 'MOZ_NO_REMOTE': '1', 20:36:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:10 INFO - 'NO_EM_RESTART': '1', 20:36:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:10 INFO - 'PWD': '/builds/slave/test', 20:36:10 INFO - 'SHELL': '/bin/bash', 20:36:10 INFO - 'SHLVL': '1', 20:36:10 INFO - 'TERM': 'linux', 20:36:10 INFO - 'TMOUT': '86400', 20:36:10 INFO - 'USER': 'cltbld', 20:36:10 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:10 INFO - '_': '/tools/buildbot/bin/python'} 20:36:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:36:10 INFO - Downloading/unpacking jsonschema==2.5.1 20:36: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 20:36: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 20:36: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 20:36: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 20:36: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 20:36: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 20:36:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:36:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:36:12 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 20:36:12 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 20:36:12 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 20:36:12 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 20:36:12 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 20:36:12 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 20:36:12 INFO - Downloading functools32-3.2.3-2.tar.gz 20:36:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:36:12 INFO - warning: no files found matching '*.txt' 20:36:12 INFO - no previously-included directories found matching 'build' 20:36:12 INFO - no previously-included directories found matching 'dist' 20:36:12 INFO - no previously-included directories found matching '.git*' 20:36:12 INFO - Installing collected packages: jsonschema, functools32 20:36:12 INFO - Running setup.py install for functools32 20:36:12 INFO - warning: no files found matching '*.txt' 20:36:12 INFO - no previously-included directories found matching 'build' 20:36:12 INFO - no previously-included directories found matching 'dist' 20:36:12 INFO - no previously-included directories found matching '.git*' 20:36:12 INFO - Successfully installed jsonschema functools32 20:36:12 INFO - Cleaning up... 20:36:12 INFO - Return code: 0 20:36:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:36:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:36:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1dd9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', '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 20:36:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:36:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:36:12 INFO - Using env: {'DISPLAY': ':0', 20:36:12 INFO - 'HOME': '/home/cltbld', 20:36:12 INFO - 'LANG': 'en_US.UTF-8', 20:36:12 INFO - 'LANGUAGE': 'en_US:en', 20:36:12 INFO - 'LOGNAME': 'cltbld', 20:36:12 INFO - 'MAIL': '/var/mail/cltbld', 20:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:12 INFO - 'MOZ_NO_REMOTE': '1', 20:36:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:12 INFO - 'NO_EM_RESTART': '1', 20:36:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:12 INFO - 'PWD': '/builds/slave/test', 20:36:12 INFO - 'SHELL': '/bin/bash', 20:36:12 INFO - 'SHLVL': '1', 20:36:12 INFO - 'TERM': 'linux', 20:36:12 INFO - 'TMOUT': '86400', 20:36:12 INFO - 'USER': 'cltbld', 20:36:12 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:12 INFO - '_': '/tools/buildbot/bin/python'} 20:36:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:36:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:36:12 INFO - Cleaning up... 20:36:12 INFO - Return code: 0 20:36:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:36:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:36:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1dd9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', '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 20:36:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:36:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:36:12 INFO - Using env: {'DISPLAY': ':0', 20:36:12 INFO - 'HOME': '/home/cltbld', 20:36:12 INFO - 'LANG': 'en_US.UTF-8', 20:36:12 INFO - 'LANGUAGE': 'en_US:en', 20:36:12 INFO - 'LOGNAME': 'cltbld', 20:36:12 INFO - 'MAIL': '/var/mail/cltbld', 20:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:12 INFO - 'MOZ_NO_REMOTE': '1', 20:36:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:12 INFO - 'NO_EM_RESTART': '1', 20:36:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:12 INFO - 'PWD': '/builds/slave/test', 20:36:12 INFO - 'SHELL': '/bin/bash', 20:36:12 INFO - 'SHLVL': '1', 20:36:12 INFO - 'TERM': 'linux', 20:36:12 INFO - 'TMOUT': '86400', 20:36:12 INFO - 'USER': 'cltbld', 20:36:12 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:12 INFO - '_': '/tools/buildbot/bin/python'} 20:36:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:36:12 INFO - Downloading/unpacking blobuploader==1.2.4 20:36:12 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 20:36:12 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 20:36:12 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 20:36:12 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 20:36:12 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 20:36:12 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 20:36:14 INFO - Downloading blobuploader-1.2.4.tar.gz 20:36:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:36:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:36:14 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 20:36:14 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 20:36:14 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 20:36:14 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 20:36:14 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 20:36:14 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 20:36:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:36:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:36:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:36:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:36:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:36:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:36:15 INFO - Downloading docopt-0.6.1.tar.gz 20:36:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:36:15 INFO - Installing collected packages: blobuploader, requests, docopt 20:36:15 INFO - Running setup.py install for blobuploader 20:36:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:36:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:36:15 INFO - Running setup.py install for requests 20:36:16 INFO - Running setup.py install for docopt 20:36:16 INFO - Successfully installed blobuploader requests docopt 20:36:16 INFO - Cleaning up... 20:36:16 INFO - Return code: 0 20:36:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:36:16 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')]} 20:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:36:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:16 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')]} 20:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:36:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:16 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 0x1dd9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', '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 20:36:16 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 20:36:16 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 20:36:16 INFO - Using env: {'DISPLAY': ':0', 20:36:16 INFO - 'HOME': '/home/cltbld', 20:36:16 INFO - 'LANG': 'en_US.UTF-8', 20:36:16 INFO - 'LANGUAGE': 'en_US:en', 20:36:16 INFO - 'LOGNAME': 'cltbld', 20:36:16 INFO - 'MAIL': '/var/mail/cltbld', 20:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:16 INFO - 'MOZ_NO_REMOTE': '1', 20:36:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:16 INFO - 'NO_EM_RESTART': '1', 20:36:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:16 INFO - 'PWD': '/builds/slave/test', 20:36:16 INFO - 'SHELL': '/bin/bash', 20:36:16 INFO - 'SHLVL': '1', 20:36:16 INFO - 'TERM': 'linux', 20:36:16 INFO - 'TMOUT': '86400', 20:36:16 INFO - 'USER': 'cltbld', 20:36:16 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:16 INFO - '_': '/tools/buildbot/bin/python'} 20:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:36:16 INFO - Running setup.py (path:/tmp/pip-eSWuay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:36:16 INFO - Running setup.py (path:/tmp/pip-zSvVbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:36:16 INFO - Running setup.py (path:/tmp/pip-IJwhcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:36:16 INFO - Running setup.py (path:/tmp/pip-XRwA7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:36:16 INFO - Running setup.py (path:/tmp/pip-LjwPlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:36:16 INFO - Running setup.py (path:/tmp/pip-3SVvuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:36:16 INFO - Running setup.py (path:/tmp/pip-mBWldy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:36:16 INFO - Running setup.py (path:/tmp/pip-DyUM04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:36:17 INFO - Running setup.py (path:/tmp/pip-svtvG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:36:17 INFO - Running setup.py (path:/tmp/pip-Vw9F0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:36:17 INFO - Running setup.py (path:/tmp/pip-Jd_zp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:36:17 INFO - Running setup.py (path:/tmp/pip-AaDJsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:36:17 INFO - Running setup.py (path:/tmp/pip-iufi17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:36:17 INFO - Running setup.py (path:/tmp/pip-L8LB_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:36:17 INFO - Running setup.py (path:/tmp/pip-F4rHww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:36:17 INFO - Running setup.py (path:/tmp/pip-w35Xyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:36:17 INFO - Running setup.py (path:/tmp/pip-X6G768-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:36:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:36:17 INFO - Running setup.py install for manifestparser 20:36:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:36:17 INFO - Running setup.py install for mozcrash 20:36:17 INFO - Running setup.py install for mozdebug 20:36:17 INFO - Running setup.py install for mozdevice 20:36:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:36:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:36:18 INFO - Running setup.py install for mozfile 20:36:18 INFO - Running setup.py install for mozhttpd 20:36:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:36:18 INFO - Running setup.py install for mozinfo 20:36:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:36:18 INFO - Running setup.py install for mozInstall 20:36:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:36:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:36:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:36:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:36:18 INFO - Running setup.py install for mozleak 20:36:18 INFO - Running setup.py install for mozlog 20:36:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:36:18 INFO - Running setup.py install for moznetwork 20:36:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:36:18 INFO - Running setup.py install for mozprocess 20:36:18 INFO - Running setup.py install for mozprofile 20:36:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:36:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:36:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:36:19 INFO - Running setup.py install for mozrunner 20:36:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:36:19 INFO - Running setup.py install for mozscreenshot 20:36:19 INFO - Running setup.py install for moztest 20:36:19 INFO - Running setup.py install for mozversion 20:36:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:36:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:36:19 INFO - Cleaning up... 20:36:19 INFO - Return code: 0 20:36:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:36:19 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')]} 20:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:36:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:19 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')]} 20:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:36:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:19 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 0x1dd9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', '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 20:36:19 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 20:36:19 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 20:36:19 INFO - Using env: {'DISPLAY': ':0', 20:36:19 INFO - 'HOME': '/home/cltbld', 20:36:19 INFO - 'LANG': 'en_US.UTF-8', 20:36:19 INFO - 'LANGUAGE': 'en_US:en', 20:36:19 INFO - 'LOGNAME': 'cltbld', 20:36:19 INFO - 'MAIL': '/var/mail/cltbld', 20:36:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:19 INFO - 'MOZ_NO_REMOTE': '1', 20:36:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:19 INFO - 'NO_EM_RESTART': '1', 20:36:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:19 INFO - 'PWD': '/builds/slave/test', 20:36:19 INFO - 'SHELL': '/bin/bash', 20:36:19 INFO - 'SHLVL': '1', 20:36:19 INFO - 'TERM': 'linux', 20:36:19 INFO - 'TMOUT': '86400', 20:36:19 INFO - 'USER': 'cltbld', 20:36:19 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:19 INFO - '_': '/tools/buildbot/bin/python'} 20:36:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:36:19 INFO - Running setup.py (path:/tmp/pip-vglyjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:36:19 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)) 20:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:36:19 INFO - Running setup.py (path:/tmp/pip-oFpLkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:36:19 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)) 20:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:36:19 INFO - Running setup.py (path:/tmp/pip-Hb_zwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:36:19 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)) 20:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:36:19 INFO - Running setup.py (path:/tmp/pip-SjmmWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:36:19 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)) 20:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:36:19 INFO - Running setup.py (path:/tmp/pip-C9boUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:36:20 INFO - Running setup.py (path:/tmp/pip-nCN58j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:36:20 INFO - Running setup.py (path:/tmp/pip-shjBDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:36:20 INFO - Running setup.py (path:/tmp/pip-Ks1Lui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:36:20 INFO - Running setup.py (path:/tmp/pip-Us2De7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:36:20 INFO - Running setup.py (path:/tmp/pip-8LIb1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:36:20 INFO - Running setup.py (path:/tmp/pip-j7WNDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:36:20 INFO - Running setup.py (path:/tmp/pip-s8I_dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:36:20 INFO - Running setup.py (path:/tmp/pip-m1KpVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:36:20 INFO - Running setup.py (path:/tmp/pip-v1OArA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:36:20 INFO - Running setup.py (path:/tmp/pip-iHvulk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:36:20 INFO - Running setup.py (path:/tmp/pip-xPWT84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:36:20 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)) 20:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:36:20 INFO - Running setup.py (path:/tmp/pip-JT8SpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:36:20 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)) 20:36:20 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)) 20:36:20 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)) 20:36:20 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)) 20:36:20 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)) 20:36:20 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)) 20:36:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:36:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:36:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:36:22 INFO - Downloading blessings-1.6.tar.gz 20:36:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:36:22 INFO - Installing collected packages: blessings 20:36:22 INFO - Running setup.py install for blessings 20:36:22 INFO - Successfully installed blessings 20:36:22 INFO - Cleaning up... 20:36:23 INFO - Return code: 0 20:36:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:36:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:36:23 INFO - Reading from file tmpfile_stdout 20:36:23 INFO - Current package versions: 20:36:23 INFO - argparse == 1.2.1 20:36:23 INFO - blessings == 1.6 20:36:23 INFO - blobuploader == 1.2.4 20:36:23 INFO - docopt == 0.6.1 20:36:23 INFO - functools32 == 3.2.3-2 20:36:23 INFO - jsonschema == 2.5.1 20:36:23 INFO - manifestparser == 1.1 20:36:23 INFO - mozInstall == 1.12 20:36:23 INFO - mozcrash == 0.17 20:36:23 INFO - mozdebug == 0.1 20:36:23 INFO - mozdevice == 0.48 20:36:23 INFO - mozfile == 1.2 20:36:23 INFO - mozhttpd == 0.7 20:36:23 INFO - mozinfo == 0.9 20:36:23 INFO - mozleak == 0.1 20:36:23 INFO - mozlog == 3.3 20:36:23 INFO - moznetwork == 0.27 20:36:23 INFO - mozprocess == 0.23 20:36:23 INFO - mozprofile == 0.28 20:36:23 INFO - mozrunner == 6.12 20:36:23 INFO - mozscreenshot == 0.1 20:36:23 INFO - mozsystemmonitor == 0.3 20:36:23 INFO - moztest == 0.7 20:36:23 INFO - mozversion == 1.4 20:36:23 INFO - psutil == 3.1.1 20:36:23 INFO - requests == 1.2.3 20:36:23 INFO - wsgiref == 0.1.2 20:36:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:36:23 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')]} 20:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:23 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')]} 20:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:23 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 0x1dd9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', '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 20:36:23 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 20:36:23 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 20:36:23 INFO - Using env: {'DISPLAY': ':0', 20:36:23 INFO - 'HOME': '/home/cltbld', 20:36:23 INFO - 'LANG': 'en_US.UTF-8', 20:36:23 INFO - 'LANGUAGE': 'en_US:en', 20:36:23 INFO - 'LOGNAME': 'cltbld', 20:36:23 INFO - 'MAIL': '/var/mail/cltbld', 20:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:23 INFO - 'MOZ_NO_REMOTE': '1', 20:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:23 INFO - 'NO_EM_RESTART': '1', 20:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:23 INFO - 'PWD': '/builds/slave/test', 20:36:23 INFO - 'SHELL': '/bin/bash', 20:36:23 INFO - 'SHLVL': '1', 20:36:23 INFO - 'TERM': 'linux', 20:36:23 INFO - 'TMOUT': '86400', 20:36:23 INFO - 'USER': 'cltbld', 20:36:23 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:23 INFO - '_': '/tools/buildbot/bin/python'} 20:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:36:23 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)) 20:36:23 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)) 20:36:23 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)) 20:36:23 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)) 20:36:23 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)) 20:36:23 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)) 20:36:23 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)) 20:36:23 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)) 20:36:23 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)) 20:36:23 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)) 20:36:23 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)) 20:36:23 INFO - Cleaning up... 20:36:23 INFO - Return code: 0 20:36:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:36:23 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')]} 20:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:23 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')]} 20:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:36:23 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 0x1dd9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', '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 20:36:23 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 20:36:23 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 20:36:23 INFO - Using env: {'DISPLAY': ':0', 20:36:23 INFO - 'HOME': '/home/cltbld', 20:36:23 INFO - 'LANG': 'en_US.UTF-8', 20:36:23 INFO - 'LANGUAGE': 'en_US:en', 20:36:23 INFO - 'LOGNAME': 'cltbld', 20:36:23 INFO - 'MAIL': '/var/mail/cltbld', 20:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:23 INFO - 'MOZ_NO_REMOTE': '1', 20:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:23 INFO - 'NO_EM_RESTART': '1', 20:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:23 INFO - 'PWD': '/builds/slave/test', 20:36:23 INFO - 'SHELL': '/bin/bash', 20:36:23 INFO - 'SHLVL': '1', 20:36:23 INFO - 'TERM': 'linux', 20:36:23 INFO - 'TMOUT': '86400', 20:36:23 INFO - 'USER': 'cltbld', 20:36:23 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:23 INFO - '_': '/tools/buildbot/bin/python'} 20:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:36:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:36:23 INFO - Cleaning up... 20:36:23 INFO - Return code: 0 20:36:23 INFO - Running post-action listener: _resource_record_post_action 20:36:23 INFO - Running post-action listener: _start_resource_monitoring 20:36:23 INFO - Starting resource monitoring. 20:36:23 INFO - [mozharness: 2016-08-11 03:36:23.740049Z] Finished create-virtualenv step (success) 20:36:23 INFO - [mozharness: 2016-08-11 03:36:23.740487Z] Running install step. 20:36:23 INFO - Running pre-action listener: _resource_record_pre_action 20:36:23 INFO - Running main action method: install 20:36:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:36:23 INFO - Reading from file tmpfile_stdout 20:36:23 INFO - Detecting whether we're running mozinstall >=1.0... 20:36:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:36:24 INFO - Reading from file tmpfile_stdout 20:36:24 INFO - Output received: 20:36:24 INFO - Usage: mozinstall [options] installer 20:36:24 INFO - Options: 20:36:24 INFO - -h, --help show this help message and exit 20:36:24 INFO - -d DEST, --destination=DEST 20:36:24 INFO - Directory to install application into. [default: 20:36:24 INFO - "/builds/slave/test"] 20:36:24 INFO - --app=APP Application being installed. [default: firefox] 20:36:24 INFO - mkdir: /builds/slave/test/build/application 20:36:24 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'] 20:36:24 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 20:36:40 INFO - Reading from file tmpfile_stdout 20:36:40 INFO - Output received: 20:36:40 INFO - /builds/slave/test/build/application/firefox/firefox 20:36:40 INFO - Running post-action listener: _resource_record_post_action 20:36:40 INFO - [mozharness: 2016-08-11 03:36:40.086568Z] Finished install step (success) 20:36:40 INFO - [mozharness: 2016-08-11 03:36:40.086905Z] Running run-tests step. 20:36:40 INFO - Running pre-action listener: _resource_record_pre_action 20:36:40 INFO - Running main action method: run_tests 20:36:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:36:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:36:40 INFO - Python 2.7.3 20:36:40 INFO - Return code: 0 20:36:40 INFO - grabbing minidump binary from tooltool 20:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:40 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 0x20983b0>, '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 0x20992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2099740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:36:40 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 20:36:40 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 20:36:40 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 20:36:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:36:40 INFO - Return code: 0 20:36:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:36:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:36:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:36:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:36:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:36:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 20:36:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 20:36:40 INFO - Using env: {'DISPLAY': ':0', 20:36:40 INFO - 'HOME': '/home/cltbld', 20:36:40 INFO - 'LANG': 'en_US.UTF-8', 20:36:40 INFO - 'LANGUAGE': 'en_US:en', 20:36:40 INFO - 'LOGNAME': 'cltbld', 20:36:40 INFO - 'MAIL': '/var/mail/cltbld', 20:36:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:36:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:36:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:36:40 INFO - 'MOZ_NO_REMOTE': '1', 20:36:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:36:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:36:40 INFO - 'NO_EM_RESTART': '1', 20:36:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:36:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:36:40 INFO - 'PWD': '/builds/slave/test', 20:36:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:36:40 INFO - 'SHELL': '/bin/bash', 20:36:40 INFO - 'SHLVL': '1', 20:36:40 INFO - 'TERM': 'linux', 20:36:40 INFO - 'TMOUT': '86400', 20:36:40 INFO - 'USER': 'cltbld', 20:36:40 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396', 20:36:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:36:40 INFO - '_': '/tools/buildbot/bin/python'} 20:36:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 20:36:40 INFO - mozversion application_buildid: 20160810195121 20:36:40 INFO - mozversion application_changeset: 6b8517876729c3a2fd289df3acc5dc7fa6cc956a 20:36:40 INFO - mozversion application_display_name: Nightly 20:36:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:36:40 INFO - mozversion application_name: Firefox 20:36:40 INFO - mozversion application_remotingname: firefox 20:36:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:36:40 INFO - mozversion application_vendor: Mozilla 20:36:40 INFO - mozversion application_version: 51.0a1 20:36:40 INFO - mozversion platform_buildid: 20160810195121 20:36:40 INFO - mozversion platform_changeset: 6b8517876729c3a2fd289df3acc5dc7fa6cc956a 20:36:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:36:40 INFO - mozversion platform_version: 51.0a1 20:36:40 INFO - using testdate: 1470886600 20:36:40 INFO - actual date: 1470886600 20:36:40 INFO - starting webserver on 'localhost:37575' 20:36:40 INFO - SUITE-START | Running 2 tests 20:36:40 INFO - TEST-START | dromaeo_css 20:36:40 INFO - operating with platform_type : linux_ 20:36:40 INFO - Initialising browser for dromaeo_css test... 20:36:40 INFO - TEST-INFO | started process 14380 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNHI2tj/profile http://localhost:37575/getInfo.html) 20:36:45 INFO - PROCESS | 14380 | __metrics Screen width/height:1600/1200 20:36:45 INFO - PROCESS | 14380 | colorDepth:24 20:36:45 INFO - PROCESS | 14380 | Browser inner width/height: 1024/697 20:36:45 INFO - PROCESS | 14380 | __metrics 20:36:45 INFO - PROCESS | 14380 | 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" 20:36:45 INFO - PROCESS | 14380 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:36:46 INFO - TEST-INFO | 14380: exit 0 20:36:46 INFO - Browser initialized. 20:36:46 INFO - Running cycle 1/1 for dromaeo_css test... 20:36:46 INFO - TEST-INFO | started process 14457 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNHI2tj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:36:58 INFO - PROCESS | 14457 | RSS: Main: 156540928 20:36:58 INFO - PROCESS | 14457 | 20:37:49 INFO - PROCESS | 14457 | 1470886669511 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] 20:40:03 INFO - PROCESS | 14457 | Cycle 1(1): loaded http://localhost:37575/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37575/tests/dromaeo/cssquery-ext.html) 20:40:04 INFO - PROCESS | 14457 | RSS: Main: 242589696 20:40:04 INFO - PROCESS | 14457 | 20:43:07 INFO - PROCESS | 14457 | Cycle 1(1): loaded http://localhost:37575/tests/dromaeo/cssquery-ext.html (next: http://localhost:37575/tests/dromaeo/cssquery-jquery.html) 20:43:07 INFO - PROCESS | 14457 | RSS: Main: 241844224 20:43:07 INFO - PROCESS | 14457 | 20:46:09 INFO - PROCESS | 14457 | Cycle 1(1): loaded http://localhost:37575/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37575/tests/dromaeo/cssquery-mootools.html) 20:46:10 INFO - PROCESS | 14457 | RSS: Main: 188461056 20:46:10 INFO - PROCESS | 14457 | 20:49:12 INFO - PROCESS | 14457 | Cycle 1(1): loaded http://localhost:37575/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37575/tests/dromaeo/cssquery-prototype.html) 20:49:13 INFO - PROCESS | 14457 | RSS: Main: 188960768 20:49:13 INFO - PROCESS | 14457 | 20:52:16 INFO - PROCESS | 14457 | Cycle 1(1): loaded http://localhost:37575/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37575/tests/dromaeo/cssquery-yui.html) 20:52:16 INFO - PROCESS | 14457 | RSS: Main: 189112320 20:52:16 INFO - PROCESS | 14457 | 20:55:19 INFO - PROCESS | 14457 | Cycle 1(1): loaded http://localhost:37575/tests/dromaeo/cssquery-yui.html (next: http://localhost:37575/tests/dromaeo/cssquery-dojo.html) 20:55:20 INFO - PROCESS | 14457 | RSS: Main: 280522752 20:55:20 INFO - PROCESS | 14457 | 20:55:20 INFO - PROCESS | 14457 | __start_tp_report 20:55:20 INFO - PROCESS | 14457 | _x_x_mozilla_page_load 20:55:20 INFO - PROCESS | 14457 | _x_x_mozilla_page_load_details 20:55:20 INFO - PROCESS | 14457 | |i|pagename|runs| 20:55:20 INFO - PROCESS | 14457 | |0;dojo.html;2648.3511539672777;2662.77366423854;2683.651125353705;2686.8790904409307;2695.528282550554;4645.349651048848;4712.646551508634;4756.785944632491;4882.292067650193;4893.388326459192;4760.928586071212;4817.518248175181;4827.8551643450755;4894.119058569456;4895.510448955103;4317.740935543861;4318.093200427914;4364.825406983717;4412;4565.315202719586;4482.753448560328;4509.526499717532;4532.4334458192725;4554.134714404253;4554.225781617117;4243.745375277463;4291.914161716748;4396.868093957187;4402.273624851896;4503.166914120881;4268.9146217075795;4312.698111132222;4340.153670034345;4344.174606824693;4365.7817109144735;4291.935620965688;4315.4821421429315;4351.999040220767;4359.912801743947;4380.277254253045;4756.167670657655;4783.258594917758;4803.1114243864995;4828.589569886564;4837.854864354075;4960.8759781005765;5141.897162056775;5149.6910185389;5209.114450543417;5209.270702101709;5185.455527169668;5207.4792520748;5248.107821670325;5256.08018596744;5303.337082864642;4893.033570925513;4944.90110197794;4944.9258261126115;4945.184044632595;4951.356323677899;5055.671381360274;5256.632035757537;5261.684298942076;5310.9734451327495;5415.079436495726;5126.256692779526;5159.329287192641;5172.818951336761;5178.689278643294;5188.558972487305;5214.97392513035;5220.582353411718;5221.582273418117;5253.23828044939;5269.130593452114;4979.3277907482025;5098.59211263098;5132.589392848563;5158.174692049329;5165.948340516622;5144.305518754995;5146.536811686891;5148.099082660519;5152.278680984665;5181.4041385240425;4466.642668586505;4470.642348612103;4475.888102797456;4507.639388848885;4525.140223357552;3762.6049264827348;3797.525309336333;3805.2769973705463;3855.4795102660783;3880.243352546283;4415.757133357696;4435.223835828717;4475.328700694857;4480.887977800581;4484.327350897326;5182.870428239249;5194.558462530652;5195.792168313301;5196.168613021974;5198.402183748842;4280.636145927631;4361.694681372273;4429.68992170545;4433.357163211381;4467.195904737114;4687.20317546018;4702.412198475191;4709.576138147583;4730.290456431563;4761.928571071437;4868.634852386156;4868.926966095441;4873.683210591442;4875.512448755097;4912.95087049125;4986.252062190701;5095.796168153232;5142.948570514247;5176.767045482887;5259.368875734936;4838.201696720002;4854.101991131652;4854.247591623304;4887.022595480989;4893.559579637849;5097.443835833881;5106.66806657566;5112.769925353294;5115.718635474936;5156.896862062813;5009.699418034929;5104.566111880383;5113.539781419541;5126.487351264844;5158.406783219828;5043.697378157175;5046;5047.848564543069;5078.238264260391;5081.364829396325;5022;5040.395152581714;5042.319286896369;5045.4450010498085;5092.032513822362;4732.479427263067;4866.221404575251;4926.802927882988;4928.802847886044;4992.301077849032;5078.619103567174;5079.3650793650795;5082.822101226439;5115.4884511550035;5129.846104616868;4969.652124351261;4981.153203955264;4983.6760610560195;4985.601151907911;5035.597152227886 20:55:20 INFO - PROCESS | 14457 | |1;ext.html;554.478789937459;566.5269499967527;567.9971600142;583.2825624481887;584.8040906296392;28969.855150724245;38490.23019539611;39540.80229598855;39553.40669889947;43640.563594364045;39855.80072099643;40157.39763903544;40249.7987510062;40298;40332.193356132935;7676.731314403983;7807.726729564466;7810.687572497093;7816.022997125359;7817.726379576716;7691.961540192291;7765.572893490856;7770.766876993699;7776.144624091346;7776.7666969990705;7771.261730135628;7806.80482987927;7814.374850012014;7817.921820781777;7823.100343460518;7744.574048427335;7777.377809775205;7778.49439786412;7781.260780225926;7794.181610930827;7705.190954949761;7752.186020467882;7758.844823103563;7765.223477652246;7766.417518686121;37426.81286593577;37729.24541509182;37746;37768.81115594432;37790;2963.6739958604535;3147.84260786962;3173.349463360029;3347.113015050989;3365.512000759876;28527.57208641872;28862;29150.562741558682;29224.41551168986;29331;3437.34690408824;3511.9297614047578;3548.698360639323;3572.7141828653644;3619.1675914539805;259532;259700;260953.9999999981;261167;261816;51032.48967510278;51317.7434112827;51401.7429912848;51417.228741568906;51470.48529514657;53111.46888531143;53292.73353633207;53300;53319.73340133352;53353.733231333594;14547.563573092824;14813.407463701547;14841;14844.92577537127;14855.554333370017;11247.437628118725;11342.376169310603;11354.829677554842;11356.26184298018;11369.488373023234;14585.270736463135;15096.320665570076;15101.093934363973;15141.470048548248;15291.61770955735;5738.741756620962;5783;5865.765369385177;5928.4960778334325;5970.253718285215;652.3346186889346;794.5510786405662;827.4042689263721;893.883795106632;926.4533924984157;26610.733892661214;27921.022764203157;27926.44147117087;28088.43823123526;28147.5926203689;733.9045924029842;992.9404235745878;1013.2704452793977;1026.984595231072;1048.4233671480808;14046;14959.41939283815;14992.175430351435;15178.392864285526;15179.620509487351;369589;372814;373051;373139;373362;49239;49339.753301233264;49387.50612493901;49417;49423.75288123536;1154.630518234174;1161.12335186935;1184.5380301682524;1215.1433239566084;1215.9817602735966;25516;26426.471470570097;26835.32911677224;27318.453630927666;27405.451890962468;39759.60240397674;40064.59935400609;40130.598694013854;40134.598654014255;40149.799251003555;330834;333007;333020;333141;333232;35390.64609353976;35643.28713425665;35676;35707.642923570435;35748.821255893556;40786.59213407828;41193.38209926793;41476;41782.164356712085;41958.790206048776;35496.64503355037;35765.46351804673;36047.639523604426;36414;36563.63436365602;35284.647153529164;35432.29135417226;35794.64205357913;36030.8198459006;36332.81833590921;30817.229569260948;31653.683463165074;33689.831550842086;35386;35931.46102808408;31027.689723103384;33385.83307083449;33494.49758253579;33539.496907545916;33717;24828.255152345457;25598.24368825526;25702;25776.423712246175;25792.226233213034 20:55:20 INFO - PROCESS | 14457 | |2;jquery.html;329.89443378118983;351.3920916813913;356.8697425439715;360.51510718084165;388.5589855513991;12763.1703939244;15956.122413267265;16405.261763220653;16800.411985580507;17800.28798848048;17014.744778828295;17312.480625581222;17390.739138912926;17624.29502819889;17707.64584708305;4896.730679812609;4931.211006239003;5100.464451232623;5106.234064890277;5120.155174396239;3724.8696295629657;5050.520200580939;5136.58907287418;5586.804461843816;5909.704514774266;5885.293764748236;5912.9704351478185;5925.733341999598;5929.347771745104;5931.228940237785;5126.820561280356;5382.838514844521;5401.108817045183;5482.753276102545;5920.230370051887;5297.682139071668;5299.258103865462;5299.735013249322;5308.362996440414;5311.601629877775;18254.908725456422;20326.28857989978;20742.06660700257;20942.895285523628;21033.36899893006;1896.781870084944;1909.3699079303935;1950.54162271866;1967.3409407848244;1970.231609672229;8327.292180164693;8908.198262156373;9020.639174433014;11678.941605292004;13483.123596966263;1772.2645102282538;1816.8092350303293;1817.2367605605546;1827.0773259503999;1837.9356722514647;63770.681146594434;68595;68801;70594.29405705929;70918.2908170922;9253.120953509406;9514.714558563253;9758.463284519277;9901.702948911545;9997.800043999225;13572.796408053888;13593.388297526635;13595.45618175262;13611.319434028457;13618.250996195105;7056.15326160864;7375.8156046099275;7403.519296140794;7508.511946723445;7538.886916696254;7026.43788496919;7059.364657180879;7252.637368131573;7263.4915555910575;7284.053073100463;4185.204811085884;4213.662906967496;4277.786110694515;4392.5607439256455;4406.449193850769;1749.868759843017;1772.6986412309939;1778.262021261188;1845.640100180452;1846.4645252876514;679.6873438218474;839.4711331860889;885.6590212768002;950.6577632052594;979.3487330924855;16558;16843;17219.82780172182;17501.562460938323;17799.911000444914;854.3976496569903;855.8202777416813;865.3466632692277;911.8449863523217;924.2282693950492;7038.577685338896;9219.953900230455;9800.803983920281;9859.35914165577;9915.107640312262;392554;393412;401041;401587;404889;46609.766951165024;48571.271430928566;73916;74517;76366.61816690992;883.8541640629225;909.9636014559476;941.7833898203452;951.0679534056663;952.6903756279237;15618.843811562194;15923.840761592235;16336.183190840173;16457.91771041137;16514.66970660604;37098.62901370952;38458.23083538369;38568.228635427695;39376.2124757509;39402.2119557613;48484.757576213306;52512.7374363141;52861.471385287194;53050.734746327566;54734.178987316016;6499.740010399721;6698.330166983263;6786.796396108125;6958.165020197609;7536.284053014955;9330.393524420891;11578.652640420802;11800.940995294968;11817.172797904064;12516.80821390576;9651.662191823252;10430.800457947433;10470.633527826489;10597.046265835803;11144.777104457704;9721.076497732704;9744.610215591298;10007.799844002933;10404.531796069346;10482.790344193227;5398.622096453368;10346.37786858199;10468.476576171313;10547.156227501935;10649.840252396376;6697.966510167418;6920.723171073302;6965;7114.110736157981;7190.101237345332;9052.411593246417;10419.479026048819;10538.631147909786;10583.470826458492;10671.253012289066 20:55:20 INFO - PROCESS | 14457 | |3;mootools.html;1331.9533816316427;1387.5074348606245;1422.850600686928;1619.4089157457518;1644.235430524805;6714.227863795665;7236.9638151809295;7240.275972402757;7244.963775181117;7245.094363204606;6069.302030266521;6263.060540918853;6765.188177418717;7004.614746188958;7181.24596917324;2094.32981445937;2123.9469013274716;2127.702121702955;2128.372130221588;2128.6062078515447;2049.272508259569;2078.9792102078936;2122.2890331738886;2123.936281911545;2124.75565309989;1751.2819743905002;2041.2141325589607;2107.1676687708346;2108.641530939744;2134.8185404240658;2067.9896600516977;2083.874967501955;2084.1871670048695;2087.2068613926613;2090.5923344947664;1930.8165724256173;1951.853610979168;1953.2284747524648;1953.7753158386824;1953.794851540582;7123.145222573325;7154.1415030196185;7163.462740294499;7178.856422871513;7181.3895818854935;4213.726107803013;4345.587169218919;4398.054418300081;4403.097365040159;4403.779811009436;2617.4895895300424;2732.002818971061;2765.14280573021;2765.806393552452;2774.7918906082123;825.5748289630845;848.1221935296938;861.3884142259004;869.42183448007;875.9124087591254;9366.765830854216;9512.191463725592;9522.571484283293;9574.808503829885;9608.519574021271;3700.2784457031157;3850.9229815403537;3885.3589157789215;3944.723869329177;3945.2898478274187;8193.180681931759;8201.26188643025;8205.876911846208;8213.548254845922;8220.095789463154;4258.808353624095;4266.2960611499375;4280.336547835091;4283.785810709452;4286.89282767927;3969.2458432898234;3971.126352202511;3971.404289356562;3973.2450834341385;3978.701597380208;2983.4331477019296;3082.3989322082425;3104.363605460875;3122.437961167012;3145.811251324928;956.5982287439292;956.6412595276771;957.4733896356976;977.5503268496572;985.7437066362654;629.3643420145686;729.8832785837677;798.5927177139585;836.2891542189212;858.4720396955952;4790.113828941657;4891.290762839369;4901.828436004723;4919.581835544019;5092;844.9704260350982;874.2961915657957;875.2341701011615;878.8418084744808;879.5030807593689;3237.2392487765355;3463.7229021678204;3500.072480792592;3536.717062634983;3568.57177138745;8366.958165209257;8804.295656347605;8805.515696636618;8806.955965220133;8818.603162857687;8689.217970382695;8729.258013068833;8754.56227188638;8756.956215218883;8866.379353445325;6766.052752614638;6841.076454678654;6873.278305777922;6884.0362349271145;6888.035675005503;4859.271109333628;4985.501449854985;5068.518490743521;5114.846554603368;5117.618243074424;7768.572728499987;7982;8147.185281471805;8158.4289099762445;8164.224398682116;8227.506349619041;8292.834143316979;8297.543635099886;8314.251717345467;8437.915620843713;6384.776532821515;6522.967385163044;6523.282438931618;6530.8040758777315;6536.084948107364;6702.899456508254;6917.515773895779;6930.8613827724175;6956.965215173892;7037.542559733805;6319.9052014218905;6385.808425747235;6390.7763228286785;6433.485321174388;6436.8390790230615;6083.452489275809;6357.110004599267;6369.808905732835;6404.935950640434;6481.708323125376;5712.200291959046;5840.415958404125;5892.440218179266;5912.615679980788;6039.365866584121;4330;4368.694191406698;4405.361222622766;4449.043455657049;4577.702449340783;4120.979395103005;4121.402396652528;4220.113776107052;4300.935485967776;4361.738295702268 20:55:20 INFO - PROCESS | 14457 | |4;prototype.html;286.3899893227425;292.8843106972746;293.98824047038113;294.5052312115645;297.80791389553724;4726.078414709129;4890.017089575356;4915.689625875837;4922.6800257983205;4984.853483698743;4842.515748425156;4855.684380515264;4872.7563621818945;4872.7563621818945;4923.178423983131;3613.1328481164596;3670.1925576373155;3714.4985426967282;3814.4087666411683;3818.4463252828464;3749.5125633667585;3765.8305376258;3766.8869933901888;3768.547774267092;3772.735908486407;3727.7204209684382;3737.177820879402;3737.7009839212933;3739.7943113128767;3740.719446041544;3552.3428165789155;3587.856485740567;3665.651763082503;3734.7012239020814;3752.699784017272;3225.9031929144203;3227.644959054502;3233.2725136844256;3236.543555400079;3248.31785325082;4721.409823772028;4728.148933192024;4729.125111854318;4733.3196715166305;4741.672331747116;4165.58344165584;4277.2087163874785;4290.077633308853;4303.483581970152;4305.311150215982;5019.924701129486;5038.773255203525;5047.318611987372;5047.899042019176;5057.002484018829;3546.5921419036627;3546.627604101558;3548.1307079765625;3549.804760738158;3550.5029295898594;5291.86770330741;5308.203769434577;5358.588635114214;5359.678419294854;5373.382061063028;3581.8925432237074;3644.2347107107275;3655.141041855162;3662.6154253802965;3666.8166591670843;3688.704903607705;3697.927600995681;3698.907527311785;3704;3704.851496036237;4254.595813397784;4260.488741351058;4262.339337402707;4264.808083636244;4277.91444171121;4881.9023619528125;5015.673981191211;5106.974465127651;5114.105031619451;5114.769835357418;1249.9625011249677;1264.4752427742567;1269.3018839638162;1273.1342686972762;1278.1180985120238;792.6948124971811;798.9280964713204;825.8885050518103;827.6937533112666;828.8176601147737;4288.292431748789;4386.649068074546;4448.419916448453;4470.262406702923;4484.39460672805;5204.8178313758835;5237.109691352479;5245.10833158364;5248.553872920845;5250.081235783722;4426.9778651106535;4450.888727781767;4461.661501549587;4482.21561226784;4482.439695038121;2631.0265201875427;2760.9430973924154;2765.764909982634;2771.902983395612;2778.4998700233746;4864.756762161877;4870.707757534559;4897.1674815281485;4902.137187868587;4904.583110435653;5169.9741501293;5323.760430780624;5329.813456529003;5355.277037599874;5368.409474957752;4838.653253172209;4876.561109500162;4880;4888.388951381077;4897.608191344755;5162.97418512905;5230.921536176884;5239.89520209586;5241.895162096813;5266.210068489757;4988.376452943382;5148.9580679166675;5157.974210128926;5167.896642067213;5178.223266510054;4865.7323847188745;4882.267659851051;4883.364073035573;4886.706797592014;4888.437829649493;3513.5067596271992;3530.5057291979656;3531.911702207465;3538.4869193967247;3539.716822654233;5026.5727413168825;5263.578913686973;5279.287296214962;5280.471952804689;5281.10221262394;5012.498750124958;5045.369328833895;5060.115734031714;5068.138416469135;5080.212567052113;5039.571636410799;5071.92392114126;5078.822241221539;5094.981003799181;5103.916866506854;4794.976025119852;4975.353204083446;4975.776090075883;4980.302757613865;4998.4251811042195;4858.441279252931;4887.975560122177;5057.69653820772;5072.264521644267;5100.974495127501;4978.975105124451;4996.850094497171;5000.524950129732;5007.949920500894;5014.022265658162 20:55:20 INFO - PROCESS | 14457 | |5;yui.html;690.19592175116;713.2724620886693;716.6954044531075;718.0450001498008;744.5272252119904;13021.934890325547;14053.789193162098;14154.929225353884;14182.149071055745;14184.36170372333;13417.664558386045;13480;13512.662183445418;13530.594082177526;13558.864411355884;612.3080918562018;632.5350867112669;746.4662766122221;751.2862780358668;771.4214339245566;758.0827199089109;761.0600907878766;764.2624867003341;766.0845289878597;767.3937589304443;826.6941231744263;827.383599218583;827.5365795154703;827.7475370012144;827.8220182660726;659.2846761264034;819.0212695828487;819.397742659145;819.8319344534416;820.9096999330069;577.8584246859547;594.4887396838715;596.8806238752267;596.9880602387927;598.2611474828583;13488.86511134886;13607.251601161932;13611.591652250449;13642.249676267802;13649.863501364958;11775.94112029443;12250.038580054872;12327;12441.937790311082;12587.874121258761;9895.455749933752;10221.540030698636;11625.418729063513;11632.592859249886;11812.468438920268;4074.653654439376;4116.773577453238;4127.752334859888;4162.188373267214;4327.480702315711;59690;60109.398906010814;60166.398336016515;60260;60435.39564604386;10116.342437741523;10117.39295642249;10139.442330671736;10156.33983791066;10556.947215263874;17545.56136096582;18088.547786305448;18097.276108955495;18112.094395280183;18118.818811811714;6788.626625535643;6874.793756187322;6954.3393377629045;6988.312855858515;7157.463190260752;3667.9266414671556;3674;3679.9448008279896;3717.9628203718157;3775.320442320409;2820.70382609823;2935.882564697431;2937.9706202937696;2938.4710752064407;2947.439986402577;506.0562051773416;516.40097486915;519.63885099856;522.9424763276037;529.7324850950246;543.6683622989973;584.2259006815985;588.7556663984484;619.1053927075423;632.6552029144127;8803.911960880438;8822.2060014599;8980.685676001309;9044.547772611342;9105.635774569075;552.6076485695121;557.1420013179738;562.7411390760215;570.6918264137403;598.2193237824594;4204.768737719455;4369.148016136886;4450.6439484841785;4476.127155204769;4540.182767101887;62402.68798655977;66926.66536667383;96608.03391965991;110456.44771776251;110640;89759.55120224488;94861;95143;95296;96073.5196324028;73075.63462182655;78621.21378786139;79193.20806791974;79256.60371698104;80972.59513702393;8124.593770311342;8337.95831020841;8391.202835730595;8532.658693652184;8603.354748393771;30174.54738178973;38748.6125138745;40063.1987360257;40523.797381012904;50189;39732.60267397289;55078;57501.712491437276;62236.68881655744;62271;11256.887431125584;11499.8275025873;11674.182807203415;11826.172167948505;11848.526058957545;12158.088143389105;12712.682182945131;13217.537386191438;13913;14008.929955350159;12215.299360089786;12800.295983720986;12927.28899910514;12965.805512917503;13077.803832942323;11799.587014454453;12828.10203285761;12843.678908027001;12852;12859.233801072289;12003.699907502383;12235;12348.259104453762;13008;13029.609111726664;9852.852207217042;10014.298999069995;10088.445135517324;10089.646862359783;10128.746781330527;10199.54549079022;10292.309147668197;10437.68686939393;10469.26715129964;10487.318324308895 20:55:20 INFO - PROCESS | 14457 | __end_tp_report 20:55:20 INFO - PROCESS | 14457 | __start_cc_report 20:55:20 INFO - PROCESS | 14457 | _x_x_mozilla_cycle_collect,979 20:55:20 INFO - PROCESS | 14457 | __end_cc_report 20:55:20 INFO - PROCESS | 14457 | __startTimestamp1470887720109__endTimestamp 20:55:20 INFO - PROCESS | 14457 | 20:55:20 INFO - PROCESS | 14457 | ------- Summary: start ------- 20:55:20 INFO - PROCESS | 14457 | Number of tests: 6 20:55:20 INFO - PROCESS | 14457 | 20:55:20 INFO - PROCESS | 14457 | [#0] dojo.html Cycles:165 Average:4773.97 Median:4912.95 stddev:512.06 (10.4%) stddev-sans-first:485.72 20:55:20 INFO - PROCESS | 14457 | Values: 2648.4 2662.8 2683.7 2686.9 2695.5 4645.3 4712.6 4756.8 4882.3 4893.4 4760.9 4817.5 4827.9 4894.1 4895.5 4317.7 4318.1 4364.8 4412.0 4565.3 4482.8 4509.5 4532.4 4554.1 4554.2 4243.7 4291.9 4396.9 4402.3 4503.2 4268.9 4312.7 4340.2 4344.2 4365.8 4291.9 4315.5 4352.0 4359.9 4380.3 4756.2 4783.3 4803.1 4828.6 4837.9 4960.9 5141.9 5149.7 5209.1 5209.3 5185.5 5207.5 5248.1 5256.1 5303.3 4893.0 4944.9 4944.9 4945.2 4951.4 5055.7 5256.6 5261.7 5311.0 5415.1 5126.3 5159.3 5172.8 5178.7 5188.6 5215.0 5220.6 5221.6 5253.2 5269.1 4979.3 5098.6 5132.6 5158.2 5165.9 5144.3 5146.5 5148.1 5152.3 5181.4 4466.6 4470.6 4475.9 4507.6 4525.1 3762.6 3797.5 3805.3 3855.5 3880.2 4415.8 4435.2 4475.3 4480.9 4484.3 5182.9 5194.6 5195.8 5196.2 5198.4 4280.6 4361.7 4429.7 4433.4 4467.2 4687.2 4702.4 4709.6 4730.3 4761.9 4868.6 4868.9 4873.7 4875.5 4913.0 4986.3 5095.8 5142.9 5176.8 5259.4 4838.2 4854.1 4854.2 4887.0 4893.6 5097.4 5106.7 5112.8 5115.7 5156.9 5009.7 5104.6 5113.5 5126.5 5158.4 5043.7 5046.0 5047.8 5078.2 5081.4 5022.0 5040.4 5042.3 5045.4 5092.0 4732.5 4866.2 4926.8 4928.8 4992.3 5078.6 5079.4 5082.8 5115.5 5129.8 4969.7 4981.2 4983.7 4985.6 5035.6 20:55:20 INFO - PROCESS | 14457 | 20:55:20 INFO - PROCESS | 14457 | [#1] ext.html Cycles:180 Average:47529.51 Median:19975.99 stddev:85527.65 (428.2%) stddev-sans-first:85694.85 20:55:20 INFO - PROCESS | 14457 | Values: 554.5 566.5 568.0 583.3 584.8 28969.9 38490.2 39540.8 39553.4 43640.6 39855.8 40157.4 40249.8 40298.0 40332.2 7676.7 7807.7 7810.7 7816.0 7817.7 7692.0 7765.6 7770.8 7776.1 7776.8 7771.3 7806.8 7814.4 7817.9 7823.1 7744.6 7777.4 7778.5 7781.3 7794.2 7705.2 7752.2 7758.8 7765.2 7766.4 37426.8 37729.2 37746.0 37768.8 37790.0 2963.7 3147.8 3173.3 3347.1 3365.5 28527.6 28862.0 29150.6 29224.4 29331.0 3437.3 3511.9 3548.7 3572.7 3619.2 259532.0 259700.0 260954.0 261167.0 261816.0 51032.5 51317.7 51401.7 51417.2 51470.5 53111.5 53292.7 53300.0 53319.7 53353.7 14547.6 14813.4 14841.0 14844.9 14855.6 11247.4 11342.4 11354.8 11356.3 11369.5 14585.3 15096.3 15101.1 15141.5 15291.6 5738.7 5783.0 5865.8 5928.5 5970.3 652.3 794.6 827.4 893.9 926.5 26610.7 27921.0 27926.4 28088.4 28147.6 733.9 992.9 1013.3 1027.0 1048.4 14046.0 14959.4 14992.2 15178.4 15179.6 369589.0 372814.0 373051.0 373139.0 373362.0 49239.0 49339.8 49387.5 49417.0 49423.8 1154.6 1161.1 1184.5 1215.1 1216.0 25516.0 26426.5 26835.3 27318.5 27405.5 39759.6 40064.6 40130.6 40134.6 40149.8 330834.0 333007.0 333020.0 333141.0 333232.0 35390.6 35643.3 35676.0 35707.6 35748.8 40786.6 41193.4 41476.0 41782.2 41958.8 35496.6 35765.5 36047.6 36414.0 36563.6 35284.6 35432.3 35794.6 36030.8 36332.8 30817.2 31653.7 33689.8 35386.0 35931.5 31027.7 33385.8 33494.5 33539.5 33717.0 24828.3 25598.2 25702.0 25776.4 25792.2 20:55:20 INFO - PROCESS | 14457 | 20:55:20 INFO - PROCESS | 14457 | [#2] jquery.html Cycles:180 Average:24211.69 Median:4913.97 stddev:65590.07 (1334.8%) stddev-sans-first:65749.55 20:55:20 INFO - PROCESS | 14457 | Values: 329.9 351.4 356.9 360.5 388.6 12763.2 15956.1 16405.3 16800.4 17800.3 17014.7 17312.5 17390.7 17624.3 17707.6 4896.7 4931.2 5100.5 5106.2 5120.2 3724.9 5050.5 5136.6 5586.8 5909.7 5885.3 5913.0 5925.7 5929.3 5931.2 5126.8 5382.8 5401.1 5482.8 5920.2 5297.7 5299.3 5299.7 5308.4 5311.6 18254.9 20326.3 20742.1 20942.9 21033.4 1896.8 1909.4 1950.5 1967.3 1970.2 8327.3 8908.2 9020.6 11678.9 13483.1 1772.3 1816.8 1817.2 1827.1 1837.9 63770.7 68595.0 68801.0 70594.3 70918.3 9253.1 9514.7 9758.5 9901.7 9997.8 13572.8 13593.4 13595.5 13611.3 13618.3 7056.2 7375.8 7403.5 7508.5 7538.9 7026.4 7059.4 7252.6 7263.5 7284.1 4185.2 4213.7 4277.8 4392.6 4406.4 1749.9 1772.7 1778.3 1845.6 1846.5 679.7 839.5 885.7 950.7 979.3 16558.0 16843.0 17219.8 17501.6 17799.9 854.4 855.8 865.3 911.8 924.2 7038.6 9220.0 9800.8 9859.4 9915.1 392554.0 393412.0 401041.0 401587.0 404889.0 46609.8 48571.3 73916.0 74517.0 76366.6 883.9 910.0 941.8 951.1 952.7 15618.8 15923.8 16336.2 16457.9 16514.7 37098.6 38458.2 38568.2 39376.2 39402.2 48484.8 52512.7 52861.5 53050.7 54734.2 6499.7 6698.3 6786.8 6958.2 7536.3 9330.4 11578.7 11800.9 11817.2 12516.8 9651.7 10430.8 10470.6 10597.0 11144.8 9721.1 9744.6 10007.8 10404.5 10482.8 5398.6 10346.4 10468.5 10547.2 10649.8 6698.0 6920.7 6965.0 7114.1 7190.1 9052.4 10419.5 10538.6 10583.5 10671.3 20:55:20 INFO - PROCESS | 14457 | 20:55:20 INFO - PROCESS | 14457 | [#3] mootools.html Cycles:180 Average:4748.81 Median:6076.38 stddev:2602.16 (42.8%) stddev-sans-first:2596.79 20:55:20 INFO - PROCESS | 14457 | Values: 1332.0 1387.5 1422.9 1619.4 1644.2 6714.2 7237.0 7240.3 7245.0 7245.1 6069.3 6263.1 6765.2 7004.6 7181.2 2094.3 2123.9 2127.7 2128.4 2128.6 2049.3 2079.0 2122.3 2123.9 2124.8 1751.3 2041.2 2107.2 2108.6 2134.8 2068.0 2083.9 2084.2 2087.2 2090.6 1930.8 1951.9 1953.2 1953.8 1953.8 7123.1 7154.1 7163.5 7178.9 7181.4 4213.7 4345.6 4398.1 4403.1 4403.8 2617.5 2732.0 2765.1 2765.8 2774.8 825.6 848.1 861.4 869.4 875.9 9366.8 9512.2 9522.6 9574.8 9608.5 3700.3 3850.9 3885.4 3944.7 3945.3 8193.2 8201.3 8205.9 8213.5 8220.1 4258.8 4266.3 4280.3 4283.8 4286.9 3969.2 3971.1 3971.4 3973.2 3978.7 2983.4 3082.4 3104.4 3122.4 3145.8 956.6 956.6 957.5 977.6 985.7 629.4 729.9 798.6 836.3 858.5 4790.1 4891.3 4901.8 4919.6 5092.0 845.0 874.3 875.2 878.8 879.5 3237.2 3463.7 3500.1 3536.7 3568.6 8367.0 8804.3 8805.5 8807.0 8818.6 8689.2 8729.3 8754.6 8757.0 8866.4 6766.1 6841.1 6873.3 6884.0 6888.0 4859.3 4985.5 5068.5 5114.8 5117.6 7768.6 7982.0 8147.2 8158.4 8164.2 8227.5 8292.8 8297.5 8314.3 8437.9 6384.8 6523.0 6523.3 6530.8 6536.1 6702.9 6917.5 6930.9 6957.0 7037.5 6319.9 6385.8 6390.8 6433.5 6436.8 6083.5 6357.1 6369.8 6404.9 6481.7 5712.2 5840.4 5892.4 5912.6 6039.4 4330.0 4368.7 4405.4 4449.0 4577.7 4121.0 4121.4 4220.1 4300.9 4361.7 20:55:20 INFO - PROCESS | 14457 | 20:55:20 INFO - PROCESS | 14457 | [#4] prototype.html Cycles:180 Average:4189.76 Median:4861.60 stddev:1238.06 (25.5%) stddev-sans-first:1206.37 20:55:20 INFO - PROCESS | 14457 | Values: 286.4 292.9 294.0 294.5 297.8 4726.1 4890.0 4915.7 4922.7 4984.9 4842.5 4855.7 4872.8 4872.8 4923.2 3613.1 3670.2 3714.5 3814.4 3818.4 3749.5 3765.8 3766.9 3768.5 3772.7 3727.7 3737.2 3737.7 3739.8 3740.7 3552.3 3587.9 3665.7 3734.7 3752.7 3225.9 3227.6 3233.3 3236.5 3248.3 4721.4 4728.1 4729.1 4733.3 4741.7 4165.6 4277.2 4290.1 4303.5 4305.3 5019.9 5038.8 5047.3 5047.9 5057.0 3546.6 3546.6 3548.1 3549.8 3550.5 5291.9 5308.2 5358.6 5359.7 5373.4 3581.9 3644.2 3655.1 3662.6 3666.8 3688.7 3697.9 3698.9 3704.0 3704.9 4254.6 4260.5 4262.3 4264.8 4277.9 4881.9 5015.7 5107.0 5114.1 5114.8 1250.0 1264.5 1269.3 1273.1 1278.1 792.7 798.9 825.9 827.7 828.8 4288.3 4386.6 4448.4 4470.3 4484.4 5204.8 5237.1 5245.1 5248.6 5250.1 4427.0 4450.9 4461.7 4482.2 4482.4 2631.0 2760.9 2765.8 2771.9 2778.5 4864.8 4870.7 4897.2 4902.1 4904.6 5170.0 5323.8 5329.8 5355.3 5368.4 4838.7 4876.6 4880.0 4888.4 4897.6 5163.0 5230.9 5239.9 5241.9 5266.2 4988.4 5149.0 5158.0 5167.9 5178.2 4865.7 4882.3 4883.4 4886.7 4888.4 3513.5 3530.5 3531.9 3538.5 3539.7 5026.6 5263.6 5279.3 5280.5 5281.1 5012.5 5045.4 5060.1 5068.1 5080.2 5039.6 5071.9 5078.8 5095.0 5103.9 4795.0 4975.4 4975.8 4980.3 4998.4 4858.4 4888.0 5057.7 5072.3 5101.0 4979.0 4996.9 5000.5 5007.9 5014.0 20:55:20 INFO - PROCESS | 14457 | 20:55:20 INFO - PROCESS | 14457 | [#5] yui.html Cycles:180 Average:17738.70 Median:4348.31 stddev:25358.51 (583.2%) stddev-sans-first:25397.34 20:55:20 INFO - PROCESS | 14457 | Values: 690.2 713.3 716.7 718.0 744.5 13021.9 14053.8 14154.9 14182.1 14184.4 13417.7 13480.0 13512.7 13530.6 13558.9 612.3 632.5 746.5 751.3 771.4 758.1 761.1 764.3 766.1 767.4 826.7 827.4 827.5 827.7 827.8 659.3 819.0 819.4 819.8 820.9 577.9 594.5 596.9 597.0 598.3 13488.9 13607.3 13611.6 13642.2 13649.9 11775.9 12250.0 12327.0 12441.9 12587.9 9895.5 10221.5 11625.4 11632.6 11812.5 4074.7 4116.8 4127.8 4162.2 4327.5 59690.0 60109.4 60166.4 60260.0 60435.4 10116.3 10117.4 10139.4 10156.3 10556.9 17545.6 18088.5 18097.3 18112.1 18118.8 6788.6 6874.8 6954.3 6988.3 7157.5 3667.9 3674.0 3679.9 3718.0 3775.3 2820.7 2935.9 2938.0 2938.5 2947.4 506.1 516.4 519.6 522.9 529.7 543.7 584.2 588.8 619.1 632.7 8803.9 8822.2 8980.7 9044.5 9105.6 552.6 557.1 562.7 570.7 598.2 4204.8 4369.1 4450.6 4476.1 4540.2 62402.7 66926.7 96608.0 110456.4 110640.0 89759.6 94861.0 95143.0 95296.0 96073.5 73075.6 78621.2 79193.2 79256.6 80972.6 8124.6 8338.0 8391.2 8532.7 8603.4 30174.5 38748.6 40063.2 40523.8 50189.0 39732.6 55078.0 57501.7 62236.7 62271.0 11256.9 11499.8 11674.2 11826.2 11848.5 12158.1 12712.7 13217.5 13913.0 14008.9 12215.3 12800.3 12927.3 12965.8 13077.8 11799.6 12828.1 12843.7 12852.0 12859.2 12003.7 12235.0 12348.3 13008.0 13029.6 9852.9 10014.3 10088.4 10089.6 10128.7 10199.5 10292.3 10437.7 10469.3 10487.3 20:55:20 INFO - PROCESS | 14457 | -------- Summary: end -------- 20:55:20 INFO - PROCESS | 14457 | 20:55:20 INFO - TEST-INFO | 14457: exit 0 20:55:20 INFO - TEST-OK | dromaeo_css | took 1120576ms 20:55:20 INFO - TEST-START | kraken 20:55:20 INFO - operating with platform_type : linux_ 20:55:20 INFO - Initialising browser for kraken test... 20:55:20 INFO - TEST-INFO | started process 14645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe0KO2T/profile http://localhost:37575/getInfo.html) 20:55:25 INFO - PROCESS | 14645 | __metrics Screen width/height:1600/1200 20:55:25 INFO - PROCESS | 14645 | colorDepth:24 20:55:25 INFO - PROCESS | 14645 | Browser inner width/height: 1024/697 20:55:25 INFO - PROCESS | 14645 | __metrics 20:55:26 INFO - PROCESS | 14645 | 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" 20:55:26 INFO - PROCESS | 14645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:55:27 INFO - TEST-INFO | 14645: exit 0 20:55:27 INFO - Browser initialized. 20:55:27 INFO - Running cycle 1/1 for kraken test... 20:55:27 INFO - TEST-INFO | started process 14726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe0KO2T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:55:39 INFO - PROCESS | 14726 | RSS: Main: 156864512 20:55:39 INFO - PROCESS | 14726 | 20:55:41 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37575/tests/kraken/driver.html?testName=audio-beat-detection) 20:55:42 INFO - PROCESS | 14726 | RSS: Main: 212901888 20:55:42 INFO - PROCESS | 14726 | 20:55:45 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37575/tests/kraken/driver.html?testName=audio-dft) 20:55:46 INFO - PROCESS | 14726 | RSS: Main: 191569920 20:55:46 INFO - PROCESS | 14726 | 20:55:49 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37575/tests/kraken/driver.html?testName=audio-fft) 20:55:50 INFO - PROCESS | 14726 | RSS: Main: 269565952 20:55:50 INFO - PROCESS | 14726 | 20:55:52 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37575/tests/kraken/driver.html?testName=audio-oscillator) 20:55:52 INFO - PROCESS | 14726 | RSS: Main: 179671040 20:55:52 INFO - PROCESS | 14726 | 20:55:55 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37575/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:55:55 INFO - PROCESS | 14726 | RSS: Main: 184348672 20:55:55 INFO - PROCESS | 14726 | 20:55:58 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37575/tests/kraken/driver.html?testName=imaging-darkroom) 20:55:59 INFO - PROCESS | 14726 | RSS: Main: 219992064 20:55:59 INFO - PROCESS | 14726 | 20:56:02 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37575/tests/kraken/driver.html?testName=imaging-desaturate) 20:56:03 INFO - PROCESS | 14726 | RSS: Main: 219213824 20:56:03 INFO - PROCESS | 14726 | 20:56:06 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37575/tests/kraken/driver.html?testName=json-parse-financial) 20:56:07 INFO - PROCESS | 14726 | RSS: Main: 218877952 20:56:07 INFO - PROCESS | 14726 | 20:56:08 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37575/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:56:09 INFO - PROCESS | 14726 | RSS: Main: 184197120 20:56:09 INFO - PROCESS | 14726 | 20:56:11 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37575/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:56:11 INFO - PROCESS | 14726 | RSS: Main: 189427712 20:56:11 INFO - PROCESS | 14726 | 20:56:13 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37575/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:56:14 INFO - PROCESS | 14726 | RSS: Main: 194682880 20:56:14 INFO - PROCESS | 14726 | 20:56:17 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37575/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:56:18 INFO - PROCESS | 14726 | RSS: Main: 188264448 20:56:18 INFO - PROCESS | 14726 | 20:56:20 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37575/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:56:21 INFO - PROCESS | 14726 | RSS: Main: 192225280 20:56:21 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | Cycle 1(1): loaded http://localhost:37575/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37575/tests/kraken/driver.html?testName=ai-astar) 20:56:23 INFO - PROCESS | 14726 | RSS: Main: 242327552 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | __start_tp_report 20:56:23 INFO - PROCESS | 14726 | _x_x_mozilla_page_load 20:56:23 INFO - PROCESS | 14726 | _x_x_mozilla_page_load_details 20:56:23 INFO - PROCESS | 14726 | |i|pagename|runs| 20:56:23 INFO - PROCESS | 14726 | |0;ai-astar;104;115;114;113;113;114;113;113;113;114 20:56:23 INFO - PROCESS | 14726 | |1;audio-beat-detection;144;121;129;181;122;135;150;121;193;121 20:56:23 INFO - PROCESS | 14726 | |2;audio-dft;162;160;162;161;161;161;162;161;160;159 20:56:23 INFO - PROCESS | 14726 | |3;audio-fft;75;76;77;104;76;77;75;74;100;76 20:56:23 INFO - PROCESS | 14726 | |4;audio-oscillator;85;86;135;85;84;85;118;85;85;134 20:56:23 INFO - PROCESS | 14726 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;117;117;116 20:56:23 INFO - PROCESS | 14726 | |6;imaging-darkroom;147;147;146;146;147;146;147;146;149;146 20:56:23 INFO - PROCESS | 14726 | |7;imaging-desaturate;95;95;94;95;96;94;94;95;94;95 20:56:23 INFO - PROCESS | 14726 | |8;json-parse-financial;64;62;61;62;63;63;63;62;63;65 20:56:23 INFO - PROCESS | 14726 | |9;json-stringify-tinderbox;65;66;65;65;65;65;67;66;66;66 20:56:23 INFO - PROCESS | 14726 | |10;stanford-crypto-aes;87;80;80;79;79;79;82;79;79;79 20:56:23 INFO - PROCESS | 14726 | |11;stanford-crypto-ccm;131;132;130;130;130;129;136;131;146;132 20:56:23 INFO - PROCESS | 14726 | |12;stanford-crypto-pbkdf2;169;169;165;171;167;167;167;165;164;169 20:56:23 INFO - PROCESS | 14726 | |13;stanford-crypto-sha256-iterative;69;66;75;70;67;66;68;67;67;67 20:56:23 INFO - PROCESS | 14726 | __end_tp_report 20:56:23 INFO - PROCESS | 14726 | __start_cc_report 20:56:23 INFO - PROCESS | 14726 | _x_x_mozilla_cycle_collect,1928 20:56:23 INFO - PROCESS | 14726 | __end_cc_report 20:56:23 INFO - PROCESS | 14726 | __startTimestamp1470887783538__endTimestamp 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | ------- Summary: start ------- 20:56:23 INFO - PROCESS | 14726 | Number of tests: 14 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.50 stddev:3.10 (2.7%) stddev-sans-first:0.73 20:56:23 INFO - PROCESS | 14726 | Values: 104.0 115.0 114.0 113.0 113.0 114.0 113.0 113.0 113.0 114.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:139.50 stddev:26.10 (18.7%) stddev-sans-first:27.67 20:56:23 INFO - PROCESS | 14726 | Values: 144.0 121.0 129.0 181.0 122.0 135.0 150.0 121.0 193.0 121.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:0.97 20:56:23 INFO - PROCESS | 14726 | Values: 162.0 160.0 162.0 161.0 161.0 161.0 162.0 161.0 160.0 159.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:11.15 (14.7%) stddev-sans-first:11.61 20:56:23 INFO - PROCESS | 14726 | Values: 75.0 76.0 77.0 104.0 76.0 77.0 75.0 74.0 100.0 76.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#4] audio-oscillator Cycles:10 Average:98.20 Median:85.00 stddev:21.73 (25.6%) stddev-sans-first:22.52 20:56:23 INFO - PROCESS | 14726 | Values: 85.0 86.0 135.0 85.0 84.0 85.0 118.0 85.0 85.0 134.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 20:56:23 INFO - PROCESS | 14726 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 20:56:23 INFO - PROCESS | 14726 | Values: 147.0 147.0 146.0 146.0 147.0 146.0 147.0 146.0 149.0 146.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 20:56:23 INFO - PROCESS | 14726 | Values: 95.0 95.0 94.0 95.0 96.0 94.0 94.0 95.0 94.0 95.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:63.00 stddev:1.14 (1.8%) stddev-sans-first:1.12 20:56:23 INFO - PROCESS | 14726 | Values: 64.0 62.0 61.0 62.0 63.0 63.0 63.0 62.0 63.0 65.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 20:56:23 INFO - PROCESS | 14726 | Values: 65.0 66.0 65.0 65.0 65.0 65.0 67.0 66.0 66.0 66.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:79.50 stddev:2.54 (3.2%) stddev-sans-first:1.01 20:56:23 INFO - PROCESS | 14726 | Values: 87.0 80.0 80.0 79.0 79.0 79.0 82.0 79.0 79.0 79.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#11] stanford-crypto-ccm Cycles:10 Average:132.70 Median:131.50 stddev:5.06 (3.8%) stddev-sans-first:5.33 20:56:23 INFO - PROCESS | 14726 | Values: 131.0 132.0 130.0 130.0 130.0 129.0 136.0 131.0 146.0 132.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:168.00 stddev:2.21 (1.3%) stddev-sans-first:2.26 20:56:23 INFO - PROCESS | 14726 | Values: 169.0 169.0 165.0 171.0 167.0 167.0 167.0 165.0 164.0 169.0 20:56:23 INFO - PROCESS | 14726 | 20:56:23 INFO - PROCESS | 14726 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:67.50 stddev:2.70 (4.0%) stddev-sans-first:2.85 20:56:23 INFO - PROCESS | 14726 | Values: 69.0 66.0 75.0 70.0 67.0 66.0 68.0 67.0 67.0 67.0 20:56:23 INFO - PROCESS | 14726 | -------- Summary: end -------- 20:56:23 INFO - PROCESS | 14726 | 20:56:24 INFO - TEST-INFO | 14726: exit 0 20:56:24 INFO - TEST-OK | kraken | took 63675ms 20:56:24 INFO - SUITE-END | took 1184s 20:56:24 INFO - Completed test suite (00:19:44) 20:56:24 INFO - javascript benchmark 20:56:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2648.3511539672777, 2662.77366423854, 2683.651125353705, 2686.8790904409307, 2695.528282550554, 4645.349651048848, 4712.646551508634, 4756.785944632491, 4882.292067650193, 4893.388326459192, 4760.928586071212, 4817.518248175181, 4827.8551643450755, 4894.119058569456, 4895.510448955103, 4317.740935543861, 4318.093200427914, 4364.825406983717, 4412.0, 4565.315202719586, 4482.753448560328, 4509.526499717532, 4532.4334458192725, 4554.134714404253, 4554.225781617117, 4243.745375277463, 4291.914161716748, 4396.868093957187, 4402.273624851896, 4503.166914120881, 4268.9146217075795, 4312.698111132222, 4340.153670034345, 4344.174606824693, 4365.7817109144735, 4291.935620965688, 4315.4821421429315, 4351.999040220767, 4359.912801743947, 4380.277254253045, 4756.167670657655, 4783.258594917758, 4803.1114243864995, 4828.589569886564, 4837.854864354075, 4960.8759781005765, 5141.897162056775, 5149.6910185389, 5209.114450543417, 5209.270702101709, 5185.455527169668, 5207.4792520748, 5248.107821670325, 5256.08018596744, 5303.337082864642, 4893.033570925513, 4944.90110197794, 4944.9258261126115, 4945.184044632595, 4951.356323677899, 5055.671381360274, 5256.632035757537, 5261.684298942076, 5310.9734451327495, 5415.079436495726, 5126.256692779526, 5159.329287192641, 5172.818951336761, 5178.689278643294, 5188.558972487305, 5214.97392513035, 5220.582353411718, 5221.582273418117, 5253.23828044939, 5269.130593452114, 4979.3277907482025, 5098.59211263098, 5132.589392848563, 5158.174692049329, 5165.948340516622, 5144.305518754995, 5146.536811686891, 5148.099082660519, 5152.278680984665, 5181.4041385240425, 4466.642668586505, 4470.642348612103, 4475.888102797456, 4507.639388848885, 4525.140223357552, 3762.6049264827348, 3797.525309336333, 3805.2769973705463, 3855.4795102660783, 3880.243352546283, 4415.757133357696, 4435.223835828717, 4475.328700694857, 4480.887977800581, 4484.327350897326, 5182.870428239249, 5194.558462530652, 5195.792168313301, 5196.168613021974, 5198.402183748842, 4280.636145927631, 4361.694681372273, 4429.68992170545, 4433.357163211381, 4467.195904737114, 4687.20317546018, 4702.412198475191, 4709.576138147583, 4730.290456431563, 4761.928571071437, 4868.634852386156, 4868.926966095441, 4873.683210591442, 4875.512448755097, 4912.95087049125, 4986.252062190701, 5095.796168153232, 5142.948570514247, 5176.767045482887, 5259.368875734936, 4838.201696720002, 4854.101991131652, 4854.247591623304, 4887.022595480989, 4893.559579637849, 5097.443835833881, 5106.66806657566, 5112.769925353294, 5115.718635474936, 5156.896862062813, 5009.699418034929, 5104.566111880383, 5113.539781419541, 5126.487351264844, 5158.406783219828, 5043.697378157175, 5046.0, 5047.848564543069, 5078.238264260391, 5081.364829396325, 5022.0, 5040.395152581714, 5042.319286896369, 5045.4450010498085, 5092.032513822362, 4732.479427263067, 4866.221404575251, 4926.802927882988, 4928.802847886044, 4992.301077849032, 5078.619103567174, 5079.3650793650795, 5082.822101226439, 5115.4884511550035, 5129.846104616868, 4969.652124351261, 4981.153203955264, 4983.6760610560195, 4985.601151907911, 5035.597152227886], "value": 4740.5728145671255, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.478789937459, 566.5269499967527, 567.9971600142, 583.2825624481887, 584.8040906296392, 28969.855150724245, 38490.23019539611, 39540.80229598855, 39553.40669889947, 43640.563594364045, 39855.80072099643, 40157.39763903544, 40249.7987510062, 40298.0, 40332.193356132935, 7676.731314403983, 7807.726729564466, 7810.687572497093, 7816.022997125359, 7817.726379576716, 7691.961540192291, 7765.572893490856, 7770.766876993699, 7776.144624091346, 7776.7666969990705, 7771.261730135628, 7806.80482987927, 7814.374850012014, 7817.921820781777, 7823.100343460518, 7744.574048427335, 7777.377809775205, 7778.49439786412, 7781.260780225926, 7794.181610930827, 7705.190954949761, 7752.186020467882, 7758.844823103563, 7765.223477652246, 7766.417518686121, 37426.81286593577, 37729.24541509182, 37746.0, 37768.81115594432, 37790.0, 2963.6739958604535, 3147.84260786962, 3173.349463360029, 3347.113015050989, 3365.512000759876, 28527.57208641872, 28862.0, 29150.562741558682, 29224.41551168986, 29331.0, 3437.34690408824, 3511.9297614047578, 3548.698360639323, 3572.7141828653644, 3619.1675914539805, 259532.0, 259700.0, 260953.9999999981, 261167.0, 261816.0, 51032.48967510278, 51317.7434112827, 51401.7429912848, 51417.228741568906, 51470.48529514657, 53111.46888531143, 53292.73353633207, 53300.0, 53319.73340133352, 53353.733231333594, 14547.563573092824, 14813.407463701547, 14841.0, 14844.92577537127, 14855.554333370017, 11247.437628118725, 11342.376169310603, 11354.829677554842, 11356.26184298018, 11369.488373023234, 14585.270736463135, 15096.320665570076, 15101.093934363973, 15141.470048548248, 15291.61770955735, 5738.741756620962, 5783.0, 5865.765369385177, 5928.4960778334325, 5970.253718285215, 652.3346186889346, 794.5510786405662, 827.4042689263721, 893.883795106632, 926.4533924984157, 26610.733892661214, 27921.022764203157, 27926.44147117087, 28088.43823123526, 28147.5926203689, 733.9045924029842, 992.9404235745878, 1013.2704452793977, 1026.984595231072, 1048.4233671480808, 14046.0, 14959.41939283815, 14992.175430351435, 15178.392864285526, 15179.620509487351, 369589.0, 372814.0, 373051.0, 373139.0, 373362.0, 49239.0, 49339.753301233264, 49387.50612493901, 49417.0, 49423.75288123536, 1154.630518234174, 1161.12335186935, 1184.5380301682524, 1215.1433239566084, 1215.9817602735966, 25516.0, 26426.471470570097, 26835.32911677224, 27318.453630927666, 27405.451890962468, 39759.60240397674, 40064.59935400609, 40130.598694013854, 40134.598654014255, 40149.799251003555, 330834.0, 333007.0, 333020.0, 333141.0, 333232.0, 35390.64609353976, 35643.28713425665, 35676.0, 35707.642923570435, 35748.821255893556, 40786.59213407828, 41193.38209926793, 41476.0, 41782.164356712085, 41958.790206048776, 35496.64503355037, 35765.46351804673, 36047.639523604426, 36414.0, 36563.63436365602, 35284.647153529164, 35432.29135417226, 35794.64205357913, 36030.8198459006, 36332.81833590921, 30817.229569260948, 31653.683463165074, 33689.831550842086, 35386.0, 35931.46102808408, 31027.689723103384, 33385.83307083449, 33494.49758253579, 33539.496907545916, 33717.0, 24828.255152345457, 25598.24368825526, 25702.0, 25776.423712246175, 25792.226233213034], "value": 17383.042545459244, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.89443378118983, 351.3920916813913, 356.8697425439715, 360.51510718084165, 388.5589855513991, 12763.1703939244, 15956.122413267265, 16405.261763220653, 16800.411985580507, 17800.28798848048, 17014.744778828295, 17312.480625581222, 17390.739138912926, 17624.29502819889, 17707.64584708305, 4896.730679812609, 4931.211006239003, 5100.464451232623, 5106.234064890277, 5120.155174396239, 3724.8696295629657, 5050.520200580939, 5136.58907287418, 5586.804461843816, 5909.704514774266, 5885.293764748236, 5912.9704351478185, 5925.733341999598, 5929.347771745104, 5931.228940237785, 5126.820561280356, 5382.838514844521, 5401.108817045183, 5482.753276102545, 5920.230370051887, 5297.682139071668, 5299.258103865462, 5299.735013249322, 5308.362996440414, 5311.601629877775, 18254.908725456422, 20326.28857989978, 20742.06660700257, 20942.895285523628, 21033.36899893006, 1896.781870084944, 1909.3699079303935, 1950.54162271866, 1967.3409407848244, 1970.231609672229, 8327.292180164693, 8908.198262156373, 9020.639174433014, 11678.941605292004, 13483.123596966263, 1772.2645102282538, 1816.8092350303293, 1817.2367605605546, 1827.0773259503999, 1837.9356722514647, 63770.681146594434, 68595.0, 68801.0, 70594.29405705929, 70918.2908170922, 9253.120953509406, 9514.714558563253, 9758.463284519277, 9901.702948911545, 9997.800043999225, 13572.796408053888, 13593.388297526635, 13595.45618175262, 13611.319434028457, 13618.250996195105, 7056.15326160864, 7375.8156046099275, 7403.519296140794, 7508.511946723445, 7538.886916696254, 7026.43788496919, 7059.364657180879, 7252.637368131573, 7263.4915555910575, 7284.053073100463, 4185.204811085884, 4213.662906967496, 4277.786110694515, 4392.5607439256455, 4406.449193850769, 1749.868759843017, 1772.6986412309939, 1778.262021261188, 1845.640100180452, 1846.4645252876514, 679.6873438218474, 839.4711331860889, 885.6590212768002, 950.6577632052594, 979.3487330924855, 16558.0, 16843.0, 17219.82780172182, 17501.562460938323, 17799.911000444914, 854.3976496569903, 855.8202777416813, 865.3466632692277, 911.8449863523217, 924.2282693950492, 7038.577685338896, 9219.953900230455, 9800.803983920281, 9859.35914165577, 9915.107640312262, 392554.0, 393412.0, 401041.0, 401587.0, 404889.0, 46609.766951165024, 48571.271430928566, 73916.0, 74517.0, 76366.61816690992, 883.8541640629225, 909.9636014559476, 941.7833898203452, 951.0679534056663, 952.6903756279237, 15618.843811562194, 15923.840761592235, 16336.183190840173, 16457.91771041137, 16514.66970660604, 37098.62901370952, 38458.23083538369, 38568.228635427695, 39376.2124757509, 39402.2119557613, 48484.757576213306, 52512.7374363141, 52861.471385287194, 53050.734746327566, 54734.178987316016, 6499.740010399721, 6698.330166983263, 6786.796396108125, 6958.165020197609, 7536.284053014955, 9330.393524420891, 11578.652640420802, 11800.940995294968, 11817.172797904064, 12516.80821390576, 9651.662191823252, 10430.800457947433, 10470.633527826489, 10597.046265835803, 11144.777104457704, 9721.076497732704, 9744.610215591298, 10007.799844002933, 10404.531796069346, 10482.790344193227, 5398.622096453368, 10346.37786858199, 10468.476576171313, 10547.156227501935, 10649.840252396376, 6697.966510167418, 6920.723171073302, 6965.0, 7114.110736157981, 7190.101237345332, 9052.411593246417, 10419.479026048819, 10538.631147909786, 10583.470826458492, 10671.253012289066], "value": 8145.1416202471255, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.9533816316427, 1387.5074348606245, 1422.850600686928, 1619.4089157457518, 1644.235430524805, 6714.227863795665, 7236.9638151809295, 7240.275972402757, 7244.963775181117, 7245.094363204606, 6069.302030266521, 6263.060540918853, 6765.188177418717, 7004.614746188958, 7181.24596917324, 2094.32981445937, 2123.9469013274716, 2127.702121702955, 2128.372130221588, 2128.6062078515447, 2049.272508259569, 2078.9792102078936, 2122.2890331738886, 2123.936281911545, 2124.75565309989, 1751.2819743905002, 2041.2141325589607, 2107.1676687708346, 2108.641530939744, 2134.8185404240658, 2067.9896600516977, 2083.874967501955, 2084.1871670048695, 2087.2068613926613, 2090.5923344947664, 1930.8165724256173, 1951.853610979168, 1953.2284747524648, 1953.7753158386824, 1953.794851540582, 7123.145222573325, 7154.1415030196185, 7163.462740294499, 7178.856422871513, 7181.3895818854935, 4213.726107803013, 4345.587169218919, 4398.054418300081, 4403.097365040159, 4403.779811009436, 2617.4895895300424, 2732.002818971061, 2765.14280573021, 2765.806393552452, 2774.7918906082123, 825.5748289630845, 848.1221935296938, 861.3884142259004, 869.42183448007, 875.9124087591254, 9366.765830854216, 9512.191463725592, 9522.571484283293, 9574.808503829885, 9608.519574021271, 3700.2784457031157, 3850.9229815403537, 3885.3589157789215, 3944.723869329177, 3945.2898478274187, 8193.180681931759, 8201.26188643025, 8205.876911846208, 8213.548254845922, 8220.095789463154, 4258.808353624095, 4266.2960611499375, 4280.336547835091, 4283.785810709452, 4286.89282767927, 3969.2458432898234, 3971.126352202511, 3971.404289356562, 3973.2450834341385, 3978.701597380208, 2983.4331477019296, 3082.3989322082425, 3104.363605460875, 3122.437961167012, 3145.811251324928, 956.5982287439292, 956.6412595276771, 957.4733896356976, 977.5503268496572, 985.7437066362654, 629.3643420145686, 729.8832785837677, 798.5927177139585, 836.2891542189212, 858.4720396955952, 4790.113828941657, 4891.290762839369, 4901.828436004723, 4919.581835544019, 5092.0, 844.9704260350982, 874.2961915657957, 875.2341701011615, 878.8418084744808, 879.5030807593689, 3237.2392487765355, 3463.7229021678204, 3500.072480792592, 3536.717062634983, 3568.57177138745, 8366.958165209257, 8804.295656347605, 8805.515696636618, 8806.955965220133, 8818.603162857687, 8689.217970382695, 8729.258013068833, 8754.56227188638, 8756.956215218883, 8866.379353445325, 6766.052752614638, 6841.076454678654, 6873.278305777922, 6884.0362349271145, 6888.035675005503, 4859.271109333628, 4985.501449854985, 5068.518490743521, 5114.846554603368, 5117.618243074424, 7768.572728499987, 7982.0, 8147.185281471805, 8158.4289099762445, 8164.224398682116, 8227.506349619041, 8292.834143316979, 8297.543635099886, 8314.251717345467, 8437.915620843713, 6384.776532821515, 6522.967385163044, 6523.282438931618, 6530.8040758777315, 6536.084948107364, 6702.899456508254, 6917.515773895779, 6930.8613827724175, 6956.965215173892, 7037.542559733805, 6319.9052014218905, 6385.808425747235, 6390.7763228286785, 6433.485321174388, 6436.8390790230615, 6083.452489275809, 6357.110004599267, 6369.808905732835, 6404.935950640434, 6481.708323125376, 5712.200291959046, 5840.415958404125, 5892.440218179266, 5912.615679980788, 6039.365866584121, 4330.0, 4368.694191406698, 4405.361222622766, 4449.043455657049, 4577.702449340783, 4120.979395103005, 4121.402396652528, 4220.113776107052, 4300.935485967776, 4361.738295702268], "value": 3847.1242822292534, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.3899893227425, 292.8843106972746, 293.98824047038113, 294.5052312115645, 297.80791389553724, 4726.078414709129, 4890.017089575356, 4915.689625875837, 4922.6800257983205, 4984.853483698743, 4842.515748425156, 4855.684380515264, 4872.7563621818945, 4872.7563621818945, 4923.178423983131, 3613.1328481164596, 3670.1925576373155, 3714.4985426967282, 3814.4087666411683, 3818.4463252828464, 3749.5125633667585, 3765.8305376258, 3766.8869933901888, 3768.547774267092, 3772.735908486407, 3727.7204209684382, 3737.177820879402, 3737.7009839212933, 3739.7943113128767, 3740.719446041544, 3552.3428165789155, 3587.856485740567, 3665.651763082503, 3734.7012239020814, 3752.699784017272, 3225.9031929144203, 3227.644959054502, 3233.2725136844256, 3236.543555400079, 3248.31785325082, 4721.409823772028, 4728.148933192024, 4729.125111854318, 4733.3196715166305, 4741.672331747116, 4165.58344165584, 4277.2087163874785, 4290.077633308853, 4303.483581970152, 4305.311150215982, 5019.924701129486, 5038.773255203525, 5047.318611987372, 5047.899042019176, 5057.002484018829, 3546.5921419036627, 3546.627604101558, 3548.1307079765625, 3549.804760738158, 3550.5029295898594, 5291.86770330741, 5308.203769434577, 5358.588635114214, 5359.678419294854, 5373.382061063028, 3581.8925432237074, 3644.2347107107275, 3655.141041855162, 3662.6154253802965, 3666.8166591670843, 3688.704903607705, 3697.927600995681, 3698.907527311785, 3704.0, 3704.851496036237, 4254.595813397784, 4260.488741351058, 4262.339337402707, 4264.808083636244, 4277.91444171121, 4881.9023619528125, 5015.673981191211, 5106.974465127651, 5114.105031619451, 5114.769835357418, 1249.9625011249677, 1264.4752427742567, 1269.3018839638162, 1273.1342686972762, 1278.1180985120238, 792.6948124971811, 798.9280964713204, 825.8885050518103, 827.6937533112666, 828.8176601147737, 4288.292431748789, 4386.649068074546, 4448.419916448453, 4470.262406702923, 4484.39460672805, 5204.8178313758835, 5237.109691352479, 5245.10833158364, 5248.553872920845, 5250.081235783722, 4426.9778651106535, 4450.888727781767, 4461.661501549587, 4482.21561226784, 4482.439695038121, 2631.0265201875427, 2760.9430973924154, 2765.764909982634, 2771.902983395612, 2778.4998700233746, 4864.756762161877, 4870.707757534559, 4897.1674815281485, 4902.137187868587, 4904.583110435653, 5169.9741501293, 5323.760430780624, 5329.813456529003, 5355.277037599874, 5368.409474957752, 4838.653253172209, 4876.561109500162, 4880.0, 4888.388951381077, 4897.608191344755, 5162.97418512905, 5230.921536176884, 5239.89520209586, 5241.895162096813, 5266.210068489757, 4988.376452943382, 5148.9580679166675, 5157.974210128926, 5167.896642067213, 5178.223266510054, 4865.7323847188745, 4882.267659851051, 4883.364073035573, 4886.706797592014, 4888.437829649493, 3513.5067596271992, 3530.5057291979656, 3531.911702207465, 3538.4869193967247, 3539.716822654233, 5026.5727413168825, 5263.578913686973, 5279.287296214962, 5280.471952804689, 5281.10221262394, 5012.498750124958, 5045.369328833895, 5060.115734031714, 5068.138416469135, 5080.212567052113, 5039.571636410799, 5071.92392114126, 5078.822241221539, 5094.981003799181, 5103.916866506854, 4794.976025119852, 4975.353204083446, 4975.776090075883, 4980.302757613865, 4998.4251811042195, 4858.441279252931, 4887.975560122177, 5057.69653820772, 5072.264521644267, 5100.974495127501, 4978.975105124451, 4996.850094497171, 5000.524950129732, 5007.949920500894, 5014.022265658162], "value": 3791.4578207440136, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.19592175116, 713.2724620886693, 716.6954044531075, 718.0450001498008, 744.5272252119904, 13021.934890325547, 14053.789193162098, 14154.929225353884, 14182.149071055745, 14184.36170372333, 13417.664558386045, 13480.0, 13512.662183445418, 13530.594082177526, 13558.864411355884, 612.3080918562018, 632.5350867112669, 746.4662766122221, 751.2862780358668, 771.4214339245566, 758.0827199089109, 761.0600907878766, 764.2624867003341, 766.0845289878597, 767.3937589304443, 826.6941231744263, 827.383599218583, 827.5365795154703, 827.7475370012144, 827.8220182660726, 659.2846761264034, 819.0212695828487, 819.397742659145, 819.8319344534416, 820.9096999330069, 577.8584246859547, 594.4887396838715, 596.8806238752267, 596.9880602387927, 598.2611474828583, 13488.86511134886, 13607.251601161932, 13611.591652250449, 13642.249676267802, 13649.863501364958, 11775.94112029443, 12250.038580054872, 12327.0, 12441.937790311082, 12587.874121258761, 9895.455749933752, 10221.540030698636, 11625.418729063513, 11632.592859249886, 11812.468438920268, 4074.653654439376, 4116.773577453238, 4127.752334859888, 4162.188373267214, 4327.480702315711, 59690.0, 60109.398906010814, 60166.398336016515, 60260.0, 60435.39564604386, 10116.342437741523, 10117.39295642249, 10139.442330671736, 10156.33983791066, 10556.947215263874, 17545.56136096582, 18088.547786305448, 18097.276108955495, 18112.094395280183, 18118.818811811714, 6788.626625535643, 6874.793756187322, 6954.3393377629045, 6988.312855858515, 7157.463190260752, 3667.9266414671556, 3674.0, 3679.9448008279896, 3717.9628203718157, 3775.320442320409, 2820.70382609823, 2935.882564697431, 2937.9706202937696, 2938.4710752064407, 2947.439986402577, 506.0562051773416, 516.40097486915, 519.63885099856, 522.9424763276037, 529.7324850950246, 543.6683622989973, 584.2259006815985, 588.7556663984484, 619.1053927075423, 632.6552029144127, 8803.911960880438, 8822.2060014599, 8980.685676001309, 9044.547772611342, 9105.635774569075, 552.6076485695121, 557.1420013179738, 562.7411390760215, 570.6918264137403, 598.2193237824594, 4204.768737719455, 4369.148016136886, 4450.6439484841785, 4476.127155204769, 4540.182767101887, 62402.68798655977, 66926.66536667383, 96608.03391965991, 110456.44771776251, 110640.0, 89759.55120224488, 94861.0, 95143.0, 95296.0, 96073.5196324028, 73075.63462182655, 78621.21378786139, 79193.20806791974, 79256.60371698104, 80972.59513702393, 8124.593770311342, 8337.95831020841, 8391.202835730595, 8532.658693652184, 8603.354748393771, 30174.54738178973, 38748.6125138745, 40063.1987360257, 40523.797381012904, 50189.0, 39732.60267397289, 55078.0, 57501.712491437276, 62236.68881655744, 62271.0, 11256.887431125584, 11499.8275025873, 11674.182807203415, 11826.172167948505, 11848.526058957545, 12158.088143389105, 12712.682182945131, 13217.537386191438, 13913.0, 14008.929955350159, 12215.299360089786, 12800.295983720986, 12927.28899910514, 12965.805512917503, 13077.803832942323, 11799.587014454453, 12828.10203285761, 12843.678908027001, 12852.0, 12859.233801072289, 12003.699907502383, 12235.0, 12348.259104453762, 13008.0, 13029.609111726664, 9852.852207217042, 10014.298999069995, 10088.445135517324, 10089.646862359783, 10128.746781330527, 10199.54549079022, 10292.309147668197, 10437.68686939393, 10469.26715129964, 10487.318324308895], "value": 6679.802723490569, "unit": "score"}], "name": "dromaeo_css", "value": 6347.492471410558, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 121.0, 129.0, 181.0, 122.0, 135.0, 150.0, 121.0, 193.0, 121.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 161.0, 161.0, 161.0, 162.0, 161.0, 160.0, 159.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 104.0, 76.0, 77.0, 75.0, 74.0, 100.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 135.0, 85.0, 84.0, 85.0, 118.0, 85.0, 85.0, 134.0], "value": 98.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 149.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 96.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 61.0, 62.0, 63.0, 63.0, 63.0, 62.0, 63.0, 65.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 67.0, 66.0, 66.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 80.0, 80.0, 79.0, 79.0, 79.0, 82.0, 79.0, 79.0, 79.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 132.0, 130.0, 130.0, 130.0, 129.0, 136.0, 131.0, 146.0, 132.0], "value": 132.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 165.0, 171.0, 167.0, 167.0, 167.0, 165.0, 164.0, 169.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 75.0, 70.0, 67.0, 66.0, 68.0, 67.0, 67.0, 67.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1529.4999999999998, "alertThreshold": 2.0}]} 20:56:24 INFO - Return code: 0 20:56:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:56:24 INFO - # TBPL SUCCESS # 20:56:24 INFO - Running post-action listener: _resource_record_post_action 20:56:24 INFO - [mozharness: 2016-08-11 03:56:24.861731Z] Finished run-tests step (success) 20:56:24 INFO - Running post-run listener: _resource_record_post_run 20:56:24 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 110592; Write bytes: 530001920; Read time: 64; Write time: 712876 20:56:24 INFO - TinderboxPrint: CPU usage
13.0% 20:56:24 INFO - TinderboxPrint: I/O read bytes / time
110,592 / 64 20:56:24 INFO - TinderboxPrint: I/O write bytes / time
530,001,920 / 712,876 20:56:24 INFO - TinderboxPrint: CPU idle
8,337.0 (87.0%) 20:56:24 INFO - TinderboxPrint: CPU user
1,200.1 (12.5%) 20:56:24 INFO - TinderboxPrint: Swap in / out
0 / 0 20:56:24 INFO - install - Wall time: 16s; CPU: 17.0%; Read bytes: 0; Write bytes: 244060160; Read time: 0; Write time: 435700 20:56:24 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 106496; Write bytes: 285941760; Read time: 48; Write time: 277176 20:56:25 INFO - Running post-run listener: _upload_blobber_files 20:56:25 INFO - Blob upload gear active. 20:56:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:56:25 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 20:56:25 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:56:25 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:56:25 INFO - (blobuploader) - INFO - Open directory for files ... 20:56:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:56:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:56:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:56:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:56:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:56:29 INFO - (blobuploader) - INFO - Done attempting. 20:56:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:56:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:56:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:56:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:56:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:56:29 INFO - (blobuploader) - INFO - Done attempting. 20:56:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:56:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:56:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:56:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:56:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:56:30 INFO - (blobuploader) - INFO - Done attempting. 20:56:30 INFO - (blobuploader) - INFO - Iteration through files over. 20:56:30 INFO - Return code: 0 20:56:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:56:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:56:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/44012209ad8dacb7be19b7c512a2158c7cafe8766eb6522facccc787e30cb2725b564640826a445fa8afc256d40e8221b64334aa9ff9cc428aeb60ab5a327c7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/afdcc3bf7e6fce1929d623330b4f9efe82bc4b5e380d130b8d2d2c8ace1f97925dad3163e3e40c9d01c1c32be6b917eb4bc9a1adaabae25e6eca8fb2a9326a4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/acdfdb2f373cae6796007714d5e68b2d74bb7aad8359f147481ca1f2ac2f6433a9b01568b45dd074c244ed3d04b8ae276401b2df837df73fb3058ba5431816cd"} 20:56:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:56:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:56:30 INFO - Contents: 20:56:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/44012209ad8dacb7be19b7c512a2158c7cafe8766eb6522facccc787e30cb2725b564640826a445fa8afc256d40e8221b64334aa9ff9cc428aeb60ab5a327c7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/afdcc3bf7e6fce1929d623330b4f9efe82bc4b5e380d130b8d2d2c8ace1f97925dad3163e3e40c9d01c1c32be6b917eb4bc9a1adaabae25e6eca8fb2a9326a4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/acdfdb2f373cae6796007714d5e68b2d74bb7aad8359f147481ca1f2ac2f6433a9b01568b45dd074c244ed3d04b8ae276401b2df837df73fb3058ba5431816cd"} 20:56:30 INFO - Running post-run listener: copy_logs_to_upload_dir 20:56:30 INFO - Copying logs to upload dir... 20:56:30 INFO - mkdir: /builds/slave/test/build/upload/logs 20:56:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.545684 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-08-10 20:56:30.657169) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 20:56:30.661025) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/44012209ad8dacb7be19b7c512a2158c7cafe8766eb6522facccc787e30cb2725b564640826a445fa8afc256d40e8221b64334aa9ff9cc428aeb60ab5a327c7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/afdcc3bf7e6fce1929d623330b4f9efe82bc4b5e380d130b8d2d2c8ace1f97925dad3163e3e40c9d01c1c32be6b917eb4bc9a1adaabae25e6eca8fb2a9326a4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/acdfdb2f373cae6796007714d5e68b2d74bb7aad8359f147481ca1f2ac2f6433a9b01568b45dd074c244ed3d04b8ae276401b2df837df73fb3058ba5431816cd"} build_url:https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011209 build_url: 'https://queue.taskcluster.net/v1/task/fKbuOPgwRfiqt1KFXTYsfQ/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/Autoland-Non-PGO/sha512/44012209ad8dacb7be19b7c512a2158c7cafe8766eb6522facccc787e30cb2725b564640826a445fa8afc256d40e8221b64334aa9ff9cc428aeb60ab5a327c7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/afdcc3bf7e6fce1929d623330b4f9efe82bc4b5e380d130b8d2d2c8ace1f97925dad3163e3e40c9d01c1c32be6b917eb4bc9a1adaabae25e6eca8fb2a9326a4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/acdfdb2f373cae6796007714d5e68b2d74bb7aad8359f147481ca1f2ac2f6433a9b01568b45dd074c244ed3d04b8ae276401b2df837df73fb3058ba5431816cd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 20:56:30.687257) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 20:56:30.687597) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470883089.992373-1825056396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004536 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 20:56:30.770305) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 20:56:30.770627) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 20:56:30.770919) ========= ========= Total master_lag: 0.18 =========