builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1474518533.51 results: success (0) buildid: 20160921183021 builduid: 1496cf5f77484793b4a1ff8feaae2798 revision: 1da8daaf2082616b3c19b31603eea886555aa2a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:53.510054) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:53.511054) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:53.511787) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:53.548572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:53.549276) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 21:28:53-- 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% 40.5M=0s 2016-09-21 21:28:53 (40.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087326 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:53.652519) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:53.652903) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015497 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:53.702653) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 21:28:53.703058) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 1da8daaf2082616b3c19b31603eea886555aa2a2 --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 1da8daaf2082616b3c19b31603eea886555aa2a2 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 21:28:53,789 truncating revision to first 12 chars 2016-09-21 21:28:53,789 Setting DEBUG logging. 2016-09-21 21:28:53,789 attempt 1/10 2016-09-21 21:28:53,790 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/1da8daaf2082?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 21:28:54,495 unpacking tar archive at: autoland-1da8daaf2082/testing/mozharness/ program finished with exit code 0 elapsedTime=1.041547 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 21:28:54.758635) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:54.758995) ========= script_repo_revision: 1da8daaf2082616b3c19b31603eea886555aa2a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:54.759416) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:54.759810) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 21:28:54.769295) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-21 21:28:54.769753) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:28:54 INFO - MultiFileLogger online at 20160921 21:28:54 in /builds/slave/test 21:28:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 21:28:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:28:54 INFO - {'append_to_log': False, 21:28:54 INFO - 'base_work_dir': '/builds/slave/test', 21:28:54 INFO - 'blob_upload_branch': 'Autoland', 21:28:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:28:54 INFO - 'branch': 'Autoland', 21:28:54 INFO - 'buildbot_json_path': 'buildprops.json', 21:28:54 INFO - 'config_files': ('talos/linux_config.py',), 21:28:54 INFO - 'default_actions': ('clobber', 21:28:54 INFO - 'read-buildbot-config', 21:28:54 INFO - 'download-and-extract', 21:28:54 INFO - 'populate-webroot', 21:28:54 INFO - 'create-virtualenv', 21:28:54 INFO - 'install', 21:28:54 INFO - 'run-tests'), 21:28:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:28:54 INFO - 'download_minidump_stackwalk': True, 21:28:54 INFO - 'download_symbols': 'ondemand', 21:28:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:28:54 INFO - 'tooltool.py': '/tools/tooltool.py', 21:28:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:28:54 INFO - '/tools/misc-python/virtualenv.py')}, 21:28:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:28:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:28:54 INFO - 'installer_path': 'installer.exe', 21:28:54 INFO - 'log_level': 'info', 21:28:54 INFO - 'log_name': 'talos', 21:28:54 INFO - 'log_to_console': True, 21:28:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:28:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:28:54 INFO - 'opt_config_files': (), 21:28:54 INFO - 'pip_index': False, 21:28:54 INFO - 'sps_profile': False, 21:28:54 INFO - 'sps_profile_interval': 0, 21:28:54 INFO - 'suite': 'dromaeojs', 21:28:54 INFO - 'system_bits': '32', 21:28:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:28:54 INFO - 'title': 'talos-linux64-ix-041', 21:28:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:28:54 INFO - 'use_talos_json': True, 21:28:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:28:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:28:54 INFO - 'work_dir': 'build'} 21:28:54 INFO - [mozharness: 2016-09-22 04:28:54.946600Z] Running clobber step. 21:28:54 INFO - Running pre-action listener: _resource_record_pre_action 21:28:54 INFO - Running main action method: clobber 21:28:54 INFO - rmtree: /builds/slave/test/build 21:28:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:28:55 INFO - Running post-action listener: _resource_record_post_action 21:28:55 INFO - [mozharness: 2016-09-22 04:28:55.407822Z] Finished clobber step (success) 21:28:55 INFO - [mozharness: 2016-09-22 04:28:55.407928Z] Running read-buildbot-config step. 21:28:55 INFO - Running pre-action listener: _resource_record_pre_action 21:28:55 INFO - Running main action method: read_buildbot_config 21:28:55 INFO - Using buildbot properties: 21:28:55 INFO - { 21:28:55 INFO - "project": "", 21:28:55 INFO - "product": "firefox", 21:28:55 INFO - "script_repo_revision": "production", 21:28:55 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 21:28:55 INFO - "repository": "", 21:28:55 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 21:28:55 INFO - "buildid": "20160921183021", 21:28:55 INFO - "pgo_build": "True", 21:28:55 INFO - "basedir": "/builds/slave/test", 21:28:55 INFO - "buildnumber": 241, 21:28:55 INFO - "slavename": "talos-linux64-ix-041", 21:28:55 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:28:55 INFO - "platform": "ubuntu64_hw", 21:28:55 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 21:28:55 INFO - "branch": "autoland", 21:28:55 INFO - "repo_path": "integration/autoland", 21:28:55 INFO - "revision": "1da8daaf2082616b3c19b31603eea886555aa2a2", 21:28:55 INFO - "stage_platform": "linux64-pgo", 21:28:55 INFO - "builduid": "1496cf5f77484793b4a1ff8feaae2798", 21:28:55 INFO - "slavebuilddir": "test" 21:28:55 INFO - } 21:28:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 21:28:55 INFO - Running post-action listener: _resource_record_post_action 21:28:55 INFO - [mozharness: 2016-09-22 04:28:55.410458Z] Finished read-buildbot-config step (success) 21:28:55 INFO - [mozharness: 2016-09-22 04:28:55.410548Z] Running download-and-extract step. 21:28:55 INFO - Running pre-action listener: _resource_record_pre_action 21:28:55 INFO - Running main action method: download_and_extract 21:28:55 INFO - mkdir: /builds/slave/test/build/tests 21:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:55 INFO - trying https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:28:55 INFO - Downloading https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:28:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:28:55 INFO - Downloaded 1361 bytes. 21:28:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:28:55 INFO - Using the following test package requirements: 21:28:55 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 21:28:55 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:28:55 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:28:55 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'jsshell-linux-x86_64.zip'], 21:28:55 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:28:55 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 21:28:55 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:28:55 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:28:55 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:28:55 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:28:55 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:28:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 21:28:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:28:55 INFO - Fetch https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:28:56 INFO - Expected file size: 21944236 21:29:01 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:29:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 21:29:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:29:01 INFO - Fetch https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:29:01 INFO - Expected file size: 21944236 21:29:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 21:29:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:29:06 INFO - Fetch https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:29:06 INFO - Expected file size: 14414059 21:29: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')]} 21:29:07 INFO - trying https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:29:07 INFO - Downloading https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:29:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:29:08 INFO - Downloaded 59569762 bytes. 21:29:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:29:08 INFO - mkdir: /builds/slave/test/properties 21:29:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:29:08 INFO - Writing to file /builds/slave/test/properties/build_url 21:29:08 INFO - Contents: 21:29:08 INFO - build_url:https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:29:09 INFO - Running post-action listener: _resource_record_post_action 21:29:09 INFO - Running post-action listener: set_extra_try_arguments 21:29:09 INFO - [mozharness: 2016-09-22 04:29:09.150299Z] Finished download-and-extract step (success) 21:29:09 INFO - [mozharness: 2016-09-22 04:29:09.150530Z] Running populate-webroot step. 21:29:09 INFO - Running pre-action listener: _resource_record_pre_action 21:29:09 INFO - Running main action method: populate_webroot 21:29:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:29:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:29:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:29:09 INFO - '--tppagecycles', 21:29:09 INFO - '7'], 21:29:09 INFO - 'tests': ['tsvgm']}}, 21:29:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:29:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:29:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:29:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:29:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:09 INFO - 'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:29:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:29:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:09 INFO - 'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['damp', 'tps']}, 21:29:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:09 INFO - 'tests': ['damp', 'tps']}, 21:29:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['dromaeo_dom']}, 21:29:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:29:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['basic_compositor_video']}, 21:29:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:29:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['a11yr', 21:29:09 INFO - 'ts_paint', 21:29:09 INFO - 'tpaint', 21:29:09 INFO - 'sessionrestore', 21:29:09 INFO - 'sessionrestore_no_auto_restore', 21:29:09 INFO - 'tabpaint']}, 21:29:09 INFO - 'other-e10s': {'tests': ['a11yr', 21:29:09 INFO - 'ts_paint', 21:29:09 INFO - 'tpaint', 21:29:09 INFO - 'sessionrestore', 21:29:09 INFO - 'sessionrestore_no_auto_restore', 21:29:09 INFO - 'tabpaint']}, 21:29:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:29:09 INFO - 'ts_paint', 21:29:09 INFO - 'tpaint', 21:29:09 INFO - 'sessionrestore', 21:29:09 INFO - 'sessionrestore_no_auto_restore', 21:29:09 INFO - 'tabpaint']}, 21:29:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:29:09 INFO - 'ts_paint', 21:29:09 INFO - 'tpaint', 21:29:09 INFO - 'sessionrestore', 21:29:09 INFO - 'sessionrestore_no_auto_restore', 21:29:09 INFO - 'tabpaint']}, 21:29:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['a11yr', 21:29:09 INFO - 'ts_paint', 21:29:09 INFO - 'tpaint', 21:29:09 INFO - 'sessionrestore', 21:29:09 INFO - 'sessionrestore_no_auto_restore', 21:29:09 INFO - 'tabpaint']}, 21:29:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['a11yr', 21:29:09 INFO - 'ts_paint', 21:29:09 INFO - 'tpaint', 21:29:09 INFO - 'sessionrestore', 21:29:09 INFO - 'sessionrestore_no_auto_restore', 21:29:09 INFO - 'tabpaint']}, 21:29:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['tsvgx', 21:29:09 INFO - 'tsvgr_opacity', 21:29:09 INFO - 'tart', 21:29:09 INFO - 'tscrollx', 21:29:09 INFO - 'cart']}, 21:29:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:29:09 INFO - 'tsvgr_opacity', 21:29:09 INFO - 'tart', 21:29:09 INFO - 'tscrollx', 21:29:09 INFO - 'cart']}, 21:29:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:09 INFO - 'talos_options': ['--disable-e10s'], 21:29:09 INFO - 'tests': ['tp5o']}, 21:29:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:09 INFO - 'tests': ['tp5o']}, 21:29:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:09 INFO - 'talos_options': ['--disable-e10s', 21:29:09 INFO - '--xperf_path', 21:29:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:29:09 INFO - 'tests': ['tp5n']}, 21:29:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:09 INFO - 'talos_options': ['--xperf_path', 21:29:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:29:09 INFO - 'tests': ['tp5n']}}, 21:29:09 INFO - 'talos.zip': {'path': '', 21:29:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:29:09 INFO - Running post-action listener: _resource_record_post_action 21:29:09 INFO - [mozharness: 2016-09-22 04:29:09.172441Z] Finished populate-webroot step (success) 21:29:09 INFO - [mozharness: 2016-09-22 04:29:09.172532Z] Running create-virtualenv step. 21:29:09 INFO - Running pre-action listener: _resource_record_pre_action 21:29:09 INFO - Running main action method: create_virtualenv 21:29:09 INFO - Creating virtualenv /builds/slave/test/build/venv 21:29:09 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 21:29:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:29:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:29:09 INFO - Using real prefix '/usr' 21:29:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:29:09 INFO - Installing distribute.............................................................................................................................................................................................done. 21:29:11 INFO - Installing pip.................done. 21:29:11 INFO - Return code: 0 21:29:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:11 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 0x7f93a1915f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2abf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', '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 21:29:11 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 21:29:11 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 21:29:11 INFO - Using env: {'DISPLAY': ':0', 21:29:11 INFO - 'HOME': '/home/cltbld', 21:29:11 INFO - 'LANG': 'en_US.UTF-8', 21:29:11 INFO - 'LANGUAGE': 'en_US:en', 21:29:11 INFO - 'LOGNAME': 'cltbld', 21:29:11 INFO - 'MAIL': '/var/mail/cltbld', 21:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:11 INFO - 'MOZ_NO_REMOTE': '1', 21:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:11 INFO - 'NO_EM_RESTART': '1', 21:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:11 INFO - 'PWD': '/builds/slave/test', 21:29:11 INFO - 'SHELL': '/bin/bash', 21:29:11 INFO - 'SHLVL': '1', 21:29:11 INFO - 'TERM': 'linux', 21:29:11 INFO - 'TMOUT': '86400', 21:29:11 INFO - 'USER': 'cltbld', 21:29:11 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:11 INFO - '_': '/tools/buildbot/bin/python'} 21:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:11 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 21:29:11 INFO - Cleaning up... 21:29:11 INFO - Return code: 0 21:29:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:11 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 0x7f93a1915f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2abf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', '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 21:29:11 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 21:29:11 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 21:29:11 INFO - Using env: {'DISPLAY': ':0', 21:29:11 INFO - 'HOME': '/home/cltbld', 21:29:11 INFO - 'LANG': 'en_US.UTF-8', 21:29:11 INFO - 'LANGUAGE': 'en_US:en', 21:29:11 INFO - 'LOGNAME': 'cltbld', 21:29:11 INFO - 'MAIL': '/var/mail/cltbld', 21:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:11 INFO - 'MOZ_NO_REMOTE': '1', 21:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:11 INFO - 'NO_EM_RESTART': '1', 21:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:11 INFO - 'PWD': '/builds/slave/test', 21:29:11 INFO - 'SHELL': '/bin/bash', 21:29:11 INFO - 'SHLVL': '1', 21:29:11 INFO - 'TERM': 'linux', 21:29:11 INFO - 'TMOUT': '86400', 21:29:11 INFO - 'USER': 'cltbld', 21:29:11 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:11 INFO - '_': '/tools/buildbot/bin/python'} 21:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:12 INFO - Downloading/unpacking psutil>=3.1.1 21:29: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 21:29: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 21:29: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 21:29: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 21:29: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 21:29: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 21:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:29:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:29:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:29:14 INFO - Installing collected packages: psutil 21:29:14 INFO - Running setup.py install for psutil 21:29:14 INFO - building 'psutil._psutil_linux' extension 21:29:14 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 21:29:14 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 21:29:14 INFO - building 'psutil._psutil_posix' extension 21:29:14 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 21:29:14 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 21:29:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:29:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:29:14 INFO - Successfully installed psutil 21:29:14 INFO - Cleaning up... 21:29:14 INFO - Return code: 0 21:29:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:14 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 0x7f93a1915f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2abf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', '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 21:29:14 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 21:29:14 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 21:29:14 INFO - Using env: {'DISPLAY': ':0', 21:29:14 INFO - 'HOME': '/home/cltbld', 21:29:14 INFO - 'LANG': 'en_US.UTF-8', 21:29:14 INFO - 'LANGUAGE': 'en_US:en', 21:29:14 INFO - 'LOGNAME': 'cltbld', 21:29:14 INFO - 'MAIL': '/var/mail/cltbld', 21:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:14 INFO - 'MOZ_NO_REMOTE': '1', 21:29:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:14 INFO - 'NO_EM_RESTART': '1', 21:29:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:14 INFO - 'PWD': '/builds/slave/test', 21:29:14 INFO - 'SHELL': '/bin/bash', 21:29:14 INFO - 'SHLVL': '1', 21:29:14 INFO - 'TERM': 'linux', 21:29:14 INFO - 'TMOUT': '86400', 21:29:14 INFO - 'USER': 'cltbld', 21:29:14 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:14 INFO - '_': '/tools/buildbot/bin/python'} 21:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:29: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 21:29: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 21:29: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 21:29: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 21:29: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 21:29: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 21:29:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:29:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:29:16 INFO - Installing collected packages: mozsystemmonitor 21:29:16 INFO - Running setup.py install for mozsystemmonitor 21:29:17 INFO - Successfully installed mozsystemmonitor 21:29:17 INFO - Cleaning up... 21:29:17 INFO - Return code: 0 21:29:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f93a1915f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2abf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', '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 21:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:29:17 INFO - Using env: {'DISPLAY': ':0', 21:29:17 INFO - 'HOME': '/home/cltbld', 21:29:17 INFO - 'LANG': 'en_US.UTF-8', 21:29:17 INFO - 'LANGUAGE': 'en_US:en', 21:29:17 INFO - 'LOGNAME': 'cltbld', 21:29:17 INFO - 'MAIL': '/var/mail/cltbld', 21:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:17 INFO - 'MOZ_NO_REMOTE': '1', 21:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:17 INFO - 'NO_EM_RESTART': '1', 21:29:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:17 INFO - 'PWD': '/builds/slave/test', 21:29:17 INFO - 'SHELL': '/bin/bash', 21:29:17 INFO - 'SHLVL': '1', 21:29:17 INFO - 'TERM': 'linux', 21:29:17 INFO - 'TMOUT': '86400', 21:29:17 INFO - 'USER': 'cltbld', 21:29:17 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:17 INFO - '_': '/tools/buildbot/bin/python'} 21:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:17 INFO - Downloading/unpacking jsonschema==2.5.1 21:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:29:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:29:19 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 21:29:19 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 21:29:19 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 21:29:19 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 21:29:19 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 21:29:19 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 21:29:19 INFO - Downloading functools32-3.2.3-2.tar.gz 21:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:29:19 INFO - warning: no files found matching '*.txt' 21:29:19 INFO - no previously-included directories found matching 'build' 21:29:19 INFO - no previously-included directories found matching 'dist' 21:29:19 INFO - no previously-included directories found matching '.git*' 21:29:19 INFO - Installing collected packages: jsonschema, functools32 21:29:19 INFO - Running setup.py install for functools32 21:29:19 INFO - warning: no files found matching '*.txt' 21:29:19 INFO - no previously-included directories found matching 'build' 21:29:19 INFO - no previously-included directories found matching 'dist' 21:29:19 INFO - no previously-included directories found matching '.git*' 21:29:19 INFO - Successfully installed jsonschema functools32 21:29:19 INFO - Cleaning up... 21:29:19 INFO - Return code: 0 21:29:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:29: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')]} 21:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29: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')]} 21:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:19 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 0x7f93a1915f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2abf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', '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 21:29:19 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 21:29:19 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 21:29:19 INFO - Using env: {'DISPLAY': ':0', 21:29:19 INFO - 'HOME': '/home/cltbld', 21:29:19 INFO - 'LANG': 'en_US.UTF-8', 21:29:19 INFO - 'LANGUAGE': 'en_US:en', 21:29:19 INFO - 'LOGNAME': 'cltbld', 21:29:19 INFO - 'MAIL': '/var/mail/cltbld', 21:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:19 INFO - 'MOZ_NO_REMOTE': '1', 21:29:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:19 INFO - 'NO_EM_RESTART': '1', 21:29:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:19 INFO - 'PWD': '/builds/slave/test', 21:29:19 INFO - 'SHELL': '/bin/bash', 21:29:19 INFO - 'SHLVL': '1', 21:29:19 INFO - 'TERM': 'linux', 21:29:19 INFO - 'TMOUT': '86400', 21:29:19 INFO - 'USER': 'cltbld', 21:29:19 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:19 INFO - '_': '/tools/buildbot/bin/python'} 21:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:29:19 INFO - Cleaning up... 21:29:19 INFO - Return code: 0 21:29:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:29: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')]} 21:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29: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')]} 21:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:19 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 0x7f93a1915f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2abf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', '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 21:29:19 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 21:29:19 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 21:29:19 INFO - Using env: {'DISPLAY': ':0', 21:29:19 INFO - 'HOME': '/home/cltbld', 21:29:19 INFO - 'LANG': 'en_US.UTF-8', 21:29:19 INFO - 'LANGUAGE': 'en_US:en', 21:29:19 INFO - 'LOGNAME': 'cltbld', 21:29:19 INFO - 'MAIL': '/var/mail/cltbld', 21:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:19 INFO - 'MOZ_NO_REMOTE': '1', 21:29:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:19 INFO - 'NO_EM_RESTART': '1', 21:29:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:19 INFO - 'PWD': '/builds/slave/test', 21:29:19 INFO - 'SHELL': '/bin/bash', 21:29:19 INFO - 'SHLVL': '1', 21:29:19 INFO - 'TERM': 'linux', 21:29:19 INFO - 'TMOUT': '86400', 21:29:19 INFO - 'USER': 'cltbld', 21:29:19 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:19 INFO - '_': '/tools/buildbot/bin/python'} 21:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:20 INFO - Downloading/unpacking blobuploader==1.2.4 21:29: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 21:29: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 21:29: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 21:29: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 21:29: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 21:29: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 21:29:21 INFO - Downloading blobuploader-1.2.4.tar.gz 21:29:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:29:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:29:22 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 21:29:22 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 21:29:22 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 21:29:22 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 21:29:22 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 21:29:22 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 21:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:29:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:29:22 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 21:29:22 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 21:29:22 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 21:29:22 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 21:29:22 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 21:29:22 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 21:29:22 INFO - Downloading docopt-0.6.1.tar.gz 21:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:29:22 INFO - Installing collected packages: blobuploader, requests, docopt 21:29:22 INFO - Running setup.py install for blobuploader 21:29:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:29:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:29:22 INFO - Running setup.py install for requests 21:29:23 INFO - Running setup.py install for docopt 21:29:23 INFO - Successfully installed blobuploader requests docopt 21:29:23 INFO - Cleaning up... 21:29:23 INFO - Return code: 0 21:29:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:29: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')]} 21:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29: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')]} 21:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:23 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 0x7f93a1915f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2abf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', '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 21:29:23 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 21:29:23 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 21:29:23 INFO - Using env: {'DISPLAY': ':0', 21:29:23 INFO - 'HOME': '/home/cltbld', 21:29:23 INFO - 'LANG': 'en_US.UTF-8', 21:29:23 INFO - 'LANGUAGE': 'en_US:en', 21:29:23 INFO - 'LOGNAME': 'cltbld', 21:29:23 INFO - 'MAIL': '/var/mail/cltbld', 21:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:23 INFO - 'MOZ_NO_REMOTE': '1', 21:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:23 INFO - 'NO_EM_RESTART': '1', 21:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:23 INFO - 'PWD': '/builds/slave/test', 21:29:23 INFO - 'SHELL': '/bin/bash', 21:29:23 INFO - 'SHLVL': '1', 21:29:23 INFO - 'TERM': 'linux', 21:29:23 INFO - 'TMOUT': '86400', 21:29:23 INFO - 'USER': 'cltbld', 21:29:23 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:23 INFO - '_': '/tools/buildbot/bin/python'} 21:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:29:23 INFO - Running setup.py (path:/tmp/pip-6RIttR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:29:23 INFO - Running setup.py (path:/tmp/pip-1McU_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:29:23 INFO - Running setup.py (path:/tmp/pip-hOGg_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:29:23 INFO - Running setup.py (path:/tmp/pip-yS_aTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:29:23 INFO - Running setup.py (path:/tmp/pip-pTu4N5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:29:23 INFO - Running setup.py (path:/tmp/pip-rdMyuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:29:23 INFO - Running setup.py (path:/tmp/pip-Hd5PmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:29:24 INFO - Running setup.py (path:/tmp/pip-IvxXjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:29:24 INFO - Running setup.py (path:/tmp/pip-RxfgYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:29:24 INFO - Running setup.py (path:/tmp/pip-k5lgoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:29:24 INFO - Running setup.py (path:/tmp/pip-nfsllg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:29:24 INFO - Running setup.py (path:/tmp/pip-rxORQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:29:24 INFO - Running setup.py (path:/tmp/pip-OXu3Qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:29:24 INFO - Running setup.py (path:/tmp/pip-qKWjPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:24 INFO - Running setup.py (path:/tmp/pip-lA1AkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:29:24 INFO - Running setup.py (path:/tmp/pip-XVuVR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:29:24 INFO - Running setup.py (path:/tmp/pip-wXJfvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:29:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:29:24 INFO - Running setup.py install for manifestparser 21:29:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:29:24 INFO - Running setup.py install for mozcrash 21:29:24 INFO - Running setup.py install for mozdebug 21:29:25 INFO - Running setup.py install for mozdevice 21:29:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for mozfile 21:29:25 INFO - Running setup.py install for mozhttpd 21:29:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for mozinfo 21:29:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for mozInstall 21:29:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for mozleak 21:29:25 INFO - Running setup.py install for mozlog 21:29:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for moznetwork 21:29:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for mozprocess 21:29:26 INFO - Running setup.py install for mozprofile 21:29:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Running setup.py install for mozrunner 21:29:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Running setup.py install for mozscreenshot 21:29:26 INFO - Running setup.py install for moztest 21:29:26 INFO - Running setup.py install for mozversion 21:29:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:29:26 INFO - Cleaning up... 21:29:26 INFO - Return code: 0 21:29:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:26 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 0x7f93a1915f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2abf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', '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 21:29:26 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 21:29:26 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 21:29:26 INFO - Using env: {'DISPLAY': ':0', 21:29:26 INFO - 'HOME': '/home/cltbld', 21:29:26 INFO - 'LANG': 'en_US.UTF-8', 21:29:26 INFO - 'LANGUAGE': 'en_US:en', 21:29:26 INFO - 'LOGNAME': 'cltbld', 21:29:26 INFO - 'MAIL': '/var/mail/cltbld', 21:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:26 INFO - 'MOZ_NO_REMOTE': '1', 21:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:26 INFO - 'NO_EM_RESTART': '1', 21:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:26 INFO - 'PWD': '/builds/slave/test', 21:29:26 INFO - 'SHELL': '/bin/bash', 21:29:26 INFO - 'SHLVL': '1', 21:29:26 INFO - 'TERM': 'linux', 21:29:26 INFO - 'TMOUT': '86400', 21:29:26 INFO - 'USER': 'cltbld', 21:29:26 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:26 INFO - '_': '/tools/buildbot/bin/python'} 21:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:29:26 INFO - Running setup.py (path:/tmp/pip-QEV5ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:29:26 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)) 21:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:29:26 INFO - Running setup.py (path:/tmp/pip-6Uq0O3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:29:26 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)) 21:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:29:26 INFO - Running setup.py (path:/tmp/pip-5UjhpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:29:26 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)) 21:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:29:26 INFO - Running setup.py (path:/tmp/pip-tD3NPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:29:27 INFO - Running setup.py (path:/tmp/pip-zvpfDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:29:27 INFO - Running setup.py (path:/tmp/pip-JmKW98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:29:27 INFO - Running setup.py (path:/tmp/pip-xyxPN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:29:27 INFO - Running setup.py (path:/tmp/pip-2qiZ_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:29:27 INFO - Running setup.py (path:/tmp/pip-PcfMc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:29:27 INFO - Running setup.py (path:/tmp/pip-Ptgf3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:29:27 INFO - Running setup.py (path:/tmp/pip-TNhd5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:29:27 INFO - Running setup.py (path:/tmp/pip-5iGwrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:29:27 INFO - Running setup.py (path:/tmp/pip-rVQUo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:29:27 INFO - Running setup.py (path:/tmp/pip-vDqoly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:27 INFO - Running setup.py (path:/tmp/pip-4LRUHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:29:27 INFO - Running setup.py (path:/tmp/pip-vxr1Sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:29:27 INFO - Running setup.py (path:/tmp/pip-GoW2sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:29:27 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)) 21:29:28 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)) 21:29:28 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)) 21:29:28 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)) 21:29:28 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)) 21:29:28 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)) 21:29:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:29:28 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 21:29:28 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 21:29:28 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 21:29:28 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 21:29:28 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 21:29:28 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 21:29:29 INFO - Downloading blessings-1.6.tar.gz 21:29:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:29:29 INFO - Installing collected packages: blessings 21:29:29 INFO - Running setup.py install for blessings 21:29:30 INFO - Successfully installed blessings 21:29:30 INFO - Cleaning up... 21:29:30 INFO - Return code: 0 21:29:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:29:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:29:30 INFO - Reading from file tmpfile_stdout 21:29:30 INFO - Current package versions: 21:29:30 INFO - argparse == 1.2.1 21:29:30 INFO - blessings == 1.6 21:29:30 INFO - blobuploader == 1.2.4 21:29:30 INFO - docopt == 0.6.1 21:29:30 INFO - functools32 == 3.2.3-2 21:29:30 INFO - jsonschema == 2.5.1 21:29:30 INFO - manifestparser == 1.1 21:29:30 INFO - mozInstall == 1.12 21:29:30 INFO - mozcrash == 0.17 21:29:30 INFO - mozdebug == 0.1 21:29:30 INFO - mozdevice == 0.48 21:29:30 INFO - mozfile == 1.2 21:29:30 INFO - mozhttpd == 0.7 21:29:30 INFO - mozinfo == 0.9 21:29:30 INFO - mozleak == 0.1 21:29:30 INFO - mozlog == 3.4 21:29:30 INFO - moznetwork == 0.27 21:29:30 INFO - mozprocess == 0.23 21:29:30 INFO - mozprofile == 0.28 21:29:30 INFO - mozrunner == 6.12 21:29:30 INFO - mozscreenshot == 0.1 21:29:30 INFO - mozsystemmonitor == 0.3 21:29:30 INFO - moztest == 0.7 21:29:30 INFO - mozversion == 1.4 21:29:30 INFO - psutil == 3.1.1 21:29:30 INFO - requests == 1.2.3 21:29:30 INFO - wsgiref == 0.1.2 21:29:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:29:30 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')]} 21:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:30 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')]} 21:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:30 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 0x7f93a1915f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2abf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', '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 21:29:30 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 21:29:30 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 21:29:30 INFO - Using env: {'DISPLAY': ':0', 21:29:30 INFO - 'HOME': '/home/cltbld', 21:29:30 INFO - 'LANG': 'en_US.UTF-8', 21:29:30 INFO - 'LANGUAGE': 'en_US:en', 21:29:30 INFO - 'LOGNAME': 'cltbld', 21:29:30 INFO - 'MAIL': '/var/mail/cltbld', 21:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:30 INFO - 'MOZ_NO_REMOTE': '1', 21:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:30 INFO - 'NO_EM_RESTART': '1', 21:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:30 INFO - 'PWD': '/builds/slave/test', 21:29:30 INFO - 'SHELL': '/bin/bash', 21:29:30 INFO - 'SHLVL': '1', 21:29:30 INFO - 'TERM': 'linux', 21:29:30 INFO - 'TMOUT': '86400', 21:29:30 INFO - 'USER': 'cltbld', 21:29:30 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:30 INFO - '_': '/tools/buildbot/bin/python'} 21:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 INFO - Cleaning up... 21:29:30 INFO - Return code: 0 21:29:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:29:30 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')]} 21:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:30 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')]} 21:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:30 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 0x7f93a1915f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2abf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', '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 21:29:30 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 21:29:30 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 21:29:30 INFO - Using env: {'DISPLAY': ':0', 21:29:30 INFO - 'HOME': '/home/cltbld', 21:29:30 INFO - 'LANG': 'en_US.UTF-8', 21:29:30 INFO - 'LANGUAGE': 'en_US:en', 21:29:30 INFO - 'LOGNAME': 'cltbld', 21:29:30 INFO - 'MAIL': '/var/mail/cltbld', 21:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:30 INFO - 'MOZ_NO_REMOTE': '1', 21:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:30 INFO - 'NO_EM_RESTART': '1', 21:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:30 INFO - 'PWD': '/builds/slave/test', 21:29:30 INFO - 'SHELL': '/bin/bash', 21:29:30 INFO - 'SHLVL': '1', 21:29:30 INFO - 'TERM': 'linux', 21:29:30 INFO - 'TMOUT': '86400', 21:29:30 INFO - 'USER': 'cltbld', 21:29:30 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:30 INFO - '_': '/tools/buildbot/bin/python'} 21:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:29:30 INFO - Cleaning up... 21:29:30 INFO - Return code: 0 21:29:30 INFO - Running post-action listener: _resource_record_post_action 21:29:30 INFO - Running post-action listener: _start_resource_monitoring 21:29:30 INFO - Starting resource monitoring. 21:29:30 INFO - [mozharness: 2016-09-22 04:29:30.843613Z] Finished create-virtualenv step (success) 21:29:30 INFO - [mozharness: 2016-09-22 04:29:30.844163Z] Running install step. 21:29:30 INFO - Running pre-action listener: _resource_record_pre_action 21:29:30 INFO - Running main action method: install 21:29:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:29:31 INFO - Reading from file tmpfile_stdout 21:29:31 INFO - Detecting whether we're running mozinstall >=1.0... 21:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:29:31 INFO - Reading from file tmpfile_stdout 21:29:31 INFO - Output received: 21:29:31 INFO - Usage: mozinstall [options] installer 21:29:31 INFO - Options: 21:29:31 INFO - -h, --help show this help message and exit 21:29:31 INFO - -d DEST, --destination=DEST 21:29:31 INFO - Directory to install application into. [default: 21:29:31 INFO - "/builds/slave/test"] 21:29:31 INFO - --app=APP Application being installed. [default: firefox] 21:29:31 INFO - mkdir: /builds/slave/test/build/application 21:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:29:48 INFO - Reading from file tmpfile_stdout 21:29:48 INFO - Output received: 21:29:48 INFO - /builds/slave/test/build/application/firefox/firefox 21:29:48 INFO - Running post-action listener: _resource_record_post_action 21:29:48 INFO - [mozharness: 2016-09-22 04:29:48.086121Z] Finished install step (success) 21:29:48 INFO - [mozharness: 2016-09-22 04:29:48.086367Z] Running run-tests step. 21:29:48 INFO - Running pre-action listener: _resource_record_pre_action 21:29:48 INFO - Running main action method: run_tests 21:29:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:29:48 INFO - Python 2.7.3 21:29:48 INFO - Return code: 0 21:29:48 INFO - grabbing minidump binary from tooltool 21:29: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')]} 21:29:48 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 0x2b89930>, '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 0x2b8a830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8acc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:29:48 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 21:29:48 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 21:29:48 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 21:29:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:29:48 INFO - Return code: 0 21:29:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:29:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:29:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:29:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:29:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:29:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 21:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 21:29:48 INFO - Using env: {'DISPLAY': ':0', 21:29:48 INFO - 'HOME': '/home/cltbld', 21:29:48 INFO - 'LANG': 'en_US.UTF-8', 21:29:48 INFO - 'LANGUAGE': 'en_US:en', 21:29:48 INFO - 'LOGNAME': 'cltbld', 21:29:48 INFO - 'MAIL': '/var/mail/cltbld', 21:29:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:29:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:48 INFO - 'MOZ_NO_REMOTE': '1', 21:29:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:29:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:48 INFO - 'NO_EM_RESTART': '1', 21:29:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:48 INFO - 'PWD': '/builds/slave/test', 21:29:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:29:48 INFO - 'SHELL': '/bin/bash', 21:29:48 INFO - 'SHLVL': '1', 21:29:48 INFO - 'TERM': 'linux', 21:29:48 INFO - 'TMOUT': '86400', 21:29:48 INFO - 'USER': 'cltbld', 21:29:48 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920', 21:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:48 INFO - '_': '/tools/buildbot/bin/python'} 21:29:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 21:29:48 INFO - mozversion application_buildid: 20160921183021 21:29:48 INFO - mozversion application_changeset: 1da8daaf2082616b3c19b31603eea886555aa2a2 21:29:48 INFO - mozversion application_display_name: Nightly 21:29:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:29:48 INFO - mozversion application_name: Firefox 21:29:48 INFO - mozversion application_remotingname: firefox 21:29:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:29:48 INFO - mozversion application_vendor: Mozilla 21:29:48 INFO - mozversion application_version: 52.0a1 21:29:48 INFO - mozversion platform_buildid: 20160921183021 21:29:48 INFO - mozversion platform_changeset: 1da8daaf2082616b3c19b31603eea886555aa2a2 21:29:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:29:48 INFO - mozversion platform_version: 52.0a1 21:29:48 INFO - using testdate: 1474518588 21:29:48 INFO - actual date: 1474518588 21:29:48 INFO - starting webserver on 'localhost:38403' 21:29:48 INFO - SUITE-START | Running 2 tests 21:29:48 INFO - TEST-START | dromaeo_css 21:29:48 INFO - operating with platform_type : linux_ 21:29:48 INFO - Initialising browser for dromaeo_css test... 21:29:48 INFO - TEST-INFO | started process 10471 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA6uHmp/profile http://localhost:38403/getInfo.html) 21:29:52 INFO - PROCESS | 10471 | __metrics Screen width/height:1600/1200 21:29:52 INFO - PROCESS | 10471 | colorDepth:24 21:29:52 INFO - PROCESS | 10471 | Browser inner width/height: 1024/697 21:29:52 INFO - PROCESS | 10471 | __metrics 21:29:53 INFO - PROCESS | 10471 | 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" 21:29:53 INFO - PROCESS | 10471 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:29:53 INFO - PROCESS | 10471 | console.error: PushService: 21:29:53 INFO - PROCESS | 10471 | stateChangeProcessEnqueue: Error transitioning state 21:29:53 INFO - PROCESS | 10471 | UnknownError 21:29:54 INFO - TEST-INFO | 10471: exit 0 21:29:54 INFO - Browser initialized. 21:29:54 INFO - Running cycle 1/1 for dromaeo_css test... 21:29:54 INFO - TEST-INFO | started process 10553 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA6uHmp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:30:06 INFO - PROCESS | 10553 | RSS: Main: 173850624 21:30:06 INFO - PROCESS | 10553 | 21:30:57 INFO - PROCESS | 10553 | 1474518657908 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] 21:33:07 INFO - PROCESS | 10553 | Cycle 1(1): loaded http://localhost:38403/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38403/tests/dromaeo/cssquery-ext.html) 21:33:08 INFO - PROCESS | 10553 | RSS: Main: 308518912 21:33:08 INFO - PROCESS | 10553 | 21:36:10 INFO - PROCESS | 10553 | Cycle 1(1): loaded http://localhost:38403/tests/dromaeo/cssquery-ext.html (next: http://localhost:38403/tests/dromaeo/cssquery-jquery.html) 21:36:11 INFO - PROCESS | 10553 | RSS: Main: 268935168 21:36:11 INFO - PROCESS | 10553 | 21:39:13 INFO - PROCESS | 10553 | Cycle 1(1): loaded http://localhost:38403/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38403/tests/dromaeo/cssquery-mootools.html) 21:39:14 INFO - PROCESS | 10553 | RSS: Main: 213680128 21:39:14 INFO - PROCESS | 10553 | 21:42:16 INFO - PROCESS | 10553 | Cycle 1(1): loaded http://localhost:38403/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38403/tests/dromaeo/cssquery-prototype.html) 21:42:16 INFO - PROCESS | 10553 | RSS: Main: 255721472 21:42:16 INFO - PROCESS | 10553 | 21:45:19 INFO - PROCESS | 10553 | Cycle 1(1): loaded http://localhost:38403/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38403/tests/dromaeo/cssquery-yui.html) 21:45:20 INFO - PROCESS | 10553 | RSS: Main: 217055232 21:45:20 INFO - PROCESS | 10553 | 21:48:23 INFO - PROCESS | 10553 | Cycle 1(1): loaded http://localhost:38403/tests/dromaeo/cssquery-yui.html (next: http://localhost:38403/tests/dromaeo/cssquery-dojo.html) 21:48:23 INFO - PROCESS | 10553 | RSS: Main: 307773440 21:48:23 INFO - PROCESS | 10553 | 21:48:23 INFO - PROCESS | 10553 | __start_tp_report 21:48:23 INFO - PROCESS | 10553 | _x_x_mozilla_page_load 21:48:23 INFO - PROCESS | 10553 | _x_x_mozilla_page_load_details 21:48:23 INFO - PROCESS | 10553 | |i|pagename|runs| 21:48:23 INFO - PROCESS | 10553 | |0;dojo.html;2966.836823974681;2989.760819134468;2998.2654249708826;3016.6380034395875;3039.103464477979;5054.949450505494;5093.821716239932;5111.923321150186;5174.715390653517;5225.033368826765;5170.121079416499;5222.843314700565;5229.111051121309;5256.605754568413;5257.815976440826;4707.435107787053;4751.572358487735;4767.85696429106;4789.3294938708605;4896.216605343146;4916.901661966758;4954.653174277802;4961.8015279388965;4963.776630051639;4994.175960966436;4656.068786242765;4709.835155769566;4719.268513380397;4798.5201479852085;4856.587190088847;4745.667803253774;4747.7388743619085;4769.236922092448;4828.61371090312;4833.492483289274;4716.080364328957;4717.245240761462;4728.267118596623;4816.44610869751;4817.470078291385;5232.659877107976;5240.240165176028;5247.212918062283;5254.211868219798;5256.553192978601;5505.724713764296;5519.641223320512;5541.5843811714285;5544.306961629796;5561.805336813192;5583.804566840141;5686.346070201938;5698.7435565399655;5701;5709;5268.525832675039;5271.65734227274;5274.103402421598;5279.52484276417;5284.973575132138;5702.173184888168;5730.856728581819;5732.971335143381;5746.798862039808;5759.395263497357;5254.605904557174;5262.368515778131;5399.460053994648;5447.1829225616475;5468.453154684579;5546.4453554644215;5547.223388725582;5552.7778888844805;5554.72226388679;5606.271184745957;5529.142982837667;5533.889322213533;5534.252875861787;5534.75093620788;5540.058190107691;5371.194320851903;5464.808731694451;5598.720063996784;5622.353429355595;5664.603477756517;4411.823527058946;4413.293872980308;4451.5103338632725;4460.754658493749;4465.285554311295;3887.3974533947285;3939.527256729211;3939.940900886489;3942.8619998300496;3982.721209515364;5189.195674670432;5195.792168313225;5208.765605547759;5399.2980912481125;5447.019536483392;5575.358833734092;5577.163425486128;5620.325560932714;5620.803271885547;5641.464060914206;5235.895282094337;5268.051750684914;5278.604104692164;5280.3663560373;5289;4811.903761924742;4883.780229889663;5025.974870125626;5026.346574945233;5069.594432445396;5735.139729040678;5760.07838745807;5771.509421699118;5777.768889244383;5854.677992710356;5615.915761263666;5626.88746225065;5635.774569017193;5638.154276858504;5643.802466913638;5639.238702775073;5738.196652468574;5806.593538452268;5807.245058142415;5807.680577568274;5418.512333889968;5582.413846546055;5587.1339942309005;5610.49505544503;5712.34308054579;5737.311522617312;5738.282714660668;5752.827415177551;5758.1650660654805;5758.366579676316;5608.971955140199;5613.691246981455;5615.101583746581;5616.1014237721765;5617.073182924772;5308.761105750174;5361.437049109787;5366.6243362964215;5533.889322213614;5618.410066942912;5553.611247212818;5561.694106824163;5618.831435056954;5622.353429355513;5633.943660563506;5605.747741351731;5608.242887210337;5644.209810626598;5658.20785090096;5671.234383358194;5324.866878328073;5352.759125839424;5524.972375138099;5531.197976293334;5534.612577119563 21:48:23 INFO - PROCESS | 10553 | |1;ext.html;569.4419468920457;617.475146125793;619.2383368456799;632.5256057956533;633.2211380002597;32428.837855810718;33263.833680831594;42985.14029719404;43386;43716.3442548362;41566.7921660392;45978;46122.53877461224;46140.76929615356;46165;8920.643174273038;9065.637374505011;9083.592847895314;9083.727488175333;9089.68186113486;8839.51382673956;8912.910870891305;8937;8938.586581622958;8940.64237430502;9000.77498062547;9057.773555661095;9072.27421806254;9074.274058075338;9077.455352678828;8863.822723545525;8888;8907.109289071106;8911.064338244456;8911.197992180734;8901.688440904538;9055.68305109318;9061.682841100595;9063.682771103046;9067.455952642797;44072.118557628666;44195.77902110501;44420.55579444196;44483.55516444859;44492.77753611244;3261.401913062582;3345.7825241359233;3365.848536815849;3707.35121353762;3924.548676902164;31874.45840015136;32585;32680.836595817105;32920.34159316824;33022.66977330244;4270.722403043823;4339.848105316299;4387.495438024636;4395.538468460798;4543.613792827614;354847;355673;356732;356784;356883;59269.40730592725;59608.70195648994;59841.40158598445;59914.81448153824;59972.700136499036;61760.69119654373;61821.07268390978;61828.69085654633;61889.38110618926;61918.38081619216;16662.916685416494;16969.745453818203;16971.066591337596;16975.236114374882;16976.32094716222;12043.939780301042;12052.51789928393;12054.457549410297;12062.57780977679;12113.576024839265;13423.932880335731;16638.75041874373;16656.333746649998;16698.081605511812;16731.581710457336;4923.261510773413;5600.831975040755;5753.741081651336;5833.183354330397;5937.554683398763;914.6890057380523;964.4454438697636;1021.3565453764081;1138.6071805227184;1146.8165093584987;31544.36911261762;32132.35735285328;32223.51664725031;32320.676793232236;32781.83609081939;1034.182995433614;1037.4345981440133;1058.8094143054325;1087.6682611803476;1142.1433924556582;16072.276747546388;16728;16761;16895.83104168967;16913.77708901448;538909;539163;540979;541577;541782;58264.41735582675;58567;58608;58613.12080318799;58747.70626146842;1215.3315676377956;1247.1706315300405;1325.5691900132495;1342.3623778705037;1360.9183448993092;29920;32762.672373275964;32914.28685049003;35572.466413003305;35743.821280893426;54847.45152548583;56056.71971640116;56219.718901405235;57926.7103664479;60612.393876060676;386517;387890;388024;389336;389377;28377.716222838335;28540.857295713387;28861.711382885904;28884.27789305284;28996.710032899402;47734.76132619454;48235.27647085367;48240;48640.2703959448;48759;40970.1805963885;41073.38389924094;41446.378304324855;42897.785511072245;43120;41585;41834.58165418307;41856.58143418649;42264.28921580705;42795.14409711851;40232.59767402406;40308.798456007535;41585.37621935734;41635;41638.334466620996;33567;36286.455703165004;36526.452103219;36826.26347473089;37456.81271593625;24979.250622481355;25542.48915021652;27809;28269.717302827532;28280.15159545217 21:48:23 INFO - PROCESS | 10553 | |2;jquery.html;377.6714258595023;393.46488775265635;397.25514660012476;407.4499425775204;419.24545775927743;13381.39783709733;15241.314140863675;19000.904995475037;19583.608327833434;19923.103460344282;17759.91120044398;17848.821511784878;19273.710894336597;19853.205871765113;19869.205231790747;5645.463680950304;5681.943180568193;5929.347771745104;5929.525637948976;5939.1388248703915;5643.125315576092;5787.363390027093;5790;5790.247267855173;5793.073108302672;5577.944220557803;5758.42415758425;5763.337216220147;5766.106253530688;5793.102069179284;5743.597948143632;5745.166950792132;5774.2782152230975;5781.739821708013;5791.565632577532;5009.874753131165;5138.974305128488;5174.404943431516;5215.139501982168;5217;23437;23700.525989480288;23825.166119185746;23898.402539936465;24009.159679411136;2381.7141942966955;2541.263033720219;2556.297018319958;2961.392914452532;3054.7861649684532;14179.14925104497;14348.713025739531;15041.247937603186;15506.45727399547;15619.219039048116;1936.206155476262;2025.2810252360348;2041.1325186795552;2047.8156965873143;2057.2285392977633;87697.56151219267;97553;99563.00436995609;101617;103049.48475257575;18124.63750724978;18199.63600727978;18445.5388615283;18540.907295463705;18549.629007419775;32549.023529293685;32614.510782338282;32704;32770.344593108006;32834.17914552108;10009.399436033862;10132.707605129084;10156.12111759327;10342.689719308433;10558.208134389952;8553.101924297858;8605.440646358093;8640.351973602004;8717.12828717136;8867.645294188289;8507.149285071444;9071.773205669779;9140.405873618327;9257.768555786159;9334.779501072118;3492.2491664292297;3519.2433626769857;3580.2098950524737;3658.463445352958;3662.7619204751604;827.5820710541138;917.5641570254076;920.3235621817959;954.6086104697041;1026.3533973596586;17454.301827926996;18506.44480665582;19136.904315478332;19310.839199132042;19589.31437399684;908.246155690775;954.1698722111807;968.4576637083256;981.2346369831542;994.8607194992707;10092.899071009342;10783.83824242637;10800.729981750519;10823.296485728402;10831.83752243717;444315;450453;461179;462091;462665;68284;69976.65011675011;79796.20203798004;90888.54555727179;96865;896.1352295035192;932.2309094996655;968.9370190937567;977.6675930183771;981.254246772458;16855.072970986712;17209.397671081453;17660.470185894443;17687.55781105431;18034.188461519;42579;43382.56617433785;43978.56021439745;44196;44350.33474497821;58417.61867860013;59745.701271493366;60368.39631603628;61200;64511.06466806003;8396.580170991549;8692.391532592657;8933.240674542476;8987.146221108984;9110.088991101102;12551;12804.359782010675;13314.866851331362;13410.262435566137;13830.170189788645;11468.82796758066;11505.309681419141;11566.710832229262;11586.13104017219;11683.123765717435;11599.5360185595;11656.30062196271;11731.88268117308;11923.284602923852;11973.853619887303;11149.554017839195;11331.150163737588;11347.773044539228;11376.886231137583;11627.2211531251;7973.242541958504;8005.919940800751;8692.174243446863;8727.170918762708;8798.97623546575;11418.714532136763;11470.885291146982;11567.190296679151;11642.126840486828;12292.754144916873 21:48:23 INFO - PROCESS | 10553 | |3;mootools.html;1495.3719437836107;1514.931828067737;1632.9510114696557;1755.7893052833663;1788.4008857032886;7643.46495745298;7832.8041798955055;8396.580170991449;8991.820163596723;8993.145651163146;8435.114312997139;8684.13158684133;8694.130586941303;8886.20024197822;8889.155530224634;2333.2067097186996;2361.4450604108106;2362.6574146748712;2378.21518898763;2378.3221781792195;2001.2495314257153;2316.81465482761;2339.789418952294;2393.9880300598475;2404.7475015123423;2011.6982452632076;2268.3081660093667;2331.5453486570127;2337.263761915;2337.9532409351805;2137.9144834206613;2159.708439360682;2160.2331172433874;2171.055590817999;2209.812165965895;2234.70948776658;2245.820334373246;2245.977540224593;2251.74104977928;2256.311824893407;8854.557272136368;8893.911060889437;8953.865692014626;8956.552172391419;8961.910380896174;4993.151164302078;5058.772355244023;5154;5174.793008279665;5195.792168313301;3339.682730140633;3448.3103379323984;3494.7728397654073;3523.277728065766;3532.9116772080647;1141.566204842163;1209.8064309710492;1228.6498347970785;1235.7528494301087;2115.375964090597;11815.11386646005;12252.326122063372;12278.815817762741;12285.754284914252;12287.1399002069;4482.058767658763;4514.255147900625;4540.318952157137;4566.49768525462;4646.83736069236;9768.658096966714;9839.950800246095;9890.851637225447;9898.901010989943;9903.405795652285;5129.820456284012;5144.819931302386;5157.510036546522;5157.716325602053;5193.272941788153;4651.418572678415;4711.1519926413575;4805.1590971579835;4806.206975849016;4806.8077276909235;3691.7600355976774;3715.981420092882;3768.962310376916;3799.848006079781;3844.1927195289304;944.40502483435;1116.4919961417536;1136.5851464215502;1168.8597368315857;1187.5368606243571;808.9069756978023;946.7396465971775;954.3844220477753;962.7929995050957;972.3582435592616;5885.61743486672;5966.582339236212;5998.880022399528;6181.81454556364;6451.3548645135115;961.2358175250693;964.5466630683568;988.4020167798523;1025.415513157508;1048.3133547526381;3825.6939444844347;3856.2094770571966;3899.863504777319;3983.741056831355;4039.959600403958;10455.111315538265;10699;10778.29941053829;10798.78402431947;10803.675889723321;10675.786484270271;10680.946595266974;10692.839607405895;10705.357678539312;10707.785844283071;7865.646045927947;8202.712905048294;8260.917390826015;8318.792030199293;8330.541820199947;6112.8777424452155;6132.47873930703;6217.409346112112;6229.688515574113;6233.314335423009;10062.89937100649;10161.288709790244;10182.083612474913;10205.540750666089;10257.179425645782;9705.078017588317;10054.597816087275;10071.848922266025;10178.592856285963;10384.532696028848;7779.57212353326;7924.326432253092;7946.562939038408;7958.522488650699;7966.402519810921;8387.916120838958;8405.243528082257;8480.279176270085;8481.109483504144;8497.787555311166;7645.235476452533;7696.422768292512;7764.184760600281;7850.6074696266105;7856.332211762065;7561.168271490242;7743;7756.728514501965;7768.300852923264;7782.844343113219;6884.345987131214;6904.067950826775;6926.168859736864;6934.341237582422;7089.6100714459135;5015.272785446163;5140.43455219933;5176.663516871391;5185.22221666753;5382.623216374816;5069.112905241642;5159.819406320761;5247.4227834937365;5280.287161233338;5318.282031925795 21:48:23 INFO - PROCESS | 10553 | |4;prototype.html;321.08330715806375;331.36543519160807;332.52615023591375;344.1087583159618;349.52639173919323;5088.2622019807195;5233.371995360562;5287.233351164079;5336.252924590555;5355.464453554648;5073.720945348014;5158.380994280682;5185.351831021122;5194.584433245341;5236.9526094781;4111.7944102794745;4122.958770412302;4128.8967775805695;4138.392812528103;4170.332746760519;3928.7839168845703;4103.589641035887;4143.647789937844;4178.791060446981;4193.706440549163;4071.755694658315;4090.1819636072755;4093.9385909211387;4094.1811637672436;4106.301928672133;4106.7125301228925;4118.217538667644;4132.979335103335;4138.896527586804;4160.417541544186;3507.140750516114;3629.4737263096968;3637.563492380905;3644.9635503645154;3692.649198326155;5073.289739436482;5099.184130539133;5116.539511443951;5120.206368012964;5122.129238029544;4650.930236046428;4691.52217051631;4700.647451441156;4700.858974230779;4700.882477938045;5288.947110528922;5406.729663516809;5451.427600102012;5476.17857321405;5492.395836457987;3940.822662980173;3957.4657421248053;3970.285348637244;3972.145988612462;3978.2242462719782;5624.91562626561;5698.715064246771;5718.313802343704;5738.454846789548;5750.194972703783;3962.782046987443;4031.697622678311;4065.8170382332864;4073.190450917885;4101.405296232029;4045.959540404617;4053.7433395647445;4102.938455923164;4105.548389677133;4124.360724087771;4602.470715867651;4631.3516107744945;4664.95335046652;4672.789724462407;4708.081924024782;5232.581393488512;5523.917141242885;5569.805056822992;5584.497395234448;5593.720313984284;1349.5694563762443;1374.3334482775845;1375.2229990055587;1377.2493990775051;1378.8417729107598;937.3719607862748;951.3103000324847;958.8082383523323;979.2313034268066;984.5618699678718;5202.81790137351;5223.582113430916;5342.9465705342445;5359.08895487768;5378.435264297271;5467.835964921059;5550.889822035609;5592.888142237132;5707.34365547959;5721.685307308138;5265.420803711589;5294.49702278283;5311.628186027018;5319.340329835082;5349.946500535023;2954.8818047278296;2979.4488019716423;2983.985080074629;2988.9701102989125;2989.312458134641;5294.841154765363;5772.624779389326;5790.565707571865;5826.533877289807;5829.970850145807;5732.570057245724;5802.709864506758;5810.360860305447;5814.854628634318;5841.561882858802;5615.596100974756;5863.824085277449;5872.941270587325;5900.173975643327;5908.586398952118;5691.833174199362;5714.085746280575;5729.140628905531;5730.684812335361;5734;5652.265205523256;5672.262605861211;5696.886062278649;5754.395788442321;5758.740856661377;5826.621269617462;5831.096180092092;5886.676232807236;5889.852753681021;5890.204822348928;4115.32097203958;4116.115035267432;4134.896627584334;4147.3986271991;4153.54311025793;5417.295751552273;5671.773129074956;5765;5779.508741756998;5791.7683292667825;5532.944670553243;5548.472895074961;5554.916676249779;5555.2778138843305;5581.5255703265675;5281.920771188512;5571.637843540157;5582.609217354746;5604.337939478715;5641.802536911188;5210.072273258918;5333.866653333543;5394.811181608625;5465.37148227943;5466.376139453006;5488.807891723771;5517.834464966058;5527.766256739852;5542.445755424425;5553.305836770403;5241.240020196974;5405.081136206775;5437.755301011385;5504.174373843955;5506.394296627288 21:48:23 INFO - PROCESS | 10553 | |5;yui.html;750.2347605442449;758.275846566529;775.9030121234846;787.5668382389684;794.1105961323319;15442.382304707813;17102.486925392226;17126.400575979842;17132.743008854875;17182.3127074917;12946.805797913015;12979.870201297985;12987.545435909746;12995.610131696041;13022.283774392405;664.0537234440926;678.443676185528;773.9613019349039;781.144646611959;802.7872613757359;800.0639252075057;805.7582725182451;814.6578437056421;814.698561532231;814.9551774652392;864.6022829498406;865.0700496965787;866.3415803988951;866.8613021916494;867.0765634599131;688.6109348218266;710.6269208665441;819.5041999590221;840.3739214285335;859.3426029087752;583.1806312131462;583.4253260538367;583.4894467341077;583.6089819820688;588.6056342250703;14567.781483277759;14649.414023439049;14657.633559161;14700.926495367456;14710;13922.791158132635;14357.138571685733;14548.781768273378;14601.415943362254;14748.115113093141;13099.279539625317;13158.934205329007;13258.801117983237;13485.123466974714;13644.863551364459;4370.628496577795;4457.108578284324;4491.820327186909;4605.125026245003;4629.652776041777;87724.99999999936;88874;88947;89374;89643.55178224067;10776;10890;11067.169962252703;11186.38474883873;11296.876640026496;20773.584528309348;20823.895880520802;20898;20925.058372373278;20945.685814712793;8294.294984926228;8312;8476.449030812977;8544.786380340542;8640.092790257058;4455.977720111444;4480.395146655225;4497.572730590588;4557.270836666184;4641.86074417768;3284.21178917063;3392.3384939936477;3402.1154499830222;3403.982980085084;3409.8977030688625;541.8320320700595;542.5144495676426;543.1120118606108;543.4212563619732;543.6683622989973;649.6037417175519;653.8365408647838;679.6839469646638;725.9927400725925;740.1044735869658;8898.154675305836;9448.433094014381;9559.713208603753;9982;9989.900100999042;628.8868003759367;680.5338343234899;701.6602314683697;715.6636380900968;736.9410447164214;4234.767087810138;4642.744649044267;4720.95279047212;4915.631327650441;4942.332785074041;122067;123908.38045809713;124765.37617312037;126950;130987;108781;110356;111430;113727;113878;78869;79744.6012769944;90539.54730226306;93970;95461.52269238609;9489.57296921654;9716.3198576099;9964;9970.800583988424;9998.700038998843;52064.479355205964;52174.91300348096;53147.73426132845;53268;53577.46422535881;68449;69174;74360;76730;78501.60749196218;13215.405306761411;13280.734385312433;13369.53206637763;13466.461341546228;13570.660733481742;13908.652283692989;14468.710625787637;14826.851731482548;15755.133467659389;16009.199540023184;13540.255285959367;13640.113392629448;13785.586432407044;13955.651108722363;14189.503367382093;14292.28538573088;14297.857021429652;14408.351624177147;14607.415703371746;14631.780523292373;13947;13970.091944023607;13994;14236.786448203078;14306.785398219243;11501.21607305387;11683.18217724751;11690;11958.820617690915;12098.697532561757;11705.531778728755;11888.605882118987;11979.34113623759;12046.819297710366;12195.451204695988 21:48:23 INFO - PROCESS | 10553 | __end_tp_report 21:48:23 INFO - PROCESS | 10553 | __start_cc_report 21:48:23 INFO - PROCESS | 10553 | _x_x_mozilla_cycle_collect,1001 21:48:23 INFO - PROCESS | 10553 | __end_cc_report 21:48:23 INFO - PROCESS | 10553 | __startTimestamp1474519703458__endTimestamp 21:48:23 INFO - PROCESS | 10553 | 21:48:23 INFO - PROCESS | 10553 | ------- Summary: start ------- 21:48:23 INFO - PROCESS | 10553 | Number of tests: 6 21:48:23 INFO - PROCESS | 10553 | 21:48:23 INFO - PROCESS | 10553 | [#0] dojo.html Cycles:165 Average:5230.91 Median:5399.46 stddev:580.79 (10.8%) stddev-sans-first:554.75 21:48:23 INFO - PROCESS | 10553 | Values: 2966.8 2989.8 2998.3 3016.6 3039.1 5054.9 5093.8 5111.9 5174.7 5225.0 5170.1 5222.8 5229.1 5256.6 5257.8 4707.4 4751.6 4767.9 4789.3 4896.2 4916.9 4954.7 4961.8 4963.8 4994.2 4656.1 4709.8 4719.3 4798.5 4856.6 4745.7 4747.7 4769.2 4828.6 4833.5 4716.1 4717.2 4728.3 4816.4 4817.5 5232.7 5240.2 5247.2 5254.2 5256.6 5505.7 5519.6 5541.6 5544.3 5561.8 5583.8 5686.3 5698.7 5701.0 5709.0 5268.5 5271.7 5274.1 5279.5 5285.0 5702.2 5730.9 5733.0 5746.8 5759.4 5254.6 5262.4 5399.5 5447.2 5468.5 5546.4 5547.2 5552.8 5554.7 5606.3 5529.1 5533.9 5534.3 5534.8 5540.1 5371.2 5464.8 5598.7 5622.4 5664.6 4411.8 4413.3 4451.5 4460.8 4465.3 3887.4 3939.5 3939.9 3942.9 3982.7 5189.2 5195.8 5208.8 5399.3 5447.0 5575.4 5577.2 5620.3 5620.8 5641.5 5235.9 5268.1 5278.6 5280.4 5289.0 4811.9 4883.8 5026.0 5026.3 5069.6 5735.1 5760.1 5771.5 5777.8 5854.7 5615.9 5626.9 5635.8 5638.2 5643.8 5639.2 5738.2 5806.6 5807.2 5807.7 5418.5 5582.4 5587.1 5610.5 5712.3 5737.3 5738.3 5752.8 5758.2 5758.4 5609.0 5613.7 5615.1 5616.1 5617.1 5308.8 5361.4 5366.6 5533.9 5618.4 5553.6 5561.7 5618.8 5622.4 5633.9 5605.7 5608.2 5644.2 5658.2 5671.2 5324.9 5352.8 5525.0 5531.2 5534.6 21:48:23 INFO - PROCESS | 10553 | 21:48:23 INFO - PROCESS | 10553 | [#1] ext.html Cycles:180 Average:59400.36 Median:41845.58 stddev:115480.81 (276.0%) stddev-sans-first:115720.29 21:48:23 INFO - PROCESS | 10553 | Values: 569.4 617.5 619.2 632.5 633.2 32428.8 33263.8 42985.1 43386.0 43716.3 41566.8 45978.0 46122.5 46140.8 46165.0 8920.6 9065.6 9083.6 9083.7 9089.7 8839.5 8912.9 8937.0 8938.6 8940.6 9000.8 9057.8 9072.3 9074.3 9077.5 8863.8 8888.0 8907.1 8911.1 8911.2 8901.7 9055.7 9061.7 9063.7 9067.5 44072.1 44195.8 44420.6 44483.6 44492.8 3261.4 3345.8 3365.8 3707.4 3924.5 31874.5 32585.0 32680.8 32920.3 33022.7 4270.7 4339.8 4387.5 4395.5 4543.6 354847.0 355673.0 356732.0 356784.0 356883.0 59269.4 59608.7 59841.4 59914.8 59972.7 61760.7 61821.1 61828.7 61889.4 61918.4 16662.9 16969.7 16971.1 16975.2 16976.3 12043.9 12052.5 12054.5 12062.6 12113.6 13423.9 16638.8 16656.3 16698.1 16731.6 4923.3 5600.8 5753.7 5833.2 5937.6 914.7 964.4 1021.4 1138.6 1146.8 31544.4 32132.4 32223.5 32320.7 32781.8 1034.2 1037.4 1058.8 1087.7 1142.1 16072.3 16728.0 16761.0 16895.8 16913.8 538909.0 539163.0 540979.0 541577.0 541782.0 58264.4 58567.0 58608.0 58613.1 58747.7 1215.3 1247.2 1325.6 1342.4 1360.9 29920.0 32762.7 32914.3 35572.5 35743.8 54847.5 56056.7 56219.7 57926.7 60612.4 386517.0 387890.0 388024.0 389336.0 389377.0 28377.7 28540.9 28861.7 28884.3 28996.7 47734.8 48235.3 48240.0 48640.3 48759.0 40970.2 41073.4 41446.4 42897.8 43120.0 41585.0 41834.6 41856.6 42264.3 42795.1 40232.6 40308.8 41585.4 41635.0 41638.3 33567.0 36286.5 36526.5 36826.3 37456.8 24979.3 25542.5 27809.0 28269.7 28280.2 21:48:23 INFO - PROCESS | 10553 | 21:48:23 INFO - PROCESS | 10553 | [#2] jquery.html Cycles:180 Average:29372.64 Median:3619.34 stddev:75478.62 (2085.4%) stddev-sans-first:75658.96 21:48:23 INFO - PROCESS | 10553 | Values: 377.7 393.5 397.3 407.4 419.2 13381.4 15241.3 19000.9 19583.6 19923.1 17759.9 17848.8 19273.7 19853.2 19869.2 5645.5 5681.9 5929.3 5929.5 5939.1 5643.1 5787.4 5790.0 5790.2 5793.1 5577.9 5758.4 5763.3 5766.1 5793.1 5743.6 5745.2 5774.3 5781.7 5791.6 5009.9 5139.0 5174.4 5215.1 5217.0 23437.0 23700.5 23825.2 23898.4 24009.2 2381.7 2541.3 2556.3 2961.4 3054.8 14179.1 14348.7 15041.2 15506.5 15619.2 1936.2 2025.3 2041.1 2047.8 2057.2 87697.6 97553.0 99563.0 101617.0 103049.5 18124.6 18199.6 18445.5 18540.9 18549.6 32549.0 32614.5 32704.0 32770.3 32834.2 10009.4 10132.7 10156.1 10342.7 10558.2 8553.1 8605.4 8640.4 8717.1 8867.6 8507.1 9071.8 9140.4 9257.8 9334.8 3492.2 3519.2 3580.2 3658.5 3662.8 827.6 917.6 920.3 954.6 1026.4 17454.3 18506.4 19136.9 19310.8 19589.3 908.2 954.2 968.5 981.2 994.9 10092.9 10783.8 10800.7 10823.3 10831.8 444315.0 450453.0 461179.0 462091.0 462665.0 68284.0 69976.7 79796.2 90888.5 96865.0 896.1 932.2 968.9 977.7 981.3 16855.1 17209.4 17660.5 17687.6 18034.2 42579.0 43382.6 43978.6 44196.0 44350.3 58417.6 59745.7 60368.4 61200.0 64511.1 8396.6 8692.4 8933.2 8987.1 9110.1 12551.0 12804.4 13314.9 13410.3 13830.2 11468.8 11505.3 11566.7 11586.1 11683.1 11599.5 11656.3 11731.9 11923.3 11973.9 11149.6 11331.2 11347.8 11376.9 11627.2 7973.2 8005.9 8692.2 8727.2 8799.0 11418.7 11470.9 11567.2 11642.1 12292.8 21:48:23 INFO - PROCESS | 10553 | 21:48:23 INFO - PROCESS | 10553 | [#3] mootools.html Cycles:180 Average:5763.87 Median:5004.21 stddev:3258.56 (65.1%) stddev-sans-first:3251.91 21:48:23 INFO - PROCESS | 10553 | Values: 1495.4 1514.9 1633.0 1755.8 1788.4 7643.5 7832.8 8396.6 8991.8 8993.1 8435.1 8684.1 8694.1 8886.2 8889.2 2333.2 2361.4 2362.7 2378.2 2378.3 2001.2 2316.8 2339.8 2394.0 2404.7 2011.7 2268.3 2331.5 2337.3 2338.0 2137.9 2159.7 2160.2 2171.1 2209.8 2234.7 2245.8 2246.0 2251.7 2256.3 8854.6 8893.9 8953.9 8956.6 8961.9 4993.2 5058.8 5154.0 5174.8 5195.8 3339.7 3448.3 3494.8 3523.3 3532.9 1141.6 1209.8 1228.6 1235.8 2115.4 11815.1 12252.3 12278.8 12285.8 12287.1 4482.1 4514.3 4540.3 4566.5 4646.8 9768.7 9840.0 9890.9 9898.9 9903.4 5129.8 5144.8 5157.5 5157.7 5193.3 4651.4 4711.2 4805.2 4806.2 4806.8 3691.8 3716.0 3769.0 3799.8 3844.2 944.4 1116.5 1136.6 1168.9 1187.5 808.9 946.7 954.4 962.8 972.4 5885.6 5966.6 5998.9 6181.8 6451.4 961.2 964.5 988.4 1025.4 1048.3 3825.7 3856.2 3899.9 3983.7 4040.0 10455.1 10699.0 10778.3 10798.8 10803.7 10675.8 10680.9 10692.8 10705.4 10707.8 7865.6 8202.7 8260.9 8318.8 8330.5 6112.9 6132.5 6217.4 6229.7 6233.3 10062.9 10161.3 10182.1 10205.5 10257.2 9705.1 10054.6 10071.8 10178.6 10384.5 7779.6 7924.3 7946.6 7958.5 7966.4 8387.9 8405.2 8480.3 8481.1 8497.8 7645.2 7696.4 7764.2 7850.6 7856.3 7561.2 7743.0 7756.7 7768.3 7782.8 6884.3 6904.1 6926.2 6934.3 7089.6 5015.3 5140.4 5176.7 5185.2 5382.6 5069.1 5159.8 5247.4 5280.3 5318.3 21:48:23 INFO - PROCESS | 10553 | 21:48:23 INFO - PROCESS | 10553 | [#4] prototype.html Cycles:180 Average:4650.55 Median:5291.72 stddev:1365.45 (25.8%) stddev-sans-first:1330.05 21:48:23 INFO - PROCESS | 10553 | Values: 321.1 331.4 332.5 344.1 349.5 5088.3 5233.4 5287.2 5336.3 5355.5 5073.7 5158.4 5185.4 5194.6 5237.0 4111.8 4123.0 4128.9 4138.4 4170.3 3928.8 4103.6 4143.6 4178.8 4193.7 4071.8 4090.2 4093.9 4094.2 4106.3 4106.7 4118.2 4133.0 4138.9 4160.4 3507.1 3629.5 3637.6 3645.0 3692.6 5073.3 5099.2 5116.5 5120.2 5122.1 4650.9 4691.5 4700.6 4700.9 4700.9 5288.9 5406.7 5451.4 5476.2 5492.4 3940.8 3957.5 3970.3 3972.1 3978.2 5624.9 5698.7 5718.3 5738.5 5750.2 3962.8 4031.7 4065.8 4073.2 4101.4 4046.0 4053.7 4102.9 4105.5 4124.4 4602.5 4631.4 4665.0 4672.8 4708.1 5232.6 5523.9 5569.8 5584.5 5593.7 1349.6 1374.3 1375.2 1377.2 1378.8 937.4 951.3 958.8 979.2 984.6 5202.8 5223.6 5342.9 5359.1 5378.4 5467.8 5550.9 5592.9 5707.3 5721.7 5265.4 5294.5 5311.6 5319.3 5349.9 2954.9 2979.4 2984.0 2989.0 2989.3 5294.8 5772.6 5790.6 5826.5 5830.0 5732.6 5802.7 5810.4 5814.9 5841.6 5615.6 5863.8 5872.9 5900.2 5908.6 5691.8 5714.1 5729.1 5730.7 5734.0 5652.3 5672.3 5696.9 5754.4 5758.7 5826.6 5831.1 5886.7 5889.9 5890.2 4115.3 4116.1 4134.9 4147.4 4153.5 5417.3 5671.8 5765.0 5779.5 5791.8 5532.9 5548.5 5554.9 5555.3 5581.5 5281.9 5571.6 5582.6 5604.3 5641.8 5210.1 5333.9 5394.8 5465.4 5466.4 5488.8 5517.8 5527.8 5542.4 5553.3 5241.2 5405.1 5437.8 5504.2 5506.4 21:48:23 INFO - PROCESS | 10553 | 21:48:23 INFO - PROCESS | 10553 | [#5] yui.html Cycles:180 Average:21953.98 Median:4494.70 stddev:32588.84 (725.1%) stddev-sans-first:32641.37 21:48:23 INFO - PROCESS | 10553 | Values: 750.2 758.3 775.9 787.6 794.1 15442.4 17102.5 17126.4 17132.7 17182.3 12946.8 12979.9 12987.5 12995.6 13022.3 664.1 678.4 774.0 781.1 802.8 800.1 805.8 814.7 814.7 815.0 864.6 865.1 866.3 866.9 867.1 688.6 710.6 819.5 840.4 859.3 583.2 583.4 583.5 583.6 588.6 14567.8 14649.4 14657.6 14700.9 14710.0 13922.8 14357.1 14548.8 14601.4 14748.1 13099.3 13158.9 13258.8 13485.1 13644.9 4370.6 4457.1 4491.8 4605.1 4629.7 87725.0 88874.0 88947.0 89374.0 89643.6 10776.0 10890.0 11067.2 11186.4 11296.9 20773.6 20823.9 20898.0 20925.1 20945.7 8294.3 8312.0 8476.4 8544.8 8640.1 4456.0 4480.4 4497.6 4557.3 4641.9 3284.2 3392.3 3402.1 3404.0 3409.9 541.8 542.5 543.1 543.4 543.7 649.6 653.8 679.7 726.0 740.1 8898.2 9448.4 9559.7 9982.0 9989.9 628.9 680.5 701.7 715.7 736.9 4234.8 4642.7 4721.0 4915.6 4942.3 122067.0 123908.4 124765.4 126950.0 130987.0 108781.0 110356.0 111430.0 113727.0 113878.0 78869.0 79744.6 90539.5 93970.0 95461.5 9489.6 9716.3 9964.0 9970.8 9998.7 52064.5 52174.9 53147.7 53268.0 53577.5 68449.0 69174.0 74360.0 76730.0 78501.6 13215.4 13280.7 13369.5 13466.5 13570.7 13908.7 14468.7 14826.9 15755.1 16009.2 13540.3 13640.1 13785.6 13955.7 14189.5 14292.3 14297.9 14408.4 14607.4 14631.8 13947.0 13970.1 13994.0 14236.8 14306.8 11501.2 11683.2 11690.0 11958.8 12098.7 11705.5 11888.6 11979.3 12046.8 12195.5 21:48:23 INFO - PROCESS | 10553 | -------- Summary: end -------- 21:48:23 INFO - PROCESS | 10553 | 21:48:24 INFO - TEST-INFO | 10553: exit 0 21:48:24 INFO - TEST-OK | dromaeo_css | took 1116051ms 21:48:24 INFO - TEST-START | kraken 21:48:24 INFO - operating with platform_type : linux_ 21:48:24 INFO - Initialising browser for kraken test... 21:48:24 INFO - TEST-INFO | started process 10853 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaEOo9s/profile http://localhost:38403/getInfo.html) 21:48:28 INFO - PROCESS | 10853 | __metrics Screen width/height:1600/1200 21:48:28 INFO - PROCESS | 10853 | colorDepth:24 21:48:28 INFO - PROCESS | 10853 | Browser inner width/height: 1024/697 21:48:28 INFO - PROCESS | 10853 | __metrics 21:48:29 INFO - PROCESS | 10853 | 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" 21:48:29 INFO - PROCESS | 10853 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:48:29 INFO - PROCESS | 10853 | console.error: PushService: 21:48:29 INFO - PROCESS | 10853 | stateChangeProcessEnqueue: Error transitioning state 21:48:29 INFO - PROCESS | 10853 | UnknownError 21:48:30 INFO - TEST-INFO | 10853: exit 0 21:48:30 INFO - Browser initialized. 21:48:30 INFO - Running cycle 1/1 for kraken test... 21:48:30 INFO - TEST-INFO | started process 10937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaEOo9s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:48:42 INFO - PROCESS | 10937 | RSS: Main: 173174784 21:48:42 INFO - PROCESS | 10937 | 21:48:45 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38403/tests/kraken/driver.html?testName=audio-beat-detection) 21:48:46 INFO - PROCESS | 10937 | RSS: Main: 240422912 21:48:46 INFO - PROCESS | 10937 | 21:48:48 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38403/tests/kraken/driver.html?testName=audio-dft) 21:48:49 INFO - PROCESS | 10937 | RSS: Main: 217513984 21:48:49 INFO - PROCESS | 10937 | 21:48:52 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38403/tests/kraken/driver.html?testName=audio-fft) 21:48:53 INFO - PROCESS | 10937 | RSS: Main: 298762240 21:48:53 INFO - PROCESS | 10937 | 21:48:55 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38403/tests/kraken/driver.html?testName=audio-oscillator) 21:48:55 INFO - PROCESS | 10937 | RSS: Main: 208257024 21:48:55 INFO - PROCESS | 10937 | 21:48:57 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38403/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:48:58 INFO - PROCESS | 10937 | RSS: Main: 212500480 21:48:58 INFO - PROCESS | 10937 | 21:49:01 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38403/tests/kraken/driver.html?testName=imaging-darkroom) 21:49:02 INFO - PROCESS | 10937 | RSS: Main: 249192448 21:49:02 INFO - PROCESS | 10937 | 21:49:05 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38403/tests/kraken/driver.html?testName=imaging-desaturate) 21:49:06 INFO - PROCESS | 10937 | RSS: Main: 248532992 21:49:06 INFO - PROCESS | 10937 | 21:49:08 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38403/tests/kraken/driver.html?testName=json-parse-financial) 21:49:09 INFO - PROCESS | 10937 | RSS: Main: 248455168 21:49:09 INFO - PROCESS | 10937 | 21:49:11 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38403/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:49:11 INFO - PROCESS | 10937 | RSS: Main: 210419712 21:49:11 INFO - PROCESS | 10937 | 21:49:13 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38403/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:49:13 INFO - PROCESS | 10937 | RSS: Main: 217686016 21:49:13 INFO - PROCESS | 10937 | 21:49:15 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38403/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:49:16 INFO - PROCESS | 10937 | RSS: Main: 222662656 21:49:16 INFO - PROCESS | 10937 | 21:49:19 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38403/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:49:19 INFO - PROCESS | 10937 | RSS: Main: 222855168 21:49:19 INFO - PROCESS | 10937 | 21:49:22 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38403/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:49:23 INFO - PROCESS | 10937 | RSS: Main: 221904896 21:49:23 INFO - PROCESS | 10937 | 21:49:24 INFO - PROCESS | 10937 | Cycle 1(1): loaded http://localhost:38403/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38403/tests/kraken/driver.html?testName=ai-astar) 21:49:25 INFO - PROCESS | 10937 | RSS: Main: 273059840 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | __start_tp_report 21:49:25 INFO - PROCESS | 10937 | _x_x_mozilla_page_load 21:49:25 INFO - PROCESS | 10937 | _x_x_mozilla_page_load_details 21:49:25 INFO - PROCESS | 10937 | |i|pagename|runs| 21:49:25 INFO - PROCESS | 10937 | |0;ai-astar;113;113;111;114;114;112;112;113;112;113 21:49:25 INFO - PROCESS | 10937 | |1;audio-beat-detection;151;118;119;160;122;119;119;121;152;117 21:49:25 INFO - PROCESS | 10937 | |2;audio-dft;161;172;160;161;161;161;161;164;161;162 21:49:25 INFO - PROCESS | 10937 | |3;audio-fft;73;74;74;114;76;76;75;76;98;75 21:49:25 INFO - PROCESS | 10937 | |4;audio-oscillator;82;83;115;82;82;82;108;81;81;119 21:49:25 INFO - PROCESS | 10937 | |5;imaging-gaussian-blur;117;118;118;117;116;117;117;117;117;117 21:49:25 INFO - PROCESS | 10937 | |6;imaging-darkroom;147;147;148;146;147;146;147;147;147;146 21:49:25 INFO - PROCESS | 10937 | |7;imaging-desaturate;95;96;95;96;96;95;95;95;95;95 21:49:25 INFO - PROCESS | 10937 | |8;json-parse-financial;51;50;49;51;52;52;63;52;51;51 21:49:25 INFO - PROCESS | 10937 | |9;json-stringify-tinderbox;60;60;60;60;60;60;61;60;60;61 21:49:25 INFO - PROCESS | 10937 | |10;stanford-crypto-aes;78;83;83;78;79;79;78;79;79;79 21:49:25 INFO - PROCESS | 10937 | |11;stanford-crypto-ccm;125;125;130;132;133;125;122;123;164;123 21:49:25 INFO - PROCESS | 10937 | |12;stanford-crypto-pbkdf2;164;161;160;161;162;165;163;165;164;167 21:49:25 INFO - PROCESS | 10937 | |13;stanford-crypto-sha256-iterative;66;65;65;66;65;74;65;65;65;63 21:49:25 INFO - PROCESS | 10937 | __end_tp_report 21:49:25 INFO - PROCESS | 10937 | __start_cc_report 21:49:25 INFO - PROCESS | 10937 | _x_x_mozilla_cycle_collect,1611 21:49:25 INFO - PROCESS | 10937 | __end_cc_report 21:49:25 INFO - PROCESS | 10937 | __startTimestamp1474519765188__endTimestamp 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | ------- Summary: start ------- 21:49:25 INFO - PROCESS | 10937 | Number of tests: 14 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 21:49:25 INFO - PROCESS | 10937 | Values: 113.0 113.0 111.0 114.0 114.0 112.0 112.0 113.0 112.0 113.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:121.50 stddev:17.15 (14.1%) stddev-sans-first:16.38 21:49:25 INFO - PROCESS | 10937 | Values: 151.0 118.0 119.0 160.0 122.0 119.0 119.0 121.0 152.0 117.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#2] audio-dft Cycles:10 Average:162.40 Median:161.00 stddev:3.53 (2.2%) stddev-sans-first:3.71 21:49:25 INFO - PROCESS | 10937 | Values: 161.0 172.0 160.0 161.0 161.0 161.0 161.0 164.0 161.0 162.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.50 stddev:13.69 (18.1%) stddev-sans-first:14.20 21:49:25 INFO - PROCESS | 10937 | Values: 73.0 74.0 74.0 114.0 76.0 76.0 75.0 76.0 98.0 75.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:82.00 stddev:15.76 (19.2%) stddev-sans-first:16.33 21:49:25 INFO - PROCESS | 10937 | Values: 82.0 83.0 115.0 82.0 82.0 82.0 108.0 81.0 81.0 119.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 21:49:25 INFO - PROCESS | 10937 | Values: 117.0 118.0 118.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 21:49:25 INFO - PROCESS | 10937 | Values: 147.0 147.0 148.0 146.0 147.0 146.0 147.0 147.0 147.0 146.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 21:49:25 INFO - PROCESS | 10937 | Values: 95.0 96.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:51.50 stddev:3.91 (7.6%) stddev-sans-first:4.12 21:49:25 INFO - PROCESS | 10937 | Values: 51.0 50.0 49.0 51.0 52.0 52.0 63.0 52.0 51.0 51.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 21:49:25 INFO - PROCESS | 10937 | Values: 60.0 60.0 60.0 60.0 60.0 60.0 61.0 60.0 60.0 61.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:79.00 stddev:1.90 (2.4%) stddev-sans-first:1.94 21:49:25 INFO - PROCESS | 10937 | Values: 78.0 83.0 83.0 78.0 79.0 79.0 78.0 79.0 79.0 79.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#11] stanford-crypto-ccm Cycles:10 Average:130.20 Median:127.50 stddev:12.50 (9.8%) stddev-sans-first:13.11 21:49:25 INFO - PROCESS | 10937 | Values: 125.0 125.0 130.0 132.0 133.0 125.0 122.0 123.0 164.0 123.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.20 Median:164.00 stddev:2.20 (1.3%) stddev-sans-first:2.32 21:49:25 INFO - PROCESS | 10937 | Values: 164.0 161.0 160.0 161.0 162.0 165.0 163.0 165.0 164.0 167.0 21:49:25 INFO - PROCESS | 10937 | 21:49:25 INFO - PROCESS | 10937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:65.00 stddev:2.96 (4.6%) stddev-sans-first:3.14 21:49:25 INFO - PROCESS | 10937 | Values: 66.0 65.0 65.0 66.0 65.0 74.0 65.0 65.0 65.0 63.0 21:49:25 INFO - PROCESS | 10937 | -------- Summary: end -------- 21:49:25 INFO - PROCESS | 10937 | 21:49:26 INFO - TEST-INFO | 10937: exit 0 21:49:26 INFO - TEST-OK | kraken | took 61992ms 21:49:26 INFO - SUITE-END | took 1178s 21:49:26 INFO - Completed test suite (00:19:38) 21:49:26 INFO - javascript benchmark 21:49:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2966.836823974681, 2989.760819134468, 2998.2654249708826, 3016.6380034395875, 3039.103464477979, 5054.949450505494, 5093.821716239932, 5111.923321150186, 5174.715390653517, 5225.033368826765, 5170.121079416499, 5222.843314700565, 5229.111051121309, 5256.605754568413, 5257.815976440826, 4707.435107787053, 4751.572358487735, 4767.85696429106, 4789.3294938708605, 4896.216605343146, 4916.901661966758, 4954.653174277802, 4961.8015279388965, 4963.776630051639, 4994.175960966436, 4656.068786242765, 4709.835155769566, 4719.268513380397, 4798.5201479852085, 4856.587190088847, 4745.667803253774, 4747.7388743619085, 4769.236922092448, 4828.61371090312, 4833.492483289274, 4716.080364328957, 4717.245240761462, 4728.267118596623, 4816.44610869751, 4817.470078291385, 5232.659877107976, 5240.240165176028, 5247.212918062283, 5254.211868219798, 5256.553192978601, 5505.724713764296, 5519.641223320512, 5541.5843811714285, 5544.306961629796, 5561.805336813192, 5583.804566840141, 5686.346070201938, 5698.7435565399655, 5701.0, 5709.0, 5268.525832675039, 5271.65734227274, 5274.103402421598, 5279.52484276417, 5284.973575132138, 5702.173184888168, 5730.856728581819, 5732.971335143381, 5746.798862039808, 5759.395263497357, 5254.605904557174, 5262.368515778131, 5399.460053994648, 5447.1829225616475, 5468.453154684579, 5546.4453554644215, 5547.223388725582, 5552.7778888844805, 5554.72226388679, 5606.271184745957, 5529.142982837667, 5533.889322213533, 5534.252875861787, 5534.75093620788, 5540.058190107691, 5371.194320851903, 5464.808731694451, 5598.720063996784, 5622.353429355595, 5664.603477756517, 4411.823527058946, 4413.293872980308, 4451.5103338632725, 4460.754658493749, 4465.285554311295, 3887.3974533947285, 3939.527256729211, 3939.940900886489, 3942.8619998300496, 3982.721209515364, 5189.195674670432, 5195.792168313225, 5208.765605547759, 5399.2980912481125, 5447.019536483392, 5575.358833734092, 5577.163425486128, 5620.325560932714, 5620.803271885547, 5641.464060914206, 5235.895282094337, 5268.051750684914, 5278.604104692164, 5280.3663560373, 5289.0, 4811.903761924742, 4883.780229889663, 5025.974870125626, 5026.346574945233, 5069.594432445396, 5735.139729040678, 5760.07838745807, 5771.509421699118, 5777.768889244383, 5854.677992710356, 5615.915761263666, 5626.88746225065, 5635.774569017193, 5638.154276858504, 5643.802466913638, 5639.238702775073, 5738.196652468574, 5806.593538452268, 5807.245058142415, 5807.680577568274, 5418.512333889968, 5582.413846546055, 5587.1339942309005, 5610.49505544503, 5712.34308054579, 5737.311522617312, 5738.282714660668, 5752.827415177551, 5758.1650660654805, 5758.366579676316, 5608.971955140199, 5613.691246981455, 5615.101583746581, 5616.1014237721765, 5617.073182924772, 5308.761105750174, 5361.437049109787, 5366.6243362964215, 5533.889322213614, 5618.410066942912, 5553.611247212818, 5561.694106824163, 5618.831435056954, 5622.353429355513, 5633.943660563506, 5605.747741351731, 5608.242887210337, 5644.209810626598, 5658.20785090096, 5671.234383358194, 5324.866878328073, 5352.759125839424, 5524.972375138099, 5531.197976293334, 5534.612577119563], "value": 5192.271870651369, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.4419468920457, 617.475146125793, 619.2383368456799, 632.5256057956533, 633.2211380002597, 32428.837855810718, 33263.833680831594, 42985.14029719404, 43386.0, 43716.3442548362, 41566.7921660392, 45978.0, 46122.53877461224, 46140.76929615356, 46165.0, 8920.643174273038, 9065.637374505011, 9083.592847895314, 9083.727488175333, 9089.68186113486, 8839.51382673956, 8912.910870891305, 8937.0, 8938.586581622958, 8940.64237430502, 9000.77498062547, 9057.773555661095, 9072.27421806254, 9074.274058075338, 9077.455352678828, 8863.822723545525, 8888.0, 8907.109289071106, 8911.064338244456, 8911.197992180734, 8901.688440904538, 9055.68305109318, 9061.682841100595, 9063.682771103046, 9067.455952642797, 44072.118557628666, 44195.77902110501, 44420.55579444196, 44483.55516444859, 44492.77753611244, 3261.401913062582, 3345.7825241359233, 3365.848536815849, 3707.35121353762, 3924.548676902164, 31874.45840015136, 32585.0, 32680.836595817105, 32920.34159316824, 33022.66977330244, 4270.722403043823, 4339.848105316299, 4387.495438024636, 4395.538468460798, 4543.613792827614, 354847.0, 355673.0, 356732.0, 356784.0, 356883.0, 59269.40730592725, 59608.70195648994, 59841.40158598445, 59914.81448153824, 59972.700136499036, 61760.69119654373, 61821.07268390978, 61828.69085654633, 61889.38110618926, 61918.38081619216, 16662.916685416494, 16969.745453818203, 16971.066591337596, 16975.236114374882, 16976.32094716222, 12043.939780301042, 12052.51789928393, 12054.457549410297, 12062.57780977679, 12113.576024839265, 13423.932880335731, 16638.75041874373, 16656.333746649998, 16698.081605511812, 16731.581710457336, 4923.261510773413, 5600.831975040755, 5753.741081651336, 5833.183354330397, 5937.554683398763, 914.6890057380523, 964.4454438697636, 1021.3565453764081, 1138.6071805227184, 1146.8165093584987, 31544.36911261762, 32132.35735285328, 32223.51664725031, 32320.676793232236, 32781.83609081939, 1034.182995433614, 1037.4345981440133, 1058.8094143054325, 1087.6682611803476, 1142.1433924556582, 16072.276747546388, 16728.0, 16761.0, 16895.83104168967, 16913.77708901448, 538909.0, 539163.0, 540979.0, 541577.0, 541782.0, 58264.41735582675, 58567.0, 58608.0, 58613.12080318799, 58747.70626146842, 1215.3315676377956, 1247.1706315300405, 1325.5691900132495, 1342.3623778705037, 1360.9183448993092, 29920.0, 32762.672373275964, 32914.28685049003, 35572.466413003305, 35743.821280893426, 54847.45152548583, 56056.71971640116, 56219.718901405235, 57926.7103664479, 60612.393876060676, 386517.0, 387890.0, 388024.0, 389336.0, 389377.0, 28377.716222838335, 28540.857295713387, 28861.711382885904, 28884.27789305284, 28996.710032899402, 47734.76132619454, 48235.27647085367, 48240.0, 48640.2703959448, 48759.0, 40970.1805963885, 41073.38389924094, 41446.378304324855, 42897.785511072245, 43120.0, 41585.0, 41834.58165418307, 41856.58143418649, 42264.28921580705, 42795.14409711851, 40232.59767402406, 40308.798456007535, 41585.37621935734, 41635.0, 41638.334466620996, 33567.0, 36286.455703165004, 36526.452103219, 36826.26347473089, 37456.81271593625, 24979.250622481355, 25542.48915021652, 27809.0, 28269.717302827532, 28280.15159545217], "value": 19938.437352748002, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.6714258595023, 393.46488775265635, 397.25514660012476, 407.4499425775204, 419.24545775927743, 13381.39783709733, 15241.314140863675, 19000.904995475037, 19583.608327833434, 19923.103460344282, 17759.91120044398, 17848.821511784878, 19273.710894336597, 19853.205871765113, 19869.205231790747, 5645.463680950304, 5681.943180568193, 5929.347771745104, 5929.525637948976, 5939.1388248703915, 5643.125315576092, 5787.363390027093, 5790.0, 5790.247267855173, 5793.073108302672, 5577.944220557803, 5758.42415758425, 5763.337216220147, 5766.106253530688, 5793.102069179284, 5743.597948143632, 5745.166950792132, 5774.2782152230975, 5781.739821708013, 5791.565632577532, 5009.874753131165, 5138.974305128488, 5174.404943431516, 5215.139501982168, 5217.0, 23437.0, 23700.525989480288, 23825.166119185746, 23898.402539936465, 24009.159679411136, 2381.7141942966955, 2541.263033720219, 2556.297018319958, 2961.392914452532, 3054.7861649684532, 14179.14925104497, 14348.713025739531, 15041.247937603186, 15506.45727399547, 15619.219039048116, 1936.206155476262, 2025.2810252360348, 2041.1325186795552, 2047.8156965873143, 2057.2285392977633, 87697.56151219267, 97553.0, 99563.00436995609, 101617.0, 103049.48475257575, 18124.63750724978, 18199.63600727978, 18445.5388615283, 18540.907295463705, 18549.629007419775, 32549.023529293685, 32614.510782338282, 32704.0, 32770.344593108006, 32834.17914552108, 10009.399436033862, 10132.707605129084, 10156.12111759327, 10342.689719308433, 10558.208134389952, 8553.101924297858, 8605.440646358093, 8640.351973602004, 8717.12828717136, 8867.645294188289, 8507.149285071444, 9071.773205669779, 9140.405873618327, 9257.768555786159, 9334.779501072118, 3492.2491664292297, 3519.2433626769857, 3580.2098950524737, 3658.463445352958, 3662.7619204751604, 827.5820710541138, 917.5641570254076, 920.3235621817959, 954.6086104697041, 1026.3533973596586, 17454.301827926996, 18506.44480665582, 19136.904315478332, 19310.839199132042, 19589.31437399684, 908.246155690775, 954.1698722111807, 968.4576637083256, 981.2346369831542, 994.8607194992707, 10092.899071009342, 10783.83824242637, 10800.729981750519, 10823.296485728402, 10831.83752243717, 444315.0, 450453.0, 461179.0, 462091.0, 462665.0, 68284.0, 69976.65011675011, 79796.20203798004, 90888.54555727179, 96865.0, 896.1352295035192, 932.2309094996655, 968.9370190937567, 977.6675930183771, 981.254246772458, 16855.072970986712, 17209.397671081453, 17660.470185894443, 17687.55781105431, 18034.188461519, 42579.0, 43382.56617433785, 43978.56021439745, 44196.0, 44350.33474497821, 58417.61867860013, 59745.701271493366, 60368.39631603628, 61200.0, 64511.06466806003, 8396.580170991549, 8692.391532592657, 8933.240674542476, 8987.146221108984, 9110.088991101102, 12551.0, 12804.359782010675, 13314.866851331362, 13410.262435566137, 13830.170189788645, 11468.82796758066, 11505.309681419141, 11566.710832229262, 11586.13104017219, 11683.123765717435, 11599.5360185595, 11656.30062196271, 11731.88268117308, 11923.284602923852, 11973.853619887303, 11149.554017839195, 11331.150163737588, 11347.773044539228, 11376.886231137583, 11627.2211531251, 7973.242541958504, 8005.919940800751, 8692.174243446863, 8727.170918762708, 8798.97623546575, 11418.714532136763, 11470.885291146982, 11567.190296679151, 11642.126840486828, 12292.754144916873], "value": 10071.781486643862, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.3719437836107, 1514.931828067737, 1632.9510114696557, 1755.7893052833663, 1788.4008857032886, 7643.46495745298, 7832.8041798955055, 8396.580170991449, 8991.820163596723, 8993.145651163146, 8435.114312997139, 8684.13158684133, 8694.130586941303, 8886.20024197822, 8889.155530224634, 2333.2067097186996, 2361.4450604108106, 2362.6574146748712, 2378.21518898763, 2378.3221781792195, 2001.2495314257153, 2316.81465482761, 2339.789418952294, 2393.9880300598475, 2404.7475015123423, 2011.6982452632076, 2268.3081660093667, 2331.5453486570127, 2337.263761915, 2337.9532409351805, 2137.9144834206613, 2159.708439360682, 2160.2331172433874, 2171.055590817999, 2209.812165965895, 2234.70948776658, 2245.820334373246, 2245.977540224593, 2251.74104977928, 2256.311824893407, 8854.557272136368, 8893.911060889437, 8953.865692014626, 8956.552172391419, 8961.910380896174, 4993.151164302078, 5058.772355244023, 5154.0, 5174.793008279665, 5195.792168313301, 3339.682730140633, 3448.3103379323984, 3494.7728397654073, 3523.277728065766, 3532.9116772080647, 1141.566204842163, 1209.8064309710492, 1228.6498347970785, 1235.7528494301087, 2115.375964090597, 11815.11386646005, 12252.326122063372, 12278.815817762741, 12285.754284914252, 12287.1399002069, 4482.058767658763, 4514.255147900625, 4540.318952157137, 4566.49768525462, 4646.83736069236, 9768.658096966714, 9839.950800246095, 9890.851637225447, 9898.901010989943, 9903.405795652285, 5129.820456284012, 5144.819931302386, 5157.510036546522, 5157.716325602053, 5193.272941788153, 4651.418572678415, 4711.1519926413575, 4805.1590971579835, 4806.206975849016, 4806.8077276909235, 3691.7600355976774, 3715.981420092882, 3768.962310376916, 3799.848006079781, 3844.1927195289304, 944.40502483435, 1116.4919961417536, 1136.5851464215502, 1168.8597368315857, 1187.5368606243571, 808.9069756978023, 946.7396465971775, 954.3844220477753, 962.7929995050957, 972.3582435592616, 5885.61743486672, 5966.582339236212, 5998.880022399528, 6181.81454556364, 6451.3548645135115, 961.2358175250693, 964.5466630683568, 988.4020167798523, 1025.415513157508, 1048.3133547526381, 3825.6939444844347, 3856.2094770571966, 3899.863504777319, 3983.741056831355, 4039.959600403958, 10455.111315538265, 10699.0, 10778.29941053829, 10798.78402431947, 10803.675889723321, 10675.786484270271, 10680.946595266974, 10692.839607405895, 10705.357678539312, 10707.785844283071, 7865.646045927947, 8202.712905048294, 8260.917390826015, 8318.792030199293, 8330.541820199947, 6112.8777424452155, 6132.47873930703, 6217.409346112112, 6229.688515574113, 6233.314335423009, 10062.89937100649, 10161.288709790244, 10182.083612474913, 10205.540750666089, 10257.179425645782, 9705.078017588317, 10054.597816087275, 10071.848922266025, 10178.592856285963, 10384.532696028848, 7779.57212353326, 7924.326432253092, 7946.562939038408, 7958.522488650699, 7966.402519810921, 8387.916120838958, 8405.243528082257, 8480.279176270085, 8481.109483504144, 8497.787555311166, 7645.235476452533, 7696.422768292512, 7764.184760600281, 7850.6074696266105, 7856.332211762065, 7561.168271490242, 7743.0, 7756.728514501965, 7768.300852923264, 7782.844343113219, 6884.345987131214, 6904.067950826775, 6926.168859736864, 6934.341237582422, 7089.6100714459135, 5015.272785446163, 5140.43455219933, 5176.663516871391, 5185.22221666753, 5382.623216374816, 5069.112905241642, 5159.819406320761, 5247.4227834937365, 5280.287161233338, 5318.282031925795], "value": 4623.754924921429, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.08330715806375, 331.36543519160807, 332.52615023591375, 344.1087583159618, 349.52639173919323, 5088.2622019807195, 5233.371995360562, 5287.233351164079, 5336.252924590555, 5355.464453554648, 5073.720945348014, 5158.380994280682, 5185.351831021122, 5194.584433245341, 5236.9526094781, 4111.7944102794745, 4122.958770412302, 4128.8967775805695, 4138.392812528103, 4170.332746760519, 3928.7839168845703, 4103.589641035887, 4143.647789937844, 4178.791060446981, 4193.706440549163, 4071.755694658315, 4090.1819636072755, 4093.9385909211387, 4094.1811637672436, 4106.301928672133, 4106.7125301228925, 4118.217538667644, 4132.979335103335, 4138.896527586804, 4160.417541544186, 3507.140750516114, 3629.4737263096968, 3637.563492380905, 3644.9635503645154, 3692.649198326155, 5073.289739436482, 5099.184130539133, 5116.539511443951, 5120.206368012964, 5122.129238029544, 4650.930236046428, 4691.52217051631, 4700.647451441156, 4700.858974230779, 4700.882477938045, 5288.947110528922, 5406.729663516809, 5451.427600102012, 5476.17857321405, 5492.395836457987, 3940.822662980173, 3957.4657421248053, 3970.285348637244, 3972.145988612462, 3978.2242462719782, 5624.91562626561, 5698.715064246771, 5718.313802343704, 5738.454846789548, 5750.194972703783, 3962.782046987443, 4031.697622678311, 4065.8170382332864, 4073.190450917885, 4101.405296232029, 4045.959540404617, 4053.7433395647445, 4102.938455923164, 4105.548389677133, 4124.360724087771, 4602.470715867651, 4631.3516107744945, 4664.95335046652, 4672.789724462407, 4708.081924024782, 5232.581393488512, 5523.917141242885, 5569.805056822992, 5584.497395234448, 5593.720313984284, 1349.5694563762443, 1374.3334482775845, 1375.2229990055587, 1377.2493990775051, 1378.8417729107598, 937.3719607862748, 951.3103000324847, 958.8082383523323, 979.2313034268066, 984.5618699678718, 5202.81790137351, 5223.582113430916, 5342.9465705342445, 5359.08895487768, 5378.435264297271, 5467.835964921059, 5550.889822035609, 5592.888142237132, 5707.34365547959, 5721.685307308138, 5265.420803711589, 5294.49702278283, 5311.628186027018, 5319.340329835082, 5349.946500535023, 2954.8818047278296, 2979.4488019716423, 2983.985080074629, 2988.9701102989125, 2989.312458134641, 5294.841154765363, 5772.624779389326, 5790.565707571865, 5826.533877289807, 5829.970850145807, 5732.570057245724, 5802.709864506758, 5810.360860305447, 5814.854628634318, 5841.561882858802, 5615.596100974756, 5863.824085277449, 5872.941270587325, 5900.173975643327, 5908.586398952118, 5691.833174199362, 5714.085746280575, 5729.140628905531, 5730.684812335361, 5734.0, 5652.265205523256, 5672.262605861211, 5696.886062278649, 5754.395788442321, 5758.740856661377, 5826.621269617462, 5831.096180092092, 5886.676232807236, 5889.852753681021, 5890.204822348928, 4115.32097203958, 4116.115035267432, 4134.896627584334, 4147.3986271991, 4153.54311025793, 5417.295751552273, 5671.773129074956, 5765.0, 5779.508741756998, 5791.7683292667825, 5532.944670553243, 5548.472895074961, 5554.916676249779, 5555.2778138843305, 5581.5255703265675, 5281.920771188512, 5571.637843540157, 5582.609217354746, 5604.337939478715, 5641.802536911188, 5210.072273258918, 5333.866653333543, 5394.811181608625, 5465.37148227943, 5466.376139453006, 5488.807891723771, 5517.834464966058, 5527.766256739852, 5542.445755424425, 5553.305836770403, 5241.240020196974, 5405.081136206775, 5437.755301011385, 5504.174373843955, 5506.394296627288], "value": 4216.793838383356, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.2347605442449, 758.275846566529, 775.9030121234846, 787.5668382389684, 794.1105961323319, 15442.382304707813, 17102.486925392226, 17126.400575979842, 17132.743008854875, 17182.3127074917, 12946.805797913015, 12979.870201297985, 12987.545435909746, 12995.610131696041, 13022.283774392405, 664.0537234440926, 678.443676185528, 773.9613019349039, 781.144646611959, 802.7872613757359, 800.0639252075057, 805.7582725182451, 814.6578437056421, 814.698561532231, 814.9551774652392, 864.6022829498406, 865.0700496965787, 866.3415803988951, 866.8613021916494, 867.0765634599131, 688.6109348218266, 710.6269208665441, 819.5041999590221, 840.3739214285335, 859.3426029087752, 583.1806312131462, 583.4253260538367, 583.4894467341077, 583.6089819820688, 588.6056342250703, 14567.781483277759, 14649.414023439049, 14657.633559161, 14700.926495367456, 14710.0, 13922.791158132635, 14357.138571685733, 14548.781768273378, 14601.415943362254, 14748.115113093141, 13099.279539625317, 13158.934205329007, 13258.801117983237, 13485.123466974714, 13644.863551364459, 4370.628496577795, 4457.108578284324, 4491.820327186909, 4605.125026245003, 4629.652776041777, 87724.99999999936, 88874.0, 88947.0, 89374.0, 89643.55178224067, 10776.0, 10890.0, 11067.169962252703, 11186.38474883873, 11296.876640026496, 20773.584528309348, 20823.895880520802, 20898.0, 20925.058372373278, 20945.685814712793, 8294.294984926228, 8312.0, 8476.449030812977, 8544.786380340542, 8640.092790257058, 4455.977720111444, 4480.395146655225, 4497.572730590588, 4557.270836666184, 4641.86074417768, 3284.21178917063, 3392.3384939936477, 3402.1154499830222, 3403.982980085084, 3409.8977030688625, 541.8320320700595, 542.5144495676426, 543.1120118606108, 543.4212563619732, 543.6683622989973, 649.6037417175519, 653.8365408647838, 679.6839469646638, 725.9927400725925, 740.1044735869658, 8898.154675305836, 9448.433094014381, 9559.713208603753, 9982.0, 9989.900100999042, 628.8868003759367, 680.5338343234899, 701.6602314683697, 715.6636380900968, 736.9410447164214, 4234.767087810138, 4642.744649044267, 4720.95279047212, 4915.631327650441, 4942.332785074041, 122067.0, 123908.38045809713, 124765.37617312037, 126950.0, 130987.0, 108781.0, 110356.0, 111430.0, 113727.0, 113878.0, 78869.0, 79744.6012769944, 90539.54730226306, 93970.0, 95461.52269238609, 9489.57296921654, 9716.3198576099, 9964.0, 9970.800583988424, 9998.700038998843, 52064.479355205964, 52174.91300348096, 53147.73426132845, 53268.0, 53577.46422535881, 68449.0, 69174.0, 74360.0, 76730.0, 78501.60749196218, 13215.405306761411, 13280.734385312433, 13369.53206637763, 13466.461341546228, 13570.660733481742, 13908.652283692989, 14468.710625787637, 14826.851731482548, 15755.133467659389, 16009.199540023184, 13540.255285959367, 13640.113392629448, 13785.586432407044, 13955.651108722363, 14189.503367382093, 14292.28538573088, 14297.857021429652, 14408.351624177147, 14607.415703371746, 14631.780523292373, 13947.0, 13970.091944023607, 13994.0, 14236.786448203078, 14306.785398219243, 11501.21607305387, 11683.18217724751, 11690.0, 11958.820617690915, 12098.697532561757, 11705.531778728755, 11888.605882118987, 11979.34113623759, 12046.819297710366, 12195.451204695988], "value": 7631.848998121785, "unit": "score"}], "name": "dromaeo_css", "value": 7330.519919083255, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 111.0, 114.0, 114.0, 112.0, 112.0, 113.0, 112.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 119.0, 160.0, 122.0, 119.0, 119.0, 121.0, 152.0, 117.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 172.0, 160.0, 161.0, 161.0, 161.0, 161.0, 164.0, 161.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 114.0, 76.0, 76.0, 75.0, 76.0, 98.0, 75.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 115.0, 82.0, 82.0, 82.0, 108.0, 81.0, 81.0, 119.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 146.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 51.0, 52.0, 52.0, 63.0, 52.0, 51.0, 51.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 83.0, 78.0, 79.0, 79.0, 78.0, 79.0, 79.0, 79.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 125.0, 130.0, 132.0, 133.0, 125.0, 122.0, 123.0, 164.0, 123.0], "value": 130.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 160.0, 161.0, 162.0, 165.0, 163.0, 165.0, 164.0, 167.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 65.0, 74.0, 65.0, 65.0, 65.0, 63.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1487.9000000000003, "alertThreshold": 2.0}]} 21:49:26 INFO - Return code: 0 21:49:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:49:26 INFO - # TBPL SUCCESS # 21:49:26 INFO - Running post-action listener: _resource_record_post_action 21:49:26 INFO - [mozharness: 2016-09-22 04:49:26.985715Z] Finished run-tests step (success) 21:49:26 INFO - Running post-run listener: _resource_record_post_run 21:49:27 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 200704; Write bytes: 398721024; Read time: 68; Write time: 339312 21:49:27 INFO - TinderboxPrint: CPU usage
13.0% 21:49:27 INFO - TinderboxPrint: I/O read bytes / time
200,704 / 68 21:49:27 INFO - TinderboxPrint: I/O write bytes / time
398,721,024 / 339,312 21:49:27 INFO - TinderboxPrint: CPU idle
8,298.1 (86.9%) 21:49:27 INFO - TinderboxPrint: CPU user
1,193.8 (12.5%) 21:49:27 INFO - TinderboxPrint: Swap in / out
0 / 0 21:49:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110813184; Read time: 0; Write time: 105464 21:49:27 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 196608; Write bytes: 287444992; Read time: 52; Write time: 233844 21:49:27 INFO - Running post-run listener: _upload_blobber_files 21:49:27 INFO - Blob upload gear active. 21:49:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:49:27 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 21:49:27 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:49:27 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:49:28 INFO - (blobuploader) - INFO - Open directory for files ... 21:49:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:49:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:49:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:49:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:49:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:49:33 INFO - (blobuploader) - INFO - Done attempting. 21:49:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:49:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:49:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:49:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:49:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:49:34 INFO - (blobuploader) - INFO - Done attempting. 21:49:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:49:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:49:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:49:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:49:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:49:34 INFO - (blobuploader) - INFO - Done attempting. 21:49:34 INFO - (blobuploader) - INFO - Iteration through files over. 21:49:34 INFO - Return code: 0 21:49:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:49:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:49:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/06a08dd6f75247d7c25ae80924a2a9708a8b5f645fb07ecb11829080255d6fb9a109e7f9fa0b7d98bf6e025e4dff58d5e56684bef5b5af5b1278a2ecece12d16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e84b01be464e7e70015f9fc7aad208dc0268ca252720549c4a9a58e9550297c58ed97eb8f1053ad24a48c4a07b49478f3c9bbacac930d825c836e40933bf718e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7c304327438c186e0ab55c8050c4a463b7fbc9855f4d1953deefc15715fc8065acf8c515b4e9f51a5ab34dc0341bec4cbe39743a20ccedb1c81ae9228f0fbca0"} 21:49:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:49:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:49:34 INFO - Contents: 21:49:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/06a08dd6f75247d7c25ae80924a2a9708a8b5f645fb07ecb11829080255d6fb9a109e7f9fa0b7d98bf6e025e4dff58d5e56684bef5b5af5b1278a2ecece12d16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e84b01be464e7e70015f9fc7aad208dc0268ca252720549c4a9a58e9550297c58ed97eb8f1053ad24a48c4a07b49478f3c9bbacac930d825c836e40933bf718e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7c304327438c186e0ab55c8050c4a463b7fbc9855f4d1953deefc15715fc8065acf8c515b4e9f51a5ab34dc0341bec4cbe39743a20ccedb1c81ae9228f0fbca0"} 21:49:34 INFO - Running post-run listener: copy_logs_to_upload_dir 21:49:34 INFO - Copying logs to upload dir... 21:49:34 INFO - mkdir: /builds/slave/test/build/upload/logs 21:49:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.081882 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-21 21:49:34.873405) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 21:49:34.877500) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920 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/sha512/06a08dd6f75247d7c25ae80924a2a9708a8b5f645fb07ecb11829080255d6fb9a109e7f9fa0b7d98bf6e025e4dff58d5e56684bef5b5af5b1278a2ecece12d16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e84b01be464e7e70015f9fc7aad208dc0268ca252720549c4a9a58e9550297c58ed97eb8f1053ad24a48c4a07b49478f3c9bbacac930d825c836e40933bf718e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7c304327438c186e0ab55c8050c4a463b7fbc9855f4d1953deefc15715fc8065acf8c515b4e9f51a5ab34dc0341bec4cbe39743a20ccedb1c81ae9228f0fbca0"} build_url:https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://queue.taskcluster.net/v1/task/J0y3r3AeR723y6ngB-j3-A/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/06a08dd6f75247d7c25ae80924a2a9708a8b5f645fb07ecb11829080255d6fb9a109e7f9fa0b7d98bf6e025e4dff58d5e56684bef5b5af5b1278a2ecece12d16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e84b01be464e7e70015f9fc7aad208dc0268ca252720549c4a9a58e9550297c58ed97eb8f1053ad24a48c4a07b49478f3c9bbacac930d825c836e40933bf718e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7c304327438c186e0ab55c8050c4a463b7fbc9855f4d1953deefc15715fc8065acf8c515b4e9f51a5ab34dc0341bec4cbe39743a20ccedb1c81ae9228f0fbca0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 21:49:34.908602) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:49:34.909126) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1474518532.696265-1488856920 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:49:34.994521) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 21:49:34.994852) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 21:49:34.995149) ========= ========= Total master_lag: 0.19 =========