builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-028 starttime: 1474605744.17 results: success (0) buildid: 20160922200659 builduid: 09c4c16e0ac44eaf904428944feaf740 revision: 379da15420cb0a94e8421f63d70e028051d22d86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:24.169730) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:24.170274) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:24.170599) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:24.186203) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:24.186541) ========= 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=836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-22 21:42:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.4M=0s 2016-09-22 21:42:24 (50.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.078549 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:24.279394) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:24.279767) ========= 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=836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017214 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:24.329862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:24.330284) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 379da15420cb0a94e8421f63d70e028051d22d86 --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 379da15420cb0a94e8421f63d70e028051d22d86 --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=836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-22 21:42:24,733 truncating revision to first 12 chars 2016-09-22 21:42:24,733 Setting DEBUG logging. 2016-09-22 21:42:24,733 attempt 1/10 2016-09-22 21:42:24,733 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/379da15420cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-22 21:42:25,184 unpacking tar archive at: autoland-379da15420cb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.782465 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:25.126808) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:25.127174) ========= script_repo_revision: 379da15420cb0a94e8421f63d70e028051d22d86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:25.127606) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:25.127926) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 21:42:25.137905) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-09-22 21:42:25.138342) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:42:25 INFO - MultiFileLogger online at 20160922 21:42:25 in /builds/slave/test 21:42:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 21:42:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:42:25 INFO - {'append_to_log': False, 21:42:25 INFO - 'base_work_dir': '/builds/slave/test', 21:42:25 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 21:42:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:42:25 INFO - 'branch': 'Autoland-Non-PGO', 21:42:25 INFO - 'buildbot_json_path': 'buildprops.json', 21:42:25 INFO - 'config_files': ('talos/linux_config.py',), 21:42:25 INFO - 'default_actions': ('clobber', 21:42:25 INFO - 'read-buildbot-config', 21:42:25 INFO - 'download-and-extract', 21:42:25 INFO - 'populate-webroot', 21:42:25 INFO - 'create-virtualenv', 21:42:25 INFO - 'install', 21:42:25 INFO - 'run-tests'), 21:42:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:42:25 INFO - 'download_minidump_stackwalk': True, 21:42:25 INFO - 'download_symbols': 'ondemand', 21:42:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:42:25 INFO - 'tooltool.py': '/tools/tooltool.py', 21:42:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:42:25 INFO - '/tools/misc-python/virtualenv.py')}, 21:42:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:42:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:42:25 INFO - 'installer_path': 'installer.exe', 21:42:25 INFO - 'log_level': 'info', 21:42:25 INFO - 'log_name': 'talos', 21:42:25 INFO - 'log_to_console': True, 21:42:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:42:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:42:25 INFO - 'opt_config_files': (), 21:42:25 INFO - 'pip_index': False, 21:42:25 INFO - 'sps_profile': False, 21:42:25 INFO - 'sps_profile_interval': 0, 21:42:25 INFO - 'suite': 'dromaeojs', 21:42:25 INFO - 'system_bits': '32', 21:42:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:42:25 INFO - 'title': 'talos-linux64-ix-028', 21:42:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:42:25 INFO - 'use_talos_json': True, 21:42:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:42:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:42:25 INFO - 'work_dir': 'build'} 21:42:25 INFO - [mozharness: 2016-09-23 04:42:25.640121Z] Running clobber step. 21:42:25 INFO - Running pre-action listener: _resource_record_pre_action 21:42:25 INFO - Running main action method: clobber 21:42:25 INFO - rmtree: /builds/slave/test/build 21:42:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:42:26 INFO - Running post-action listener: _resource_record_post_action 21:42:26 INFO - [mozharness: 2016-09-23 04:42:26.114653Z] Finished clobber step (success) 21:42:26 INFO - [mozharness: 2016-09-23 04:42:26.114754Z] Running read-buildbot-config step. 21:42:26 INFO - Running pre-action listener: _resource_record_pre_action 21:42:26 INFO - Running main action method: read_buildbot_config 21:42:26 INFO - Using buildbot properties: 21:42:26 INFO - { 21:42:26 INFO - "project": "", 21:42:26 INFO - "product": "firefox", 21:42:26 INFO - "script_repo_revision": "production", 21:42:26 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 21:42:26 INFO - "repository": "", 21:42:26 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 21:42:26 INFO - "buildid": "20160922200659", 21:42:26 INFO - "pgo_build": "False", 21:42:26 INFO - "basedir": "/builds/slave/test", 21:42:26 INFO - "buildnumber": 702, 21:42:26 INFO - "slavename": "talos-linux64-ix-028", 21:42:26 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:42:26 INFO - "platform": "ubuntu64_hw", 21:42:26 INFO - "scheduler": "tests-autoland-linux64-talos", 21:42:26 INFO - "branch": "autoland", 21:42:26 INFO - "repo_path": "integration/autoland", 21:42:26 INFO - "revision": "379da15420cb0a94e8421f63d70e028051d22d86", 21:42:26 INFO - "stage_platform": "linux64", 21:42:26 INFO - "builduid": "09c4c16e0ac44eaf904428944feaf740", 21:42:26 INFO - "slavebuilddir": "test" 21:42:26 INFO - } 21:42:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 21:42:26 INFO - Running post-action listener: _resource_record_post_action 21:42:26 INFO - [mozharness: 2016-09-23 04:42:26.117212Z] Finished read-buildbot-config step (success) 21:42:26 INFO - [mozharness: 2016-09-23 04:42:26.117301Z] Running download-and-extract step. 21:42:26 INFO - Running pre-action listener: _resource_record_pre_action 21:42:26 INFO - Running main action method: download_and_extract 21:42:26 INFO - mkdir: /builds/slave/test/build/tests 21:42: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:42:26 INFO - trying https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:42:26 INFO - Downloading https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/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:42:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/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:42:26 INFO - Downloaded 1361 bytes. 21:42:26 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:42:26 INFO - Using the following test package requirements: 21:42:26 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 21:42:26 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:42:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:42:26 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:42:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:42:26 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:42:26 INFO - u'jsshell-linux-x86_64.zip'], 21:42:26 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:42:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:42:26 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 21:42:26 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:42:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:42:26 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:42:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:42:26 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:42:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:42:26 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:42:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:42:26 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:42:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 21:42:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:42:26 INFO - Fetch https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:42:27 INFO - Expected file size: 20371529 21:42:31 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:42:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 21:42:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:42:31 INFO - Fetch https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:42:32 INFO - Expected file size: 20371529 21:42:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 21:42:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:42:36 INFO - Fetch https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:42:37 INFO - Expected file size: 14414059 21:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:37 INFO - trying https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:42:37 INFO - Downloading https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/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:42:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/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:42:38 INFO - Downloaded 55703455 bytes. 21:42:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:42:38 INFO - mkdir: /builds/slave/test/properties 21:42:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:42:38 INFO - Writing to file /builds/slave/test/properties/build_url 21:42:38 INFO - Contents: 21:42:38 INFO - build_url:https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:42:39 INFO - Running post-action listener: _resource_record_post_action 21:42:39 INFO - Running post-action listener: set_extra_try_arguments 21:42:39 INFO - [mozharness: 2016-09-23 04:42:39.485687Z] Finished download-and-extract step (success) 21:42:39 INFO - [mozharness: 2016-09-23 04:42:39.485914Z] Running populate-webroot step. 21:42:39 INFO - Running pre-action listener: _resource_record_pre_action 21:42:39 INFO - Running main action method: populate_webroot 21:42:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:42:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:42:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:42:39 INFO - '--tppagecycles', 21:42:39 INFO - '7'], 21:42:39 INFO - 'tests': ['tsvgm']}}, 21:42:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:42:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:42:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:42:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:42:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:39 INFO - 'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:42:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:42:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:39 INFO - 'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['damp', 'tps']}, 21:42:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:39 INFO - 'tests': ['damp', 'tps']}, 21:42:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['dromaeo_dom']}, 21:42:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:42:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['basic_compositor_video']}, 21:42:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:42:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['a11yr', 21:42:39 INFO - 'ts_paint', 21:42:39 INFO - 'tpaint', 21:42:39 INFO - 'sessionrestore', 21:42:39 INFO - 'sessionrestore_no_auto_restore', 21:42:39 INFO - 'tabpaint']}, 21:42:39 INFO - 'other-e10s': {'tests': ['a11yr', 21:42:39 INFO - 'ts_paint', 21:42:39 INFO - 'tpaint', 21:42:39 INFO - 'sessionrestore', 21:42:39 INFO - 'sessionrestore_no_auto_restore', 21:42:39 INFO - 'tabpaint']}, 21:42:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:42:39 INFO - 'ts_paint', 21:42:39 INFO - 'tpaint', 21:42:39 INFO - 'sessionrestore', 21:42:39 INFO - 'sessionrestore_no_auto_restore', 21:42:39 INFO - 'tabpaint']}, 21:42:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:42:39 INFO - 'ts_paint', 21:42:39 INFO - 'tpaint', 21:42:39 INFO - 'sessionrestore', 21:42:39 INFO - 'sessionrestore_no_auto_restore', 21:42:39 INFO - 'tabpaint']}, 21:42:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['a11yr', 21:42:39 INFO - 'ts_paint', 21:42:39 INFO - 'tpaint', 21:42:39 INFO - 'sessionrestore', 21:42:39 INFO - 'sessionrestore_no_auto_restore', 21:42:39 INFO - 'tabpaint']}, 21:42:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['a11yr', 21:42:39 INFO - 'ts_paint', 21:42:39 INFO - 'tpaint', 21:42:39 INFO - 'sessionrestore', 21:42:39 INFO - 'sessionrestore_no_auto_restore', 21:42:39 INFO - 'tabpaint']}, 21:42:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['tsvgx', 21:42:39 INFO - 'tsvgr_opacity', 21:42:39 INFO - 'tart', 21:42:39 INFO - 'tscrollx', 21:42:39 INFO - 'cart']}, 21:42:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:42:39 INFO - 'tsvgr_opacity', 21:42:39 INFO - 'tart', 21:42:39 INFO - 'tscrollx', 21:42:39 INFO - 'cart']}, 21:42:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:39 INFO - 'talos_options': ['--disable-e10s'], 21:42:39 INFO - 'tests': ['tp5o']}, 21:42:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:39 INFO - 'tests': ['tp5o']}, 21:42:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:39 INFO - 'talos_options': ['--disable-e10s', 21:42:39 INFO - '--xperf_path', 21:42:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:42:39 INFO - 'tests': ['tp5n']}, 21:42:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:42:39 INFO - 'talos_options': ['--xperf_path', 21:42:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:42:39 INFO - 'tests': ['tp5n']}}, 21:42:39 INFO - 'talos.zip': {'path': '', 21:42:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:42:39 INFO - Running post-action listener: _resource_record_post_action 21:42:39 INFO - [mozharness: 2016-09-23 04:42:39.507475Z] Finished populate-webroot step (success) 21:42:39 INFO - [mozharness: 2016-09-23 04:42:39.507564Z] Running create-virtualenv step. 21:42:39 INFO - Running pre-action listener: _resource_record_pre_action 21:42:39 INFO - Running main action method: create_virtualenv 21:42:39 INFO - Creating virtualenv /builds/slave/test/build/venv 21:42:39 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:42:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:42:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:42:39 INFO - Using real prefix '/usr' 21:42:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:42:40 INFO - Installing distribute.............................................................................................................................................................................................done. 21:42:42 INFO - Installing pip.................done. 21:42:42 INFO - Return code: 0 21:42:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:42 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 0x7fca3f9b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', '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:42:42 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:42:42 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:42:42 INFO - Using env: {'DISPLAY': ':0', 21:42:42 INFO - 'HOME': '/home/cltbld', 21:42:42 INFO - 'LANG': 'en_US.UTF-8', 21:42:42 INFO - 'LANGUAGE': 'en_US:en', 21:42:42 INFO - 'LOGNAME': 'cltbld', 21:42:42 INFO - 'MAIL': '/var/mail/cltbld', 21:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:42 INFO - 'MOZ_NO_REMOTE': '1', 21:42:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:42 INFO - 'NO_EM_RESTART': '1', 21:42:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:42 INFO - 'PWD': '/builds/slave/test', 21:42:42 INFO - 'SHELL': '/bin/bash', 21:42:42 INFO - 'SHLVL': '1', 21:42:42 INFO - 'TERM': 'linux', 21:42:42 INFO - 'TMOUT': '86400', 21:42:42 INFO - 'USER': 'cltbld', 21:42:42 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:42 INFO - '_': '/tools/buildbot/bin/python'} 21:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:42 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:42:42 INFO - Cleaning up... 21:42:42 INFO - Return code: 0 21:42:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:42 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 0x7fca3f9b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', '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:42:42 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:42:42 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:42:42 INFO - Using env: {'DISPLAY': ':0', 21:42:42 INFO - 'HOME': '/home/cltbld', 21:42:42 INFO - 'LANG': 'en_US.UTF-8', 21:42:42 INFO - 'LANGUAGE': 'en_US:en', 21:42:42 INFO - 'LOGNAME': 'cltbld', 21:42:42 INFO - 'MAIL': '/var/mail/cltbld', 21:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:42 INFO - 'MOZ_NO_REMOTE': '1', 21:42:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:42 INFO - 'NO_EM_RESTART': '1', 21:42:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:42 INFO - 'PWD': '/builds/slave/test', 21:42:42 INFO - 'SHELL': '/bin/bash', 21:42:42 INFO - 'SHLVL': '1', 21:42:42 INFO - 'TERM': 'linux', 21:42:42 INFO - 'TMOUT': '86400', 21:42:42 INFO - 'USER': 'cltbld', 21:42:42 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:42 INFO - '_': '/tools/buildbot/bin/python'} 21:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:42 INFO - Downloading/unpacking psutil>=3.1.1 21:42:42 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:42:42 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:42:42 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:42:42 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:42:42 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:42:42 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:42:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:42:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:42:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:42:44 INFO - Installing collected packages: psutil 21:42:44 INFO - Running setup.py install for psutil 21:42:44 INFO - building 'psutil._psutil_linux' extension 21:42:44 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:42:44 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:42:44 INFO - building 'psutil._psutil_posix' extension 21:42:44 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:42:44 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:42:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:42:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:42:45 INFO - Successfully installed psutil 21:42:45 INFO - Cleaning up... 21:42:45 INFO - Return code: 0 21:42:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:45 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 0x7fca3f9b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', '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:42:45 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:42:45 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:42:45 INFO - Using env: {'DISPLAY': ':0', 21:42:45 INFO - 'HOME': '/home/cltbld', 21:42:45 INFO - 'LANG': 'en_US.UTF-8', 21:42:45 INFO - 'LANGUAGE': 'en_US:en', 21:42:45 INFO - 'LOGNAME': 'cltbld', 21:42:45 INFO - 'MAIL': '/var/mail/cltbld', 21:42:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:45 INFO - 'MOZ_NO_REMOTE': '1', 21:42:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:45 INFO - 'NO_EM_RESTART': '1', 21:42:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:45 INFO - 'PWD': '/builds/slave/test', 21:42:45 INFO - 'SHELL': '/bin/bash', 21:42:45 INFO - 'SHLVL': '1', 21:42:45 INFO - 'TERM': 'linux', 21:42:45 INFO - 'TMOUT': '86400', 21:42:45 INFO - 'USER': 'cltbld', 21:42:45 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:42:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:45 INFO - '_': '/tools/buildbot/bin/python'} 21:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:42:45 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:42:45 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:42:45 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:42:45 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:42:45 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:42:45 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:42:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:42:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:42:47 INFO - Installing collected packages: mozsystemmonitor 21:42:47 INFO - Running setup.py install for mozsystemmonitor 21:42:47 INFO - Successfully installed mozsystemmonitor 21:42:47 INFO - Cleaning up... 21:42:47 INFO - Return code: 0 21:42:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:42:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:47 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 0x7fca3f9b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', '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:42:47 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:42:47 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:42:47 INFO - Using env: {'DISPLAY': ':0', 21:42:47 INFO - 'HOME': '/home/cltbld', 21:42:47 INFO - 'LANG': 'en_US.UTF-8', 21:42:47 INFO - 'LANGUAGE': 'en_US:en', 21:42:47 INFO - 'LOGNAME': 'cltbld', 21:42:47 INFO - 'MAIL': '/var/mail/cltbld', 21:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:47 INFO - 'MOZ_NO_REMOTE': '1', 21:42:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:47 INFO - 'NO_EM_RESTART': '1', 21:42:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:47 INFO - 'PWD': '/builds/slave/test', 21:42:47 INFO - 'SHELL': '/bin/bash', 21:42:47 INFO - 'SHLVL': '1', 21:42:47 INFO - 'TERM': 'linux', 21:42:47 INFO - 'TMOUT': '86400', 21:42:47 INFO - 'USER': 'cltbld', 21:42:47 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:47 INFO - '_': '/tools/buildbot/bin/python'} 21:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:47 INFO - Downloading/unpacking jsonschema==2.5.1 21:42:47 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:42:47 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:42:47 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:42:47 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:42:47 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:42:47 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:42:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:42:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:42:49 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:42:49 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:42:49 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:42:49 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:42:49 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:42:49 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:42:49 INFO - Downloading functools32-3.2.3-2.tar.gz 21:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:42:49 INFO - warning: no files found matching '*.txt' 21:42:49 INFO - no previously-included directories found matching 'build' 21:42:49 INFO - no previously-included directories found matching 'dist' 21:42:49 INFO - no previously-included directories found matching '.git*' 21:42:49 INFO - Installing collected packages: jsonschema, functools32 21:42:49 INFO - Running setup.py install for functools32 21:42:49 INFO - warning: no files found matching '*.txt' 21:42:49 INFO - no previously-included directories found matching 'build' 21:42:49 INFO - no previously-included directories found matching 'dist' 21:42:49 INFO - no previously-included directories found matching '.git*' 21:42:49 INFO - Successfully installed jsonschema functools32 21:42:49 INFO - Cleaning up... 21:42:49 INFO - Return code: 0 21:42:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:42:49 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:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:49 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:42:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:49 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 0x7fca3f9b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', '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:42:49 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:42:49 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:42:49 INFO - Using env: {'DISPLAY': ':0', 21:42:49 INFO - 'HOME': '/home/cltbld', 21:42:49 INFO - 'LANG': 'en_US.UTF-8', 21:42:49 INFO - 'LANGUAGE': 'en_US:en', 21:42:49 INFO - 'LOGNAME': 'cltbld', 21:42:49 INFO - 'MAIL': '/var/mail/cltbld', 21:42:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:49 INFO - 'MOZ_NO_REMOTE': '1', 21:42:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:49 INFO - 'NO_EM_RESTART': '1', 21:42:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:49 INFO - 'PWD': '/builds/slave/test', 21:42:49 INFO - 'SHELL': '/bin/bash', 21:42:49 INFO - 'SHLVL': '1', 21:42:49 INFO - 'TERM': 'linux', 21:42:49 INFO - 'TMOUT': '86400', 21:42:49 INFO - 'USER': 'cltbld', 21:42:49 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:42:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:49 INFO - '_': '/tools/buildbot/bin/python'} 21:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:42:50 INFO - Cleaning up... 21:42:50 INFO - Return code: 0 21:42:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:42:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fca3f9b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', '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:42:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:42:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:42:50 INFO - Using env: {'DISPLAY': ':0', 21:42:50 INFO - 'HOME': '/home/cltbld', 21:42:50 INFO - 'LANG': 'en_US.UTF-8', 21:42:50 INFO - 'LANGUAGE': 'en_US:en', 21:42:50 INFO - 'LOGNAME': 'cltbld', 21:42:50 INFO - 'MAIL': '/var/mail/cltbld', 21:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:50 INFO - 'MOZ_NO_REMOTE': '1', 21:42:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:50 INFO - 'NO_EM_RESTART': '1', 21:42:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:50 INFO - 'PWD': '/builds/slave/test', 21:42:50 INFO - 'SHELL': '/bin/bash', 21:42:50 INFO - 'SHLVL': '1', 21:42:50 INFO - 'TERM': 'linux', 21:42:50 INFO - 'TMOUT': '86400', 21:42:50 INFO - 'USER': 'cltbld', 21:42:50 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:50 INFO - '_': '/tools/buildbot/bin/python'} 21:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:50 INFO - Downloading/unpacking blobuploader==1.2.4 21:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:52 INFO - Downloading blobuploader-1.2.4.tar.gz 21:42:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:42:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:42:52 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:42:52 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:42:52 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:42:52 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:42:52 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:42:52 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:42:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:42:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:42:52 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:42:52 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:42:52 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:42:52 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:42:52 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:42:52 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:42:52 INFO - Downloading docopt-0.6.1.tar.gz 21:42:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:42:53 INFO - Installing collected packages: blobuploader, requests, docopt 21:42:53 INFO - Running setup.py install for blobuploader 21:42:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:42:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:42:53 INFO - Running setup.py install for requests 21:42:53 INFO - Running setup.py install for docopt 21:42:53 INFO - Successfully installed blobuploader requests docopt 21:42:53 INFO - Cleaning up... 21:42:53 INFO - Return code: 0 21:42:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:42:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:53 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 0x7fca3f9b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', '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:42:53 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:42:53 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:42:53 INFO - Using env: {'DISPLAY': ':0', 21:42:53 INFO - 'HOME': '/home/cltbld', 21:42:53 INFO - 'LANG': 'en_US.UTF-8', 21:42:53 INFO - 'LANGUAGE': 'en_US:en', 21:42:53 INFO - 'LOGNAME': 'cltbld', 21:42:53 INFO - 'MAIL': '/var/mail/cltbld', 21:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:53 INFO - 'MOZ_NO_REMOTE': '1', 21:42:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:53 INFO - 'NO_EM_RESTART': '1', 21:42:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:53 INFO - 'PWD': '/builds/slave/test', 21:42:53 INFO - 'SHELL': '/bin/bash', 21:42:53 INFO - 'SHLVL': '1', 21:42:53 INFO - 'TERM': 'linux', 21:42:53 INFO - 'TMOUT': '86400', 21:42:53 INFO - 'USER': 'cltbld', 21:42:53 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:53 INFO - '_': '/tools/buildbot/bin/python'} 21:42:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:42:53 INFO - Running setup.py (path:/tmp/pip-km2R89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:42:53 INFO - Running setup.py (path:/tmp/pip-PoWIER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:42:54 INFO - Running setup.py (path:/tmp/pip-PMa1oY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:42:54 INFO - Running setup.py (path:/tmp/pip-e0nPGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:42:54 INFO - Running setup.py (path:/tmp/pip-yP9D1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:42:54 INFO - Running setup.py (path:/tmp/pip-vdlpNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:42:54 INFO - Running setup.py (path:/tmp/pip-kuvKCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:42:54 INFO - Running setup.py (path:/tmp/pip-CBTYS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:42:54 INFO - Running setup.py (path:/tmp/pip-PkhsKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:42:54 INFO - Running setup.py (path:/tmp/pip-O85gqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:42:54 INFO - Running setup.py (path:/tmp/pip-RtNkR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:42:54 INFO - Running setup.py (path:/tmp/pip-rbHYeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:42:54 INFO - Running setup.py (path:/tmp/pip-_a_DxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:42:54 INFO - Running setup.py (path:/tmp/pip-LlbDDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:42:54 INFO - Running setup.py (path:/tmp/pip-G1iXBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:42:54 INFO - Running setup.py (path:/tmp/pip-IxJsqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:42:55 INFO - Running setup.py (path:/tmp/pip-ZAf5QX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:42:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:42:55 INFO - Running setup.py install for manifestparser 21:42:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:42:55 INFO - Running setup.py install for mozcrash 21:42:55 INFO - Running setup.py install for mozdebug 21:42:55 INFO - Running setup.py install for mozdevice 21:42:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:42:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:42:55 INFO - Running setup.py install for mozfile 21:42:55 INFO - Running setup.py install for mozhttpd 21:42:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:42:55 INFO - Running setup.py install for mozinfo 21:42:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:42:55 INFO - Running setup.py install for mozInstall 21:42:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:42:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:42:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:42:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:42:55 INFO - Running setup.py install for mozleak 21:42:55 INFO - Running setup.py install for mozlog 21:42:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:42:56 INFO - Running setup.py install for moznetwork 21:42:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:42:56 INFO - Running setup.py install for mozprocess 21:42:56 INFO - Running setup.py install for mozprofile 21:42:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:42:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:42:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:42:56 INFO - Running setup.py install for mozrunner 21:42:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:42:56 INFO - Running setup.py install for mozscreenshot 21:42:56 INFO - Running setup.py install for moztest 21:42:56 INFO - Running setup.py install for mozversion 21:42:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:42:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:42:56 INFO - Cleaning up... 21:42:56 INFO - Return code: 0 21:42:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:56 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 0x7fca3f9b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', '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:42:56 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:42:56 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:42:56 INFO - Using env: {'DISPLAY': ':0', 21:42:56 INFO - 'HOME': '/home/cltbld', 21:42:56 INFO - 'LANG': 'en_US.UTF-8', 21:42:56 INFO - 'LANGUAGE': 'en_US:en', 21:42:56 INFO - 'LOGNAME': 'cltbld', 21:42:56 INFO - 'MAIL': '/var/mail/cltbld', 21:42:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:56 INFO - 'MOZ_NO_REMOTE': '1', 21:42:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:42:56 INFO - 'NO_EM_RESTART': '1', 21:42:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:42:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:56 INFO - 'PWD': '/builds/slave/test', 21:42:56 INFO - 'SHELL': '/bin/bash', 21:42:56 INFO - 'SHLVL': '1', 21:42:56 INFO - 'TERM': 'linux', 21:42:56 INFO - 'TMOUT': '86400', 21:42:56 INFO - 'USER': 'cltbld', 21:42:56 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:42:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:56 INFO - '_': '/tools/buildbot/bin/python'} 21:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:42:57 INFO - Running setup.py (path:/tmp/pip-8IYiv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:42:57 INFO - Running setup.py (path:/tmp/pip-YIN7Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:42:57 INFO - Running setup.py (path:/tmp/pip-bVby2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:42:57 INFO - Running setup.py (path:/tmp/pip-jPMkvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:42:57 INFO - Running setup.py (path:/tmp/pip-cvfBI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:42:57 INFO - Running setup.py (path:/tmp/pip-PGF0pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:42:57 INFO - Running setup.py (path:/tmp/pip-Pd6Xl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:42:57 INFO - Running setup.py (path:/tmp/pip-KXDnJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:42:57 INFO - Running setup.py (path:/tmp/pip-cgcGvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:42:57 INFO - Running setup.py (path:/tmp/pip-WNBlaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:42:57 INFO - Running setup.py (path:/tmp/pip-lgivQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:42:57 INFO - Running setup.py (path:/tmp/pip-0D0Ehl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:42:58 INFO - Running setup.py (path:/tmp/pip-aUfdCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:42:58 INFO - Running setup.py (path:/tmp/pip-rVRKi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:42:58 INFO - Running setup.py (path:/tmp/pip-wuRhpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:42:58 INFO - Running setup.py (path:/tmp/pip-FSDd05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:42:58 INFO - Running setup.py (path:/tmp/pip-uCR6Xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:42:58 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:42:58 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:42:58 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:42:58 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:42:58 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:42:58 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:42:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:43:00 INFO - Downloading blessings-1.6.tar.gz 21:43:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:43:00 INFO - Installing collected packages: blessings 21:43:00 INFO - Running setup.py install for blessings 21:43:00 INFO - Successfully installed blessings 21:43:00 INFO - Cleaning up... 21:43:00 INFO - Return code: 0 21:43:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:43:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:43:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:43:00 INFO - Reading from file tmpfile_stdout 21:43:00 INFO - Current package versions: 21:43:00 INFO - argparse == 1.2.1 21:43:00 INFO - blessings == 1.6 21:43:00 INFO - blobuploader == 1.2.4 21:43:00 INFO - docopt == 0.6.1 21:43:00 INFO - functools32 == 3.2.3-2 21:43:00 INFO - jsonschema == 2.5.1 21:43:00 INFO - manifestparser == 1.1 21:43:00 INFO - mozInstall == 1.12 21:43:00 INFO - mozcrash == 0.17 21:43:00 INFO - mozdebug == 0.1 21:43:00 INFO - mozdevice == 0.48 21:43:00 INFO - mozfile == 1.2 21:43:00 INFO - mozhttpd == 0.7 21:43:00 INFO - mozinfo == 0.9 21:43:00 INFO - mozleak == 0.1 21:43:00 INFO - mozlog == 3.4 21:43:00 INFO - moznetwork == 0.27 21:43:00 INFO - mozprocess == 0.23 21:43:00 INFO - mozprofile == 0.28 21:43:00 INFO - mozrunner == 6.12 21:43:00 INFO - mozscreenshot == 0.1 21:43:00 INFO - mozsystemmonitor == 0.3 21:43:00 INFO - moztest == 0.7 21:43:00 INFO - mozversion == 1.4 21:43:00 INFO - psutil == 3.1.1 21:43:00 INFO - requests == 1.2.3 21:43:00 INFO - wsgiref == 0.1.2 21:43:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:43:00 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:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:00 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:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:00 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 0x7fca3f9b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', '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:43:00 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:43:00 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:43:00 INFO - Using env: {'DISPLAY': ':0', 21:43:00 INFO - 'HOME': '/home/cltbld', 21:43:00 INFO - 'LANG': 'en_US.UTF-8', 21:43:00 INFO - 'LANGUAGE': 'en_US:en', 21:43:00 INFO - 'LOGNAME': 'cltbld', 21:43:00 INFO - 'MAIL': '/var/mail/cltbld', 21:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:00 INFO - 'MOZ_NO_REMOTE': '1', 21:43:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:00 INFO - 'NO_EM_RESTART': '1', 21:43:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:00 INFO - 'PWD': '/builds/slave/test', 21:43:00 INFO - 'SHELL': '/bin/bash', 21:43:00 INFO - 'SHLVL': '1', 21:43:00 INFO - 'TERM': 'linux', 21:43:00 INFO - 'TMOUT': '86400', 21:43:00 INFO - 'USER': 'cltbld', 21:43:00 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:00 INFO - '_': '/tools/buildbot/bin/python'} 21:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:00 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:43:00 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:43:00 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:43:00 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:43:00 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:43:00 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:43:00 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:43:00 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:43:00 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:43:00 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:43:00 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:43:00 INFO - Cleaning up... 21:43:00 INFO - Return code: 0 21:43:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:43:00 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:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:00 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:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:00 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 0x7fca3f9b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', '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:43:00 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:43:00 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:43:00 INFO - Using env: {'DISPLAY': ':0', 21:43:00 INFO - 'HOME': '/home/cltbld', 21:43:00 INFO - 'LANG': 'en_US.UTF-8', 21:43:00 INFO - 'LANGUAGE': 'en_US:en', 21:43:00 INFO - 'LOGNAME': 'cltbld', 21:43:00 INFO - 'MAIL': '/var/mail/cltbld', 21:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:00 INFO - 'MOZ_NO_REMOTE': '1', 21:43:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:00 INFO - 'NO_EM_RESTART': '1', 21:43:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:00 INFO - 'PWD': '/builds/slave/test', 21:43:00 INFO - 'SHELL': '/bin/bash', 21:43:00 INFO - 'SHLVL': '1', 21:43:00 INFO - 'TERM': 'linux', 21:43:00 INFO - 'TMOUT': '86400', 21:43:00 INFO - 'USER': 'cltbld', 21:43:00 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:00 INFO - '_': '/tools/buildbot/bin/python'} 21:43:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:43:01 INFO - Cleaning up... 21:43:01 INFO - Return code: 0 21:43:01 INFO - Running post-action listener: _resource_record_post_action 21:43:01 INFO - Running post-action listener: _start_resource_monitoring 21:43:01 INFO - Starting resource monitoring. 21:43:01 INFO - [mozharness: 2016-09-23 04:43:01.215551Z] Finished create-virtualenv step (success) 21:43:01 INFO - [mozharness: 2016-09-23 04:43:01.216009Z] Running install step. 21:43:01 INFO - Running pre-action listener: _resource_record_pre_action 21:43:01 INFO - Running main action method: install 21:43:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:43:01 INFO - Reading from file tmpfile_stdout 21:43:01 INFO - Detecting whether we're running mozinstall >=1.0... 21:43:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:43:01 INFO - Reading from file tmpfile_stdout 21:43:01 INFO - Output received: 21:43:01 INFO - Usage: mozinstall [options] installer 21:43:01 INFO - Options: 21:43:01 INFO - -h, --help show this help message and exit 21:43:01 INFO - -d DEST, --destination=DEST 21:43:01 INFO - Directory to install application into. [default: 21:43:01 INFO - "/builds/slave/test"] 21:43:01 INFO - --app=APP Application being installed. [default: firefox] 21:43:01 INFO - mkdir: /builds/slave/test/build/application 21:43:01 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:43:01 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:43:17 INFO - Reading from file tmpfile_stdout 21:43:17 INFO - Output received: 21:43:17 INFO - /builds/slave/test/build/application/firefox/firefox 21:43:17 INFO - Running post-action listener: _resource_record_post_action 21:43:17 INFO - [mozharness: 2016-09-23 04:43:17.581199Z] Finished install step (success) 21:43:17 INFO - [mozharness: 2016-09-23 04:43:17.581445Z] Running run-tests step. 21:43:17 INFO - Running pre-action listener: _resource_record_pre_action 21:43:17 INFO - Running main action method: run_tests 21:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:43:17 INFO - Python 2.7.3 21:43:17 INFO - Return code: 0 21:43:17 INFO - grabbing minidump binary from tooltool 21:43: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:43:17 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 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:43:17 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:43:17 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:43:17 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:43:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:43:17 INFO - Return code: 0 21:43:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:43:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:43:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:43:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:43:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --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:43:17 INFO - Using env: {'DISPLAY': ':0', 21:43:17 INFO - 'HOME': '/home/cltbld', 21:43:17 INFO - 'LANG': 'en_US.UTF-8', 21:43:17 INFO - 'LANGUAGE': 'en_US:en', 21:43:17 INFO - 'LOGNAME': 'cltbld', 21:43:17 INFO - 'MAIL': '/var/mail/cltbld', 21:43:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:43:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:17 INFO - 'MOZ_NO_REMOTE': '1', 21:43:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:43:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:17 INFO - 'NO_EM_RESTART': '1', 21:43:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:17 INFO - 'PWD': '/builds/slave/test', 21:43:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:43:17 INFO - 'SHELL': '/bin/bash', 21:43:17 INFO - 'SHLVL': '1', 21:43:17 INFO - 'TERM': 'linux', 21:43:17 INFO - 'TMOUT': '86400', 21:43:17 INFO - 'USER': 'cltbld', 21:43:17 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246', 21:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:17 INFO - '_': '/tools/buildbot/bin/python'} 21:43:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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:43:17 INFO - mozversion application_buildid: 20160922200659 21:43:17 INFO - mozversion application_changeset: 379da15420cb0a94e8421f63d70e028051d22d86 21:43:17 INFO - mozversion application_display_name: Nightly 21:43:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:43:17 INFO - mozversion application_name: Firefox 21:43:17 INFO - mozversion application_remotingname: firefox 21:43:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:43:17 INFO - mozversion application_vendor: Mozilla 21:43:17 INFO - mozversion application_version: 52.0a1 21:43:17 INFO - mozversion platform_buildid: 20160922200659 21:43:17 INFO - mozversion platform_changeset: 379da15420cb0a94e8421f63d70e028051d22d86 21:43:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:43:17 INFO - mozversion platform_version: 52.0a1 21:43:17 INFO - using testdate: 1474605797 21:43:17 INFO - actual date: 1474605797 21:43:17 INFO - starting webserver on 'localhost:53199' 21:43:17 INFO - SUITE-START | Running 2 tests 21:43:17 INFO - TEST-START | dromaeo_css 21:43:17 INFO - operating with platform_type : linux_ 21:43:17 INFO - Initialising browser for dromaeo_css test... 21:43:17 INFO - TEST-INFO | started process 1347 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg41ip5/profile http://localhost:53199/getInfo.html) 21:43:22 INFO - PROCESS | 1347 | __metrics Screen width/height:1600/1200 21:43:22 INFO - PROCESS | 1347 | colorDepth:24 21:43:22 INFO - PROCESS | 1347 | Browser inner width/height: 1024/697 21:43:22 INFO - PROCESS | 1347 | __metrics 21:43:23 INFO - PROCESS | 1347 | 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:43:23 INFO - PROCESS | 1347 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:43:23 INFO - PROCESS | 1347 | console.error: PushService: 21:43:23 INFO - PROCESS | 1347 | stateChangeProcessEnqueue: Error transitioning state 21:43:23 INFO - PROCESS | 1347 | UnknownError 21:43:24 INFO - TEST-INFO | 1347: exit 0 21:43:24 INFO - Browser initialized. 21:43:24 INFO - Running cycle 1/1 for dromaeo_css test... 21:43:24 INFO - TEST-INFO | started process 1428 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg41ip5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:43:36 INFO - PROCESS | 1428 | RSS: Main: 175210496 21:43:36 INFO - PROCESS | 1428 | 21:44:27 INFO - PROCESS | 1428 | 1474605867752 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:46:40 INFO - PROCESS | 1428 | Cycle 1(1): loaded http://localhost:53199/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53199/tests/dromaeo/cssquery-ext.html) 21:46:42 INFO - PROCESS | 1428 | RSS: Main: 328577024 21:46:42 INFO - PROCESS | 1428 | 21:49:44 INFO - PROCESS | 1428 | Cycle 1(1): loaded http://localhost:53199/tests/dromaeo/cssquery-ext.html (next: http://localhost:53199/tests/dromaeo/cssquery-jquery.html) 21:49:45 INFO - PROCESS | 1428 | RSS: Main: 273145856 21:49:45 INFO - PROCESS | 1428 | 21:52:47 INFO - PROCESS | 1428 | Cycle 1(1): loaded http://localhost:53199/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53199/tests/dromaeo/cssquery-mootools.html) 21:52:48 INFO - PROCESS | 1428 | RSS: Main: 219496448 21:52:48 INFO - PROCESS | 1428 | 21:55:50 INFO - PROCESS | 1428 | Cycle 1(1): loaded http://localhost:53199/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53199/tests/dromaeo/cssquery-prototype.html) 21:55:51 INFO - PROCESS | 1428 | RSS: Main: 260132864 21:55:51 INFO - PROCESS | 1428 | 21:58:54 INFO - PROCESS | 1428 | Cycle 1(1): loaded http://localhost:53199/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53199/tests/dromaeo/cssquery-yui.html) 21:58:54 INFO - PROCESS | 1428 | RSS: Main: 218894336 21:58:54 INFO - PROCESS | 1428 | 22:01:58 INFO - PROCESS | 1428 | Cycle 1(1): loaded http://localhost:53199/tests/dromaeo/cssquery-yui.html (next: http://localhost:53199/tests/dromaeo/cssquery-dojo.html) 22:01:58 INFO - PROCESS | 1428 | RSS: Main: 311349248 22:01:58 INFO - PROCESS | 1428 | 22:01:58 INFO - PROCESS | 1428 | __start_tp_report 22:01:58 INFO - PROCESS | 1428 | _x_x_mozilla_page_load 22:01:58 INFO - PROCESS | 1428 | _x_x_mozilla_page_load_details 22:01:58 INFO - PROCESS | 1428 | |i|pagename|runs| 22:01:58 INFO - PROCESS | 1428 | |0;dojo.html;2588.042424303008;2613.8431694098367;2642.273374821924;2648.417348183399;2663.8801253943557;4478.753668548228;4541.863744087675;4694.295855621658;4699.882502937428;4717.528247175285;4553.04386078923;4574.176648203331;4582.702124361914;4588.7476188809605;4646.25659894417;4292.613664770178;4318.179545886272;4348.956510434887;4370.3007518797;4381;4345.69580129391;4373.628241599468;4375.168717943597;4375.890602734941;4392.912141757163;4330.198913201068;4358.629516491102;4368.781560921957;4477.216487114758;4478.081993191404;4368.7815609219415;4414.690971631987;4423.623991960687;4426.77866106695;4457.24226881431;4204.915901681979;4242.830286788525;4251.447311849471;4265.466816647919;4314.482262128533;4605.976970115162;4610.400647915783;4626.745528995903;4663.393758811367;4739.644526660514;4940.25896115582;4974.204127339608;4975.577075948549;4983.0033993201505;5033.421156566968;4834.444038935532;4889.584385327216;4961.454240033593;4988.301637770715;5042;4703.623710103184;4708.223143181405;4708.576228139484;4769.976150119227;4774.259989701602;5100.081985242692;5146.408163061221;5148.382194136728;5148.794048238104;5183.118869792069;4698.248280275139;4737.3130896020575;4908.705477671351;4915.188993816052;4982.451930287665;4960.975195124001;5001.324821149171;5006.924896126561;5010.49895010496;5081.212412076134;4959.032988567267;4971.45314015458;4972.502749725071;4978.47725988766;5030;4753.215719406328;4941.45643979162;4948.90102197954;4949.406071271398;5010.574101201366;3925.371940489508;3928.508936382952;3948.3879998600264;3978.8607398740905;3985.900352491211;3505.158761897177;3508.017755028596;3549.3078849624076;3556.76881002734;3557.4130268505924;4303.33298338758;4311.267084595626;4312.310030395121;4313.6980411370905;4366.323219900921;4870.902581948341;4932.482089380635;4936.975315123474;4950.801967921243;4990.975045124824;4274.444322238089;4294.634956028773;4322.200392927319;4344.652427805768;4351.934720979188;4564.908701825945;4573.63410927125;4583.977080114578;4586.954130458719;4646.744429056367;4526.275795872644;4625.953740462687;4672.953270467252;4719.5752382285755;4726.1729197391005;4954.1330267202375;5048.293238946477;5060.645754797276;5075.568576670877;5077.568406685473;4697.389339385858;4731.69243999139;4736.8105275788585;4739.502352252964;4798.880027999328;4912.975435122801;4922.163232250455;4922.384701912261;4929.334539837076;4981.975090124526;4796.640251981185;4823.9517604824905;4953.925691114564;4956.330895329084;4963.726995015309;4856.902861942812;4856.975715121402;4857.878553036202;4898.093852637274;4918.532739389752;4869.269609558594;4869.829555965524;4879.194932836184;4882.194437917704;4949.6287778416045;4637.420322459692;4703.835365762318;4742.691725037862;4774.665773395968;4783.593394561502;4854.708717476963;4878.536539028786;4879.682820616648;4931.48219436954;4944.332515110414;4652.162610730101;4700.271457924027;4708.788104535236;4778.808847646055;4826.8069277228515 22:01:58 INFO - PROCESS | 1428 | |1;ext.html;564.6160610784665;566.6515093217671;571.4799532425491;572.1103683771734;574.287883025049;27970.720292797065;37852.81073594632;38990.22019559607;39245.41131883017;42351.78824105883;38729.8063509682;39071.80464097683;39084.80457597715;39101.804490977505;39142.21715565694;7728.884066739018;7876.488028278172;7887.250711182429;7890.763277101696;7894.80262993424;7736.806579835521;7752.379809615245;7832.882506762403;7833.882491762628;7838.372930165573;7817.101033381148;7859.6856125755185;7861.724839630614;7870.567118808463;7877;7765.922340776605;7808.297253247179;7809.336206422461;7819.804504887366;7833.060032796044;7773.611319434006;7839.608019598997;7853.803654908673;7857.960710196412;7864.882026769603;36519.63480365189;36652;36749.63250367516;36766.44850327247;36832.44751328706;2851.17315978366;2972.9419342597416;3134;3135.764817638686;3329.716974057208;27359.45281094387;27389.315267118283;27404.588931166047;27557;28431.573526397122;3361.428557145291;3441.1397150712323;3458.63684313146;3529.9647003529894;3571.982140089309;254283;256012;256401;256409.71795141092;256484;49964;50143;50258.246126308135;50267;50283;32036.839815800773;51512.22731659028;51587.742061290206;51611.741941290056;51713.224301635506;13076.411561479756;13625.795613065811;13675.794863077062;13693.657658558417;13716;10217.080462758238;10375.533101010473;10375.896241037493;10376.42929638862;10377.688669339932;17063.40278090261;17168.742468862976;17262.827371726373;17281.56796080108;17362.39231626887;6396.904046439307;6468.838279043061;6481.967590162019;6558;6740.797776066725;775.3719487215374;831.3224721851672;930.5626355612851;930.8743319651895;973.3816034866187;26823.329416764736;27808.44383112367;28011.193383026428;28327.858360708065;28392.148235552555;912.3932584831012;912.885889263842;915.2906497464518;928.8885333759993;956.8277710012126;14327;14411.495597654031;14580.343884525222;14861.405543778345;14895.553133406016;382036;385589;385947;387062;387233;47085.29372059422;47470;47486;47513.287300690514;47515;1073.660914555731;1137.1641843245043;1150.631797824688;1183.674489515373;1197.7125489882364;25163.87418062898;26097.608535871597;26719.866400667874;27097.322566935985;27189.72810271872;40121.39817902792;40870.59129408668;40984.18031639291;41083;41145;317922;318980;319742;320474;320962;24659.876700616383;25072.749272507772;25092.62361064622;25107.87446062831;25189.496210076064;39511;39765.60234397735;39805.203895922496;39880.800595996836;40069.59930400775;34027;34038.319233615686;34074.659253407146;34274.82862585671;34395.828020859735;33740.66259337375;33966.83016584901;34190.82904585461;34273.828630857686;34561;33408.49887251742;34332.31335373328;34467.65532344745;34515.48226776651;34695.479567805996;29075.418491629625;30782.69129815228;31281.687183127877;31714.682853172097;31931;24658;24715.258542243762;24734.134305299227;24927;25186.874065629556 22:01:58 INFO - PROCESS | 1428 | |2;jquery.html;337.92153481780986;343.1267424404888;360.7799242462099;363.9526861508003;380.391373801917;12168.391580420977;13248;16695.332186712516;16923.153842307896;17442.91278543609;17028.574285642866;17263.74104388435;17313.91343043286;17364.56588585286;17445.91277043613;3746.569144548371;4934.999999999991;5127.692338459686;5148.227765835118;5155.407128180251;3664.13892735207;3840;4995.325631039817;5130.51260130287;5698.259226300575;5932.762689492415;6068.3021452533085;6070.635761854281;6075.908861367061;6080.391960803929;5746.25298711169;5838.620489668158;5878.676672782995;5884.6469211847425;6061.636301821883;5144.639875208737;5161.896762064737;5171.276021357013;5177.352830896138;5179.171332586768;19692.40922772319;20233.79766202334;20294;20533.38399848007;20618.896905515525;1816.536783120309;1956.45219338584;2017.1023894367013;2046.7310267634014;2557.5524283250356;12528;12857.871421285761;13176.472941082346;13315.800262996063;13743.725125497534;1726.48205538338;1730.9048002359866;1758.5955230297104;1758.9032603206817;1792.3726695656417;67030.3296967029;67787.9999999995;68331.65834170848;69986;70652.2934770656;9284.442933423881;9344.95327523358;9476.95261523688;9554.665586704432;9821.017898210264;13149.21104733719;13155.934220329029;13166.078374513883;13166.144200626928;13168.407421666048;6906.930930690729;7046.1192350956135;7194.424446044304;7302;7303.488755787044;6677.198736151596;6768.661566921634;6907.516473846783;6930.44556435484;6971.407430368374;5960.970195148997;6031.788887389008;6047.455728984412;6057.666828324396;6076.270847498328;1615.7334039883522;1655.0751833042898;1717.6135369542003;1724.7326664367042;1780.5815633326158;763.9656215470317;782.6243403166512;820.6594263380749;834.3116928533984;861.8793368928355;17057.658846822993;17379;17484;18034.188461519265;18070;789.8815177723387;807.1323327423044;809.8987626546682;818.1491249100868;821.5850995247362;9832.9016709832;9983.600655973825;10201.846972295421;10286.845697314546;10298;359965;362140;365419;365487;383906;42856.357154642705;44010.33984490235;52107;61396.38603613907;63745;900.7027680865299;906.918377346042;924.7410724997012;929.4144688845984;931.2549960031947;15361.385544578092;15436.996595221275;15758.842411575737;15867.127307998171;16147.03117812935;35198.824005879804;35408.29183416266;35701.28597427985;36550;36816.44775328426;45512;46727;46988.295175573076;47553.76223118862;50845.74577127091;6415.326390729093;6506.544541882023;6521.315261897433;7146.642667866482;7781.105172904962;10223.795524089328;10858.619948301772;10986.340819550853;11099.500522476346;11295.943520282346;9272.490013049348;9286.257099432165;9469.47917864524;9547.952260238655;10242.897571024194;9271.860922086309;9327.393719408217;9394.67118650844;9411.341206115507;9524.571394287412;9633.759156020875;9750.414975101234;9873.358231714916;10482.790344193227;10578.735531611772;6606.933930660632;6615.272320044887;6703.195616525853;6798.218204906499;7161.498695091502;7352.77941661751;8881.911180888366;9583.904160958302;9954.502274886081;10183.18534517222 22:01:58 INFO - PROCESS | 1428 | |3;mootools.html;1295.9092863499552;1359.9388027538762;1360.1906865415074;1561.117968347884;1594.0834020438244;6633.2703402625675;6812.523123381366;7204.495685302031;7837.686492540299;7845.09781375142;7230.385417239541;7501.662425190866;7502.512336698111;7650.311471967521;7669.424793140509;2117.4071260047212;2131.8488016471088;2134.2210093315985;2164.8159906407973;2167.7073595064703;1746.4760571828401;1813.2837529175968;2058.670612701968;2088.7284652995168;2124.415785658941;2077.366403247009;2155.2348916134783;2160.600288946542;2161.859479133859;2179.9346019619356;2068.606964676722;2072.5440403111365;2086.9895650521726;2130.1905275995177;2135.3914134471756;2110.883901385423;2110.9894450527804;2117.502386939068;2122.7983341582526;2125.6173888700027;7773.416993725493;7835.804104897366;7836.294733473957;7838.9216107838765;7839.725609603636;4484.865454036384;4513.323001549761;4589.311603259504;4591.517890621503;4642;2828.589854471111;2940.6765255821842;2958.068208514311;2984.3732816108645;2989.9402011959637;877.978050548735;883.5847151838625;889.5196593839319;893.4907102951321;895.7671005538542;10397.428141452217;10400.479976001245;10414.739631509197;10435.739106522322;10456.634017809416;3853.614638536124;3988.2621714982242;4182.937255941163;4184.581541845791;4211.052513184509;8956.238719708768;8964.462132271954;8996.685116020908;9005.414648047856;9007.09929007094;4621.676482646182;4622.14490319292;4623.16782979067;4626.976865115653;4631.907361852744;4134.669226461876;4217.472815898012;4227.281362168439;4231.090315582164;4232.343986682069;3477.94783078254;3541.610422853484;3557.324108419392;3591.28174365128;3652.8904132876055;1020.6886899495726;1087.342157994402;1133.3313345126414;1181.9704507387212;1187.4300335838673;764.5145332713766;807.7253733730442;812.9837403251902;829.1459796409707;835.8955130608674;4924.655274130777;5132.255822905658;5145.614078944094;5159.1229490986625;5382;829.4194064155115;857.6869442653511;862.4997501449144;864.3344624638993;899.9100089990949;3448.620651728308;3553.484744712054;3592.946105808415;3608.891733248007;3691.90770230741;8807.735767926972;8828.42615230008;9083.772905677279;9149.130832570896;9245.861312080324;9127.361084724138;9140.177384035336;9197.494137822485;9202.447853128833;9211.63153473867;6895.275996020446;7014.649267536603;7017.754378596725;7041.471889608198;7095.148582170069;5129.256257842667;5270.683758974474;5297.682139071668;5300.125479296027;5346.679199248058;8509.064002959793;8716.128387161232;8742.34432417584;8794.692185773467;8827.337949653931;8237.50574965504;8433.704820331259;8742.431741936754;8871.423357481743;8925.062868398983;6559.311272316338;6718.428933540704;6722.092517510074;6724.394804467622;6728.899066513908;7236.96381518089;7336.046313979148;7368.115826100902;7415.295546923034;7440.962795185989;4904.558589726942;6495.447886929664;6513.478921686159;6556.737730490727;6611.239707433514;6435.4529864962005;6444.291127976013;6457.741690332522;6464.676766161767;6497.577657452251;6117.357677443804;6203.658798766111;6209.068639704081;6209.379062093754;6209.658468784261;4440.555944405533;4448.7108337957925;4504.729716217037;4544.840930567547;4605.976970115128;4569.405977222939;4588.655850811269;4624;4649.1863923812525;4764.976175119103 22:01:58 INFO - PROCESS | 1428 | |4;prototype.html;291.92701824543866;292.31452244486684;297.86000579727533;302.34994761263283;303.8617429069773;4792.83225087122;4820.469748327686;4831.806727730886;4841.685290456122;4932.80268789248;4583.633309335253;4637.382841383726;4736.8578942631775;4742.596879265258;4848.418189817218;3777.6411240932207;3794.829232684536;3801.7909015004166;3811.313963486571;3812.9046773830737;3770.547534295875;3796.8291426885858;3799.7530160539613;3804.657580817726;3821.9808900955454;3555.857765689382;3673.687736542397;3704.499892514503;3786.678132358753;3796.4495148203496;3776.584575696671;3784.432335149722;3792.2984247914087;3792.307461702195;3803.9429408558894;3323.900282991514;3327.45097058985;3332;3345.4312766829453;3356.865725370982;4517.170545928904;4696.319033740088;4713.905721885543;4714.646401519865;4717.882052948669;4250.702450828443;4253.659707223414;4278.577138289356;4285.828566857322;4324.459442569679;5027.622928280357;5027.84916452507;5033.874153146201;5041.924371134399;5075.720835354054;3567.8037707926055;3580.5882323532865;3583.229605634801;3598.298331825295;3605.1888325126897;5149.470200655899;5225.42520322768;5259.526642602184;5268.051750684837;5271.367435907678;3617.0595645132457;3639.945400818937;3643.143861192618;3643.4716966039778;3654.8720794772057;3703.9074023149096;3713.090292878262;3717.646823551758;3725.4166979033803;3743.12036671382;4172.749635021908;4236.279832428495;4289.206496798102;4304.096139810675;4304.794657495906;5004.9499505005215;5016.44819069902;5038.470960549163;5060.822871199491;5080.618953578496;1259.2696236182992;1283.1402960016624;1289.432649634158;1293.6895145165092;1297.2540789046147;816.7304789419477;837.024866031067;851.2806678356778;858.9441686290371;878.512425603796;4446.822127114944;4453.799579018952;4454.1537107950035;4458.576435238647;4461.13007963444;5139.563137133386;5161.329027226437;5173.741312934339;5190.92213616796;5209.505097015777;4292.720973136736;4464.192002239099;4482.7534485602955;4492.865214043519;4517.051419201939;2713.7014928357867;2732.6037724529833;2746.807723459339;2747.9420423136835;2769.8892044318;4909.116359055404;4927.211646136601;4935.728534930613;4945.109880221595;4950.663320903408;5144.717040562805;5251.711155886386;5287.524122828887;5290.788368465218;5296;4898.142825005681;4899.461059283476;4909.680870743462;4910.312556242057;4920.3111564381015;5150.330457040561;5151.6908985461;5177.524405544402;5177.534021938043;5205.6356055075785;4873.488283730299;4996.725180114983;5009.223570346602;5044.319016932667;5045.394552653705;4828.61371090319;4831.371921650163;4833.637477189154;4850.466448690711;4873.171560834738;3641.908952276214;3643.0892276930767;3665.3585622516484;3666.7983260920905;3670.210904655405;4891.80432782699;5045.839456924855;5100.566451851634;5105.540501354896;5163.812323165618;4955.65310428281;4968.677035992649;4992.450830408579;4998.900021999612;4999.47505511916;4978.726170060681;4988;4988.376452943382;5012.548870601663;5023.296738456692;4699.953000469952;4932.679375840559;4988.850334489971;5008.9499105009945;5011.323471331323;4840.661153719206;4935.975320123376;4971.229459433794;5093.612747522856;5113.181890897587;4983.227599722049;4987.551120399036;4993.40343021634;4994.2508623706735;5019.924701129559 22:01:58 INFO - PROCESS | 1428 | |5;yui.html;601.9428154325338;626.7493002798877;655.1744801550049;716.4411758828113;717.3973861955958;12459.190152640083;13020;13020.088593798439;13026.73946521069;13034.869651303485;11975.580854670088;11983.101267404936;11983.400829958511;11988;11992.64022079339;592.9318128415223;608.5679167790873;714.7462650758984;724.8514054618804;739.7669734033763;724.2431658916445;732.3847967707125;732.7874916274274;733.8752412089957;733.9302766237199;780.1963977103593;781.2578050851686;781.613101514751;782.941279404047;782.949108307961;624.9750009999594;641.3073880209363;644.1079105439001;646.3633321178635;775.7866586688649;476.3878416235156;554.3763266325384;569.5756661287368;572.9799457018985;574.4973148495067;11723.706907327385;11749.588764393291;11762.353070581115;11767.529298828036;11768.352740599265;12296.323702196376;12425.813612795815;12628.621141365773;13207;13376.933115334361;11466.885331146665;11585.47865346053;11788.587399441065;11824.172307938448;12440.066994975414;3605.693516051139;3637.7271704621994;3661.450782382664;3747.2130852521004;3986.54154772202;61834.31805150513;62349.37650623526;62490.68754656243;62551.11937686851;62964;9592.376495527908;9626;9675;9835.950820245853;10313.329633573794;18021.909890450726;18033.18850651724;18046.458606241835;18059.638807223782;18059.819401806075;6192.535559833031;6313.526485513605;6827.385535301747;6848.212455567574;7159.534630249017;3233.9838300808347;3647.252313275772;3691.6676205216254;3716.9070773230887;3733.272011957642;2595.0787470448095;2718.0350975403844;2719.5240832854565;2721.3060669529145;2722.128918746022;466.56143225368044;478.1799214347341;479.24040395972077;485.20426500539145;486.23418116466706;441.8188542697479;446.7297285142507;450.80390030336906;463.24953575207707;476.05978193069103;7283.7450689225625;7657.119431265364;7783.610819459004;7889.8816517752275;8061.750428683564;405.94519739835334;495.35603715170134;499.2237071354007;510.4232217594095;574.3768011707261;3546.840392182358;3568.1971556399603;3639.9090022749115;3800.6959443244473;3802.08749900022;55968;57334.7133264331;72832.63583682153;76789.61605191938;77109.61445192738;75117;77422.61288693521;77743.61128194323;77909.2209077902;79302.20697792948;66094;69845.65077174582;70254.64872675706;71042.64478677677;72121.6393918027;7860.371170306247;7886.293442082212;8049.597520124087;8268.586570671321;8374.246317831181;30173.133552312494;38114.42828357632;42981;44136.55863441453;44418.55581444144;56855.147172791614;57455;61604.69197653983;63191;64678.353216467236;10909.18181136402;10995.780084398428;11104.61133860311;11117.055050320814;11373.090152787923;10089.394636321844;10286.691399258034;10677.46612669379;11246.268992515461;11790.764184716087;9503.429794212369;10091.091801737584;11374.64881507808;11624.5350185995;11813.350265735124;10063.39619622825;10143.949280253551;10586.153107751516;10703.304285221435;10972.561097556327;11058.447077646246;11102.61140860066;11259.324440533594;11535.538578456768;11704.94147529257;8600.655973760979;8686;8841.646334146819;8878.156575125353;9244.352895297265;8298.543580103153;8938.1508756668;9013.864792027995;9090.00069946151;9178.816423671356 22:01:58 INFO - PROCESS | 1428 | __end_tp_report 22:01:58 INFO - PROCESS | 1428 | __start_cc_report 22:01:58 INFO - PROCESS | 1428 | _x_x_mozilla_cycle_collect,1393 22:01:58 INFO - PROCESS | 1428 | __end_cc_report 22:01:58 INFO - PROCESS | 1428 | __startTimestamp1474606918334__endTimestamp 22:01:58 INFO - PROCESS | 1428 | 22:01:58 INFO - PROCESS | 1428 | ------- Summary: start ------- 22:01:58 INFO - PROCESS | 1428 | Number of tests: 6 22:01:58 INFO - PROCESS | 1428 | 22:01:58 INFO - PROCESS | 1428 | [#0] dojo.html Cycles:165 Average:4603.25 Median:4719.58 stddev:489.28 (10.4%) stddev-sans-first:464.54 22:01:58 INFO - PROCESS | 1428 | Values: 2588.0 2613.8 2642.3 2648.4 2663.9 4478.8 4541.9 4694.3 4699.9 4717.5 4553.0 4574.2 4582.7 4588.7 4646.3 4292.6 4318.2 4349.0 4370.3 4381.0 4345.7 4373.6 4375.2 4375.9 4392.9 4330.2 4358.6 4368.8 4477.2 4478.1 4368.8 4414.7 4423.6 4426.8 4457.2 4204.9 4242.8 4251.4 4265.5 4314.5 4606.0 4610.4 4626.7 4663.4 4739.6 4940.3 4974.2 4975.6 4983.0 5033.4 4834.4 4889.6 4961.5 4988.3 5042.0 4703.6 4708.2 4708.6 4770.0 4774.3 5100.1 5146.4 5148.4 5148.8 5183.1 4698.2 4737.3 4908.7 4915.2 4982.5 4961.0 5001.3 5006.9 5010.5 5081.2 4959.0 4971.5 4972.5 4978.5 5030.0 4753.2 4941.5 4948.9 4949.4 5010.6 3925.4 3928.5 3948.4 3978.9 3985.9 3505.2 3508.0 3549.3 3556.8 3557.4 4303.3 4311.3 4312.3 4313.7 4366.3 4870.9 4932.5 4937.0 4950.8 4991.0 4274.4 4294.6 4322.2 4344.7 4351.9 4564.9 4573.6 4584.0 4587.0 4646.7 4526.3 4626.0 4673.0 4719.6 4726.2 4954.1 5048.3 5060.6 5075.6 5077.6 4697.4 4731.7 4736.8 4739.5 4798.9 4913.0 4922.2 4922.4 4929.3 4982.0 4796.6 4824.0 4953.9 4956.3 4963.7 4856.9 4857.0 4857.9 4898.1 4918.5 4869.3 4869.8 4879.2 4882.2 4949.6 4637.4 4703.8 4742.7 4774.7 4783.6 4854.7 4878.5 4879.7 4931.5 4944.3 4652.2 4700.3 4708.8 4778.8 4826.8 22:01:58 INFO - PROCESS | 1428 | 22:01:58 INFO - PROCESS | 1428 | [#1] ext.html Cycles:180 Average:46581.94 Median:3550.97 stddev:85543.35 (2409.0%) stddev-sans-first:85713.55 22:01:58 INFO - PROCESS | 1428 | Values: 564.6 566.7 571.5 572.1 574.3 27970.7 37852.8 38990.2 39245.4 42351.8 38729.8 39071.8 39084.8 39101.8 39142.2 7728.9 7876.5 7887.3 7890.8 7894.8 7736.8 7752.4 7832.9 7833.9 7838.4 7817.1 7859.7 7861.7 7870.6 7877.0 7765.9 7808.3 7809.3 7819.8 7833.1 7773.6 7839.6 7853.8 7858.0 7864.9 36519.6 36652.0 36749.6 36766.4 36832.4 2851.2 2972.9 3134.0 3135.8 3329.7 27359.5 27389.3 27404.6 27557.0 28431.6 3361.4 3441.1 3458.6 3530.0 3572.0 254283.0 256012.0 256401.0 256409.7 256484.0 49964.0 50143.0 50258.2 50267.0 50283.0 32036.8 51512.2 51587.7 51611.7 51713.2 13076.4 13625.8 13675.8 13693.7 13716.0 10217.1 10375.5 10375.9 10376.4 10377.7 17063.4 17168.7 17262.8 17281.6 17362.4 6396.9 6468.8 6482.0 6558.0 6740.8 775.4 831.3 930.6 930.9 973.4 26823.3 27808.4 28011.2 28327.9 28392.1 912.4 912.9 915.3 928.9 956.8 14327.0 14411.5 14580.3 14861.4 14895.6 382036.0 385589.0 385947.0 387062.0 387233.0 47085.3 47470.0 47486.0 47513.3 47515.0 1073.7 1137.2 1150.6 1183.7 1197.7 25163.9 26097.6 26719.9 27097.3 27189.7 40121.4 40870.6 40984.2 41083.0 41145.0 317922.0 318980.0 319742.0 320474.0 320962.0 24659.9 25072.7 25092.6 25107.9 25189.5 39511.0 39765.6 39805.2 39880.8 40069.6 34027.0 34038.3 34074.7 34274.8 34395.8 33740.7 33966.8 34190.8 34273.8 34561.0 33408.5 34332.3 34467.7 34515.5 34695.5 29075.4 30782.7 31281.7 31714.7 31931.0 24658.0 24715.3 24734.1 24927.0 25186.9 22:01:58 INFO - PROCESS | 1428 | 22:01:58 INFO - PROCESS | 1428 | [#2] jquery.html Cycles:180 Average:22841.57 Median:5137.58 stddev:60334.68 (1174.4%) stddev-sans-first:60480.27 22:01:58 INFO - PROCESS | 1428 | Values: 337.9 343.1 360.8 364.0 380.4 12168.4 13248.0 16695.3 16923.2 17442.9 17028.6 17263.7 17313.9 17364.6 17445.9 3746.6 4935.0 5127.7 5148.2 5155.4 3664.1 3840.0 4995.3 5130.5 5698.3 5932.8 6068.3 6070.6 6075.9 6080.4 5746.3 5838.6 5878.7 5884.6 6061.6 5144.6 5161.9 5171.3 5177.4 5179.2 19692.4 20233.8 20294.0 20533.4 20618.9 1816.5 1956.5 2017.1 2046.7 2557.6 12528.0 12857.9 13176.5 13315.8 13743.7 1726.5 1730.9 1758.6 1758.9 1792.4 67030.3 67788.0 68331.7 69986.0 70652.3 9284.4 9345.0 9477.0 9554.7 9821.0 13149.2 13155.9 13166.1 13166.1 13168.4 6906.9 7046.1 7194.4 7302.0 7303.5 6677.2 6768.7 6907.5 6930.4 6971.4 5961.0 6031.8 6047.5 6057.7 6076.3 1615.7 1655.1 1717.6 1724.7 1780.6 764.0 782.6 820.7 834.3 861.9 17057.7 17379.0 17484.0 18034.2 18070.0 789.9 807.1 809.9 818.1 821.6 9832.9 9983.6 10201.8 10286.8 10298.0 359965.0 362140.0 365419.0 365487.0 383906.0 42856.4 44010.3 52107.0 61396.4 63745.0 900.7 906.9 924.7 929.4 931.3 15361.4 15437.0 15758.8 15867.1 16147.0 35198.8 35408.3 35701.3 36550.0 36816.4 45512.0 46727.0 46988.3 47553.8 50845.7 6415.3 6506.5 6521.3 7146.6 7781.1 10223.8 10858.6 10986.3 11099.5 11295.9 9272.5 9286.3 9469.5 9548.0 10242.9 9271.9 9327.4 9394.7 9411.3 9524.6 9633.8 9750.4 9873.4 10482.8 10578.7 6606.9 6615.3 6703.2 6798.2 7161.5 7352.8 8881.9 9583.9 9954.5 10183.2 22:01:58 INFO - PROCESS | 1428 | 22:01:58 INFO - PROCESS | 1428 | [#3] mootools.html Cycles:180 Average:5006.08 Median:5214.90 stddev:2776.67 (53.2%) stddev-sans-first:2770.46 22:01:58 INFO - PROCESS | 1428 | Values: 1295.9 1359.9 1360.2 1561.1 1594.1 6633.3 6812.5 7204.5 7837.7 7845.1 7230.4 7501.7 7502.5 7650.3 7669.4 2117.4 2131.8 2134.2 2164.8 2167.7 1746.5 1813.3 2058.7 2088.7 2124.4 2077.4 2155.2 2160.6 2161.9 2179.9 2068.6 2072.5 2087.0 2130.2 2135.4 2110.9 2111.0 2117.5 2122.8 2125.6 7773.4 7835.8 7836.3 7838.9 7839.7 4484.9 4513.3 4589.3 4591.5 4642.0 2828.6 2940.7 2958.1 2984.4 2989.9 878.0 883.6 889.5 893.5 895.8 10397.4 10400.5 10414.7 10435.7 10456.6 3853.6 3988.3 4182.9 4184.6 4211.1 8956.2 8964.5 8996.7 9005.4 9007.1 4621.7 4622.1 4623.2 4627.0 4631.9 4134.7 4217.5 4227.3 4231.1 4232.3 3477.9 3541.6 3557.3 3591.3 3652.9 1020.7 1087.3 1133.3 1182.0 1187.4 764.5 807.7 813.0 829.1 835.9 4924.7 5132.3 5145.6 5159.1 5382.0 829.4 857.7 862.5 864.3 899.9 3448.6 3553.5 3592.9 3608.9 3691.9 8807.7 8828.4 9083.8 9149.1 9245.9 9127.4 9140.2 9197.5 9202.4 9211.6 6895.3 7014.6 7017.8 7041.5 7095.1 5129.3 5270.7 5297.7 5300.1 5346.7 8509.1 8716.1 8742.3 8794.7 8827.3 8237.5 8433.7 8742.4 8871.4 8925.1 6559.3 6718.4 6722.1 6724.4 6728.9 7237.0 7336.0 7368.1 7415.3 7441.0 4904.6 6495.4 6513.5 6556.7 6611.2 6435.5 6444.3 6457.7 6464.7 6497.6 6117.4 6203.7 6209.1 6209.4 6209.7 4440.6 4448.7 4504.7 4544.8 4606.0 4569.4 4588.7 4624.0 4649.2 4765.0 22:01:58 INFO - PROCESS | 1428 | 22:01:58 INFO - PROCESS | 1428 | [#4] prototype.html Cycles:180 Average:4178.65 Median:4806.65 stddev:1207.95 (25.1%) stddev-sans-first:1175.59 22:01:58 INFO - PROCESS | 1428 | Values: 291.9 292.3 297.9 302.3 303.9 4792.8 4820.5 4831.8 4841.7 4932.8 4583.6 4637.4 4736.9 4742.6 4848.4 3777.6 3794.8 3801.8 3811.3 3812.9 3770.5 3796.8 3799.8 3804.7 3822.0 3555.9 3673.7 3704.5 3786.7 3796.4 3776.6 3784.4 3792.3 3792.3 3803.9 3323.9 3327.5 3332.0 3345.4 3356.9 4517.2 4696.3 4713.9 4714.6 4717.9 4250.7 4253.7 4278.6 4285.8 4324.5 5027.6 5027.8 5033.9 5041.9 5075.7 3567.8 3580.6 3583.2 3598.3 3605.2 5149.5 5225.4 5259.5 5268.1 5271.4 3617.1 3639.9 3643.1 3643.5 3654.9 3703.9 3713.1 3717.6 3725.4 3743.1 4172.7 4236.3 4289.2 4304.1 4304.8 5004.9 5016.4 5038.5 5060.8 5080.6 1259.3 1283.1 1289.4 1293.7 1297.3 816.7 837.0 851.3 858.9 878.5 4446.8 4453.8 4454.2 4458.6 4461.1 5139.6 5161.3 5173.7 5190.9 5209.5 4292.7 4464.2 4482.8 4492.9 4517.1 2713.7 2732.6 2746.8 2747.9 2769.9 4909.1 4927.2 4935.7 4945.1 4950.7 5144.7 5251.7 5287.5 5290.8 5296.0 4898.1 4899.5 4909.7 4910.3 4920.3 5150.3 5151.7 5177.5 5177.5 5205.6 4873.5 4996.7 5009.2 5044.3 5045.4 4828.6 4831.4 4833.6 4850.5 4873.2 3641.9 3643.1 3665.4 3666.8 3670.2 4891.8 5045.8 5100.6 5105.5 5163.8 4955.7 4968.7 4992.5 4998.9 4999.5 4978.7 4988.0 4988.4 5012.5 5023.3 4700.0 4932.7 4988.9 5008.9 5011.3 4840.7 4936.0 4971.2 5093.6 5113.2 4983.2 4987.6 4993.4 4994.3 5019.9 22:01:58 INFO - PROCESS | 1428 | 22:01:58 INFO - PROCESS | 1428 | [#5] yui.html Cycles:180 Average:16158.88 Median:485.72 stddev:22163.07 (4562.9%) stddev-sans-first:22194.46 22:01:58 INFO - PROCESS | 1428 | Values: 601.9 626.7 655.2 716.4 717.4 12459.2 13020.0 13020.1 13026.7 13034.9 11975.6 11983.1 11983.4 11988.0 11992.6 592.9 608.6 714.7 724.9 739.8 724.2 732.4 732.8 733.9 733.9 780.2 781.3 781.6 782.9 782.9 625.0 641.3 644.1 646.4 775.8 476.4 554.4 569.6 573.0 574.5 11723.7 11749.6 11762.4 11767.5 11768.4 12296.3 12425.8 12628.6 13207.0 13376.9 11466.9 11585.5 11788.6 11824.2 12440.1 3605.7 3637.7 3661.5 3747.2 3986.5 61834.3 62349.4 62490.7 62551.1 62964.0 9592.4 9626.0 9675.0 9836.0 10313.3 18021.9 18033.2 18046.5 18059.6 18059.8 6192.5 6313.5 6827.4 6848.2 7159.5 3234.0 3647.3 3691.7 3716.9 3733.3 2595.1 2718.0 2719.5 2721.3 2722.1 466.6 478.2 479.2 485.2 486.2 441.8 446.7 450.8 463.2 476.1 7283.7 7657.1 7783.6 7889.9 8061.8 405.9 495.4 499.2 510.4 574.4 3546.8 3568.2 3639.9 3800.7 3802.1 55968.0 57334.7 72832.6 76789.6 77109.6 75117.0 77422.6 77743.6 77909.2 79302.2 66094.0 69845.7 70254.6 71042.6 72121.6 7860.4 7886.3 8049.6 8268.6 8374.2 30173.1 38114.4 42981.0 44136.6 44418.6 56855.1 57455.0 61604.7 63191.0 64678.4 10909.2 10995.8 11104.6 11117.1 11373.1 10089.4 10286.7 10677.5 11246.3 11790.8 9503.4 10091.1 11374.6 11624.5 11813.4 10063.4 10143.9 10586.2 10703.3 10972.6 11058.4 11102.6 11259.3 11535.5 11704.9 8600.7 8686.0 8841.6 8878.2 9244.4 8298.5 8938.2 9013.9 9090.0 9178.8 22:01:58 INFO - PROCESS | 1428 | -------- Summary: end -------- 22:01:58 INFO - PROCESS | 1428 | 22:01:59 INFO - TEST-INFO | 1428: exit 0 22:01:59 INFO - TEST-OK | dromaeo_css | took 1121537ms 22:01:59 INFO - TEST-START | kraken 22:01:59 INFO - operating with platform_type : linux_ 22:01:59 INFO - Initialising browser for kraken test... 22:01:59 INFO - TEST-INFO | started process 1736 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpByhEBS/profile http://localhost:53199/getInfo.html) 22:02:03 INFO - PROCESS | 1736 | __metrics Screen width/height:1600/1200 22:02:03 INFO - PROCESS | 1736 | colorDepth:24 22:02:03 INFO - PROCESS | 1736 | Browser inner width/height: 1024/697 22:02:03 INFO - PROCESS | 1736 | __metrics 22:02:04 INFO - PROCESS | 1736 | 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" 22:02:04 INFO - PROCESS | 1736 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:02:05 INFO - PROCESS | 1736 | console.error: PushService: 22:02:05 INFO - PROCESS | 1736 | stateChangeProcessEnqueue: Error transitioning state 22:02:05 INFO - PROCESS | 1736 | UnknownError 22:02:05 INFO - TEST-INFO | 1736: exit 0 22:02:05 INFO - Browser initialized. 22:02:05 INFO - Running cycle 1/1 for kraken test... 22:02:05 INFO - TEST-INFO | started process 1822 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpByhEBS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:02:17 INFO - PROCESS | 1822 | RSS: Main: 175259648 22:02:17 INFO - PROCESS | 1822 | 22:02:20 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53199/tests/kraken/driver.html?testName=audio-beat-detection) 22:02:21 INFO - PROCESS | 1822 | RSS: Main: 234409984 22:02:21 INFO - PROCESS | 1822 | 22:02:24 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53199/tests/kraken/driver.html?testName=audio-dft) 22:02:25 INFO - PROCESS | 1822 | RSS: Main: 218394624 22:02:25 INFO - PROCESS | 1822 | 22:02:28 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53199/tests/kraken/driver.html?testName=audio-fft) 22:02:28 INFO - PROCESS | 1822 | RSS: Main: 300462080 22:02:28 INFO - PROCESS | 1822 | 22:02:31 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53199/tests/kraken/driver.html?testName=audio-oscillator) 22:02:31 INFO - PROCESS | 1822 | RSS: Main: 210317312 22:02:31 INFO - PROCESS | 1822 | 22:02:34 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53199/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:02:34 INFO - PROCESS | 1822 | RSS: Main: 213291008 22:02:34 INFO - PROCESS | 1822 | 22:02:37 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53199/tests/kraken/driver.html?testName=imaging-darkroom) 22:02:38 INFO - PROCESS | 1822 | RSS: Main: 250105856 22:02:38 INFO - PROCESS | 1822 | 22:02:41 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53199/tests/kraken/driver.html?testName=imaging-desaturate) 22:02:42 INFO - PROCESS | 1822 | RSS: Main: 244428800 22:02:42 INFO - PROCESS | 1822 | 22:02:45 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53199/tests/kraken/driver.html?testName=json-parse-financial) 22:02:46 INFO - PROCESS | 1822 | RSS: Main: 246591488 22:02:46 INFO - PROCESS | 1822 | 22:02:47 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53199/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:02:48 INFO - PROCESS | 1822 | RSS: Main: 210849792 22:02:48 INFO - PROCESS | 1822 | 22:02:50 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53199/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:02:50 INFO - PROCESS | 1822 | RSS: Main: 217706496 22:02:50 INFO - PROCESS | 1822 | 22:02:52 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53199/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:02:53 INFO - PROCESS | 1822 | RSS: Main: 222187520 22:02:53 INFO - PROCESS | 1822 | 22:02:56 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53199/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:02:57 INFO - PROCESS | 1822 | RSS: Main: 217522176 22:02:57 INFO - PROCESS | 1822 | 22:03:00 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53199/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:03:00 INFO - PROCESS | 1822 | RSS: Main: 220897280 22:03:00 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | Cycle 1(1): loaded http://localhost:53199/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53199/tests/kraken/driver.html?testName=ai-astar) 22:03:02 INFO - PROCESS | 1822 | RSS: Main: 273010688 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | __start_tp_report 22:03:02 INFO - PROCESS | 1822 | _x_x_mozilla_page_load 22:03:02 INFO - PROCESS | 1822 | _x_x_mozilla_page_load_details 22:03:02 INFO - PROCESS | 1822 | |i|pagename|runs| 22:03:02 INFO - PROCESS | 1822 | |0;ai-astar;112;114;114;114;113;114;114;114;113;112 22:03:02 INFO - PROCESS | 1822 | |1;audio-beat-detection;156;127;124;165;125;131;125;123;181;125 22:03:02 INFO - PROCESS | 1822 | |2;audio-dft;163;164;163;163;162;161;163;161;162;164 22:03:02 INFO - PROCESS | 1822 | |3;audio-fft;76;77;76;114;76;78;77;76;110;79 22:03:02 INFO - PROCESS | 1822 | |4;audio-oscillator;86;83;126;83;85;83;105;84;84;115 22:03:02 INFO - PROCESS | 1822 | |5;imaging-gaussian-blur;116;117;116;117;117;117;117;118;118;117 22:03:02 INFO - PROCESS | 1822 | |6;imaging-darkroom;148;147;146;147;147;147;146;147;146;147 22:03:02 INFO - PROCESS | 1822 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;94;94 22:03:02 INFO - PROCESS | 1822 | |8;json-parse-financial;65;63;64;64;66;66;66;65;66;65 22:03:02 INFO - PROCESS | 1822 | |9;json-stringify-tinderbox;66;67;67;67;67;67;66;66;67;66 22:03:02 INFO - PROCESS | 1822 | |10;stanford-crypto-aes;84;84;82;82;83;83;91;83;82;82 22:03:02 INFO - PROCESS | 1822 | |11;stanford-crypto-ccm;133;130;131;134;132;134;135;133;222;138 22:03:02 INFO - PROCESS | 1822 | |12;stanford-crypto-pbkdf2;174;176;174;173;176;176;176;184;174;175 22:03:02 INFO - PROCESS | 1822 | |13;stanford-crypto-sha256-iterative;69;72;71;70;69;69;69;69;69;66 22:03:02 INFO - PROCESS | 1822 | __end_tp_report 22:03:02 INFO - PROCESS | 1822 | __start_cc_report 22:03:02 INFO - PROCESS | 1822 | _x_x_mozilla_cycle_collect,1939 22:03:02 INFO - PROCESS | 1822 | __end_cc_report 22:03:02 INFO - PROCESS | 1822 | __startTimestamp1474606982828__endTimestamp 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | ------- Summary: start ------- 22:03:02 INFO - PROCESS | 1822 | Number of tests: 14 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:0.84 (0.7%) stddev-sans-first:0.73 22:03:02 INFO - PROCESS | 1822 | Values: 112.0 114.0 114.0 114.0 113.0 114.0 114.0 114.0 113.0 112.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:129.00 stddev:21.08 (16.3%) stddev-sans-first:21.35 22:03:02 INFO - PROCESS | 1822 | Values: 156.0 127.0 124.0 165.0 125.0 131.0 125.0 123.0 181.0 125.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#2] audio-dft Cycles:10 Average:162.60 Median:163.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 22:03:02 INFO - PROCESS | 1822 | Values: 163.0 164.0 163.0 163.0 162.0 161.0 163.0 161.0 162.0 164.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#3] audio-fft Cycles:10 Average:83.90 Median:76.50 stddev:14.87 (19.4%) stddev-sans-first:15.50 22:03:02 INFO - PROCESS | 1822 | Values: 76.0 77.0 76.0 114.0 76.0 78.0 77.0 76.0 110.0 79.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:83.50 stddev:15.95 (19.1%) stddev-sans-first:16.69 22:03:02 INFO - PROCESS | 1822 | Values: 86.0 83.0 126.0 83.0 85.0 83.0 105.0 84.0 84.0 115.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 22:03:02 INFO - PROCESS | 1822 | Values: 116.0 117.0 116.0 117.0 117.0 117.0 117.0 118.0 118.0 117.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 22:03:02 INFO - PROCESS | 1822 | Values: 148.0 147.0 146.0 147.0 147.0 147.0 146.0 147.0 146.0 147.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:03:02 INFO - PROCESS | 1822 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 94.0 94.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.50 stddev:1.05 (1.6%) stddev-sans-first:1.12 22:03:02 INFO - PROCESS | 1822 | Values: 65.0 63.0 64.0 64.0 66.0 66.0 66.0 65.0 66.0 65.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:67.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 22:03:02 INFO - PROCESS | 1822 | Values: 66.0 67.0 67.0 67.0 67.0 67.0 66.0 66.0 67.0 66.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:83.00 stddev:2.72 (3.3%) stddev-sans-first:2.88 22:03:02 INFO - PROCESS | 1822 | Values: 84.0 84.0 82.0 82.0 83.0 83.0 91.0 83.0 82.0 82.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:134.00 stddev:28.13 (21.0%) stddev-sans-first:29.63 22:03:02 INFO - PROCESS | 1822 | Values: 133.0 130.0 131.0 134.0 132.0 134.0 135.0 133.0 222.0 138.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:176.00 stddev:3.08 (1.8%) stddev-sans-first:3.20 22:03:02 INFO - PROCESS | 1822 | Values: 174.0 176.0 174.0 173.0 176.0 176.0 176.0 184.0 174.0 175.0 22:03:02 INFO - PROCESS | 1822 | 22:03:02 INFO - PROCESS | 1822 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:1.57 (2.3%) stddev-sans-first:1.66 22:03:02 INFO - PROCESS | 1822 | Values: 69.0 72.0 71.0 70.0 69.0 69.0 69.0 69.0 69.0 66.0 22:03:02 INFO - PROCESS | 1822 | -------- Summary: end -------- 22:03:02 INFO - PROCESS | 1822 | 22:03:04 INFO - TEST-INFO | 1822: exit 0 22:03:04 INFO - TEST-OK | kraken | took 64868ms 22:03:04 INFO - SUITE-END | took 1186s 22:03:04 INFO - Completed test suite (00:19:46) 22:03:04 INFO - javascript benchmark 22:03:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2588.042424303008, 2613.8431694098367, 2642.273374821924, 2648.417348183399, 2663.8801253943557, 4478.753668548228, 4541.863744087675, 4694.295855621658, 4699.882502937428, 4717.528247175285, 4553.04386078923, 4574.176648203331, 4582.702124361914, 4588.7476188809605, 4646.25659894417, 4292.613664770178, 4318.179545886272, 4348.956510434887, 4370.3007518797, 4381.0, 4345.69580129391, 4373.628241599468, 4375.168717943597, 4375.890602734941, 4392.912141757163, 4330.198913201068, 4358.629516491102, 4368.781560921957, 4477.216487114758, 4478.081993191404, 4368.7815609219415, 4414.690971631987, 4423.623991960687, 4426.77866106695, 4457.24226881431, 4204.915901681979, 4242.830286788525, 4251.447311849471, 4265.466816647919, 4314.482262128533, 4605.976970115162, 4610.400647915783, 4626.745528995903, 4663.393758811367, 4739.644526660514, 4940.25896115582, 4974.204127339608, 4975.577075948549, 4983.0033993201505, 5033.421156566968, 4834.444038935532, 4889.584385327216, 4961.454240033593, 4988.301637770715, 5042.0, 4703.623710103184, 4708.223143181405, 4708.576228139484, 4769.976150119227, 4774.259989701602, 5100.081985242692, 5146.408163061221, 5148.382194136728, 5148.794048238104, 5183.118869792069, 4698.248280275139, 4737.3130896020575, 4908.705477671351, 4915.188993816052, 4982.451930287665, 4960.975195124001, 5001.324821149171, 5006.924896126561, 5010.49895010496, 5081.212412076134, 4959.032988567267, 4971.45314015458, 4972.502749725071, 4978.47725988766, 5030.0, 4753.215719406328, 4941.45643979162, 4948.90102197954, 4949.406071271398, 5010.574101201366, 3925.371940489508, 3928.508936382952, 3948.3879998600264, 3978.8607398740905, 3985.900352491211, 3505.158761897177, 3508.017755028596, 3549.3078849624076, 3556.76881002734, 3557.4130268505924, 4303.33298338758, 4311.267084595626, 4312.310030395121, 4313.6980411370905, 4366.323219900921, 4870.902581948341, 4932.482089380635, 4936.975315123474, 4950.801967921243, 4990.975045124824, 4274.444322238089, 4294.634956028773, 4322.200392927319, 4344.652427805768, 4351.934720979188, 4564.908701825945, 4573.63410927125, 4583.977080114578, 4586.954130458719, 4646.744429056367, 4526.275795872644, 4625.953740462687, 4672.953270467252, 4719.5752382285755, 4726.1729197391005, 4954.1330267202375, 5048.293238946477, 5060.645754797276, 5075.568576670877, 5077.568406685473, 4697.389339385858, 4731.69243999139, 4736.8105275788585, 4739.502352252964, 4798.880027999328, 4912.975435122801, 4922.163232250455, 4922.384701912261, 4929.334539837076, 4981.975090124526, 4796.640251981185, 4823.9517604824905, 4953.925691114564, 4956.330895329084, 4963.726995015309, 4856.902861942812, 4856.975715121402, 4857.878553036202, 4898.093852637274, 4918.532739389752, 4869.269609558594, 4869.829555965524, 4879.194932836184, 4882.194437917704, 4949.6287778416045, 4637.420322459692, 4703.835365762318, 4742.691725037862, 4774.665773395968, 4783.593394561502, 4854.708717476963, 4878.536539028786, 4879.682820616648, 4931.48219436954, 4944.332515110414, 4652.162610730101, 4700.271457924027, 4708.788104535236, 4778.808847646055, 4826.8069277228515], "value": 4571.716870849341, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.6160610784665, 566.6515093217671, 571.4799532425491, 572.1103683771734, 574.287883025049, 27970.720292797065, 37852.81073594632, 38990.22019559607, 39245.41131883017, 42351.78824105883, 38729.8063509682, 39071.80464097683, 39084.80457597715, 39101.804490977505, 39142.21715565694, 7728.884066739018, 7876.488028278172, 7887.250711182429, 7890.763277101696, 7894.80262993424, 7736.806579835521, 7752.379809615245, 7832.882506762403, 7833.882491762628, 7838.372930165573, 7817.101033381148, 7859.6856125755185, 7861.724839630614, 7870.567118808463, 7877.0, 7765.922340776605, 7808.297253247179, 7809.336206422461, 7819.804504887366, 7833.060032796044, 7773.611319434006, 7839.608019598997, 7853.803654908673, 7857.960710196412, 7864.882026769603, 36519.63480365189, 36652.0, 36749.63250367516, 36766.44850327247, 36832.44751328706, 2851.17315978366, 2972.9419342597416, 3134.0, 3135.764817638686, 3329.716974057208, 27359.45281094387, 27389.315267118283, 27404.588931166047, 27557.0, 28431.573526397122, 3361.428557145291, 3441.1397150712323, 3458.63684313146, 3529.9647003529894, 3571.982140089309, 254283.0, 256012.0, 256401.0, 256409.71795141092, 256484.0, 49964.0, 50143.0, 50258.246126308135, 50267.0, 50283.0, 32036.839815800773, 51512.22731659028, 51587.742061290206, 51611.741941290056, 51713.224301635506, 13076.411561479756, 13625.795613065811, 13675.794863077062, 13693.657658558417, 13716.0, 10217.080462758238, 10375.533101010473, 10375.896241037493, 10376.42929638862, 10377.688669339932, 17063.40278090261, 17168.742468862976, 17262.827371726373, 17281.56796080108, 17362.39231626887, 6396.904046439307, 6468.838279043061, 6481.967590162019, 6558.0, 6740.797776066725, 775.3719487215374, 831.3224721851672, 930.5626355612851, 930.8743319651895, 973.3816034866187, 26823.329416764736, 27808.44383112367, 28011.193383026428, 28327.858360708065, 28392.148235552555, 912.3932584831012, 912.885889263842, 915.2906497464518, 928.8885333759993, 956.8277710012126, 14327.0, 14411.495597654031, 14580.343884525222, 14861.405543778345, 14895.553133406016, 382036.0, 385589.0, 385947.0, 387062.0, 387233.0, 47085.29372059422, 47470.0, 47486.0, 47513.287300690514, 47515.0, 1073.660914555731, 1137.1641843245043, 1150.631797824688, 1183.674489515373, 1197.7125489882364, 25163.87418062898, 26097.608535871597, 26719.866400667874, 27097.322566935985, 27189.72810271872, 40121.39817902792, 40870.59129408668, 40984.18031639291, 41083.0, 41145.0, 317922.0, 318980.0, 319742.0, 320474.0, 320962.0, 24659.876700616383, 25072.749272507772, 25092.62361064622, 25107.87446062831, 25189.496210076064, 39511.0, 39765.60234397735, 39805.203895922496, 39880.800595996836, 40069.59930400775, 34027.0, 34038.319233615686, 34074.659253407146, 34274.82862585671, 34395.828020859735, 33740.66259337375, 33966.83016584901, 34190.82904585461, 34273.828630857686, 34561.0, 33408.49887251742, 34332.31335373328, 34467.65532344745, 34515.48226776651, 34695.479567805996, 29075.418491629625, 30782.69129815228, 31281.687183127877, 31714.682853172097, 31931.0, 24658.0, 24715.258542243762, 24734.134305299227, 24927.0, 25186.874065629556], "value": 16985.079112607746, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.92153481780986, 343.1267424404888, 360.7799242462099, 363.9526861508003, 380.391373801917, 12168.391580420977, 13248.0, 16695.332186712516, 16923.153842307896, 17442.91278543609, 17028.574285642866, 17263.74104388435, 17313.91343043286, 17364.56588585286, 17445.91277043613, 3746.569144548371, 4934.999999999991, 5127.692338459686, 5148.227765835118, 5155.407128180251, 3664.13892735207, 3840.0, 4995.325631039817, 5130.51260130287, 5698.259226300575, 5932.762689492415, 6068.3021452533085, 6070.635761854281, 6075.908861367061, 6080.391960803929, 5746.25298711169, 5838.620489668158, 5878.676672782995, 5884.6469211847425, 6061.636301821883, 5144.639875208737, 5161.896762064737, 5171.276021357013, 5177.352830896138, 5179.171332586768, 19692.40922772319, 20233.79766202334, 20294.0, 20533.38399848007, 20618.896905515525, 1816.536783120309, 1956.45219338584, 2017.1023894367013, 2046.7310267634014, 2557.5524283250356, 12528.0, 12857.871421285761, 13176.472941082346, 13315.800262996063, 13743.725125497534, 1726.48205538338, 1730.9048002359866, 1758.5955230297104, 1758.9032603206817, 1792.3726695656417, 67030.3296967029, 67787.9999999995, 68331.65834170848, 69986.0, 70652.2934770656, 9284.442933423881, 9344.95327523358, 9476.95261523688, 9554.665586704432, 9821.017898210264, 13149.21104733719, 13155.934220329029, 13166.078374513883, 13166.144200626928, 13168.407421666048, 6906.930930690729, 7046.1192350956135, 7194.424446044304, 7302.0, 7303.488755787044, 6677.198736151596, 6768.661566921634, 6907.516473846783, 6930.44556435484, 6971.407430368374, 5960.970195148997, 6031.788887389008, 6047.455728984412, 6057.666828324396, 6076.270847498328, 1615.7334039883522, 1655.0751833042898, 1717.6135369542003, 1724.7326664367042, 1780.5815633326158, 763.9656215470317, 782.6243403166512, 820.6594263380749, 834.3116928533984, 861.8793368928355, 17057.658846822993, 17379.0, 17484.0, 18034.188461519265, 18070.0, 789.8815177723387, 807.1323327423044, 809.8987626546682, 818.1491249100868, 821.5850995247362, 9832.9016709832, 9983.600655973825, 10201.846972295421, 10286.845697314546, 10298.0, 359965.0, 362140.0, 365419.0, 365487.0, 383906.0, 42856.357154642705, 44010.33984490235, 52107.0, 61396.38603613907, 63745.0, 900.7027680865299, 906.918377346042, 924.7410724997012, 929.4144688845984, 931.2549960031947, 15361.385544578092, 15436.996595221275, 15758.842411575737, 15867.127307998171, 16147.03117812935, 35198.824005879804, 35408.29183416266, 35701.28597427985, 36550.0, 36816.44775328426, 45512.0, 46727.0, 46988.295175573076, 47553.76223118862, 50845.74577127091, 6415.326390729093, 6506.544541882023, 6521.315261897433, 7146.642667866482, 7781.105172904962, 10223.795524089328, 10858.619948301772, 10986.340819550853, 11099.500522476346, 11295.943520282346, 9272.490013049348, 9286.257099432165, 9469.47917864524, 9547.952260238655, 10242.897571024194, 9271.860922086309, 9327.393719408217, 9394.67118650844, 9411.341206115507, 9524.571394287412, 9633.759156020875, 9750.414975101234, 9873.358231714916, 10482.790344193227, 10578.735531611772, 6606.933930660632, 6615.272320044887, 6703.195616525853, 6798.218204906499, 7161.498695091502, 7352.77941661751, 8881.911180888366, 9583.904160958302, 9954.502274886081, 10183.18534517222], "value": 8040.143697582461, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1295.9092863499552, 1359.9388027538762, 1360.1906865415074, 1561.117968347884, 1594.0834020438244, 6633.2703402625675, 6812.523123381366, 7204.495685302031, 7837.686492540299, 7845.09781375142, 7230.385417239541, 7501.662425190866, 7502.512336698111, 7650.311471967521, 7669.424793140509, 2117.4071260047212, 2131.8488016471088, 2134.2210093315985, 2164.8159906407973, 2167.7073595064703, 1746.4760571828401, 1813.2837529175968, 2058.670612701968, 2088.7284652995168, 2124.415785658941, 2077.366403247009, 2155.2348916134783, 2160.600288946542, 2161.859479133859, 2179.9346019619356, 2068.606964676722, 2072.5440403111365, 2086.9895650521726, 2130.1905275995177, 2135.3914134471756, 2110.883901385423, 2110.9894450527804, 2117.502386939068, 2122.7983341582526, 2125.6173888700027, 7773.416993725493, 7835.804104897366, 7836.294733473957, 7838.9216107838765, 7839.725609603636, 4484.865454036384, 4513.323001549761, 4589.311603259504, 4591.517890621503, 4642.0, 2828.589854471111, 2940.6765255821842, 2958.068208514311, 2984.3732816108645, 2989.9402011959637, 877.978050548735, 883.5847151838625, 889.5196593839319, 893.4907102951321, 895.7671005538542, 10397.428141452217, 10400.479976001245, 10414.739631509197, 10435.739106522322, 10456.634017809416, 3853.614638536124, 3988.2621714982242, 4182.937255941163, 4184.581541845791, 4211.052513184509, 8956.238719708768, 8964.462132271954, 8996.685116020908, 9005.414648047856, 9007.09929007094, 4621.676482646182, 4622.14490319292, 4623.16782979067, 4626.976865115653, 4631.907361852744, 4134.669226461876, 4217.472815898012, 4227.281362168439, 4231.090315582164, 4232.343986682069, 3477.94783078254, 3541.610422853484, 3557.324108419392, 3591.28174365128, 3652.8904132876055, 1020.6886899495726, 1087.342157994402, 1133.3313345126414, 1181.9704507387212, 1187.4300335838673, 764.5145332713766, 807.7253733730442, 812.9837403251902, 829.1459796409707, 835.8955130608674, 4924.655274130777, 5132.255822905658, 5145.614078944094, 5159.1229490986625, 5382.0, 829.4194064155115, 857.6869442653511, 862.4997501449144, 864.3344624638993, 899.9100089990949, 3448.620651728308, 3553.484744712054, 3592.946105808415, 3608.891733248007, 3691.90770230741, 8807.735767926972, 8828.42615230008, 9083.772905677279, 9149.130832570896, 9245.861312080324, 9127.361084724138, 9140.177384035336, 9197.494137822485, 9202.447853128833, 9211.63153473867, 6895.275996020446, 7014.649267536603, 7017.754378596725, 7041.471889608198, 7095.148582170069, 5129.256257842667, 5270.683758974474, 5297.682139071668, 5300.125479296027, 5346.679199248058, 8509.064002959793, 8716.128387161232, 8742.34432417584, 8794.692185773467, 8827.337949653931, 8237.50574965504, 8433.704820331259, 8742.431741936754, 8871.423357481743, 8925.062868398983, 6559.311272316338, 6718.428933540704, 6722.092517510074, 6724.394804467622, 6728.899066513908, 7236.96381518089, 7336.046313979148, 7368.115826100902, 7415.295546923034, 7440.962795185989, 4904.558589726942, 6495.447886929664, 6513.478921686159, 6556.737730490727, 6611.239707433514, 6435.4529864962005, 6444.291127976013, 6457.741690332522, 6464.676766161767, 6497.577657452251, 6117.357677443804, 6203.658798766111, 6209.068639704081, 6209.379062093754, 6209.658468784261, 4440.555944405533, 4448.7108337957925, 4504.729716217037, 4544.840930567547, 4605.976970115128, 4569.405977222939, 4588.655850811269, 4624.0, 4649.1863923812525, 4764.976175119103], "value": 4038.4437943733196, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.92701824543866, 292.31452244486684, 297.86000579727533, 302.34994761263283, 303.8617429069773, 4792.83225087122, 4820.469748327686, 4831.806727730886, 4841.685290456122, 4932.80268789248, 4583.633309335253, 4637.382841383726, 4736.8578942631775, 4742.596879265258, 4848.418189817218, 3777.6411240932207, 3794.829232684536, 3801.7909015004166, 3811.313963486571, 3812.9046773830737, 3770.547534295875, 3796.8291426885858, 3799.7530160539613, 3804.657580817726, 3821.9808900955454, 3555.857765689382, 3673.687736542397, 3704.499892514503, 3786.678132358753, 3796.4495148203496, 3776.584575696671, 3784.432335149722, 3792.2984247914087, 3792.307461702195, 3803.9429408558894, 3323.900282991514, 3327.45097058985, 3332.0, 3345.4312766829453, 3356.865725370982, 4517.170545928904, 4696.319033740088, 4713.905721885543, 4714.646401519865, 4717.882052948669, 4250.702450828443, 4253.659707223414, 4278.577138289356, 4285.828566857322, 4324.459442569679, 5027.622928280357, 5027.84916452507, 5033.874153146201, 5041.924371134399, 5075.720835354054, 3567.8037707926055, 3580.5882323532865, 3583.229605634801, 3598.298331825295, 3605.1888325126897, 5149.470200655899, 5225.42520322768, 5259.526642602184, 5268.051750684837, 5271.367435907678, 3617.0595645132457, 3639.945400818937, 3643.143861192618, 3643.4716966039778, 3654.8720794772057, 3703.9074023149096, 3713.090292878262, 3717.646823551758, 3725.4166979033803, 3743.12036671382, 4172.749635021908, 4236.279832428495, 4289.206496798102, 4304.096139810675, 4304.794657495906, 5004.9499505005215, 5016.44819069902, 5038.470960549163, 5060.822871199491, 5080.618953578496, 1259.2696236182992, 1283.1402960016624, 1289.432649634158, 1293.6895145165092, 1297.2540789046147, 816.7304789419477, 837.024866031067, 851.2806678356778, 858.9441686290371, 878.512425603796, 4446.822127114944, 4453.799579018952, 4454.1537107950035, 4458.576435238647, 4461.13007963444, 5139.563137133386, 5161.329027226437, 5173.741312934339, 5190.92213616796, 5209.505097015777, 4292.720973136736, 4464.192002239099, 4482.7534485602955, 4492.865214043519, 4517.051419201939, 2713.7014928357867, 2732.6037724529833, 2746.807723459339, 2747.9420423136835, 2769.8892044318, 4909.116359055404, 4927.211646136601, 4935.728534930613, 4945.109880221595, 4950.663320903408, 5144.717040562805, 5251.711155886386, 5287.524122828887, 5290.788368465218, 5296.0, 4898.142825005681, 4899.461059283476, 4909.680870743462, 4910.312556242057, 4920.3111564381015, 5150.330457040561, 5151.6908985461, 5177.524405544402, 5177.534021938043, 5205.6356055075785, 4873.488283730299, 4996.725180114983, 5009.223570346602, 5044.319016932667, 5045.394552653705, 4828.61371090319, 4831.371921650163, 4833.637477189154, 4850.466448690711, 4873.171560834738, 3641.908952276214, 3643.0892276930767, 3665.3585622516484, 3666.7983260920905, 3670.210904655405, 4891.80432782699, 5045.839456924855, 5100.566451851634, 5105.540501354896, 5163.812323165618, 4955.65310428281, 4968.677035992649, 4992.450830408579, 4998.900021999612, 4999.47505511916, 4978.726170060681, 4988.0, 4988.376452943382, 5012.548870601663, 5023.296738456692, 4699.953000469952, 4932.679375840559, 4988.850334489971, 5008.9499105009945, 5011.323471331323, 4840.661153719206, 4935.975320123376, 4971.229459433794, 5093.612747522856, 5113.181890897587, 4983.227599722049, 4987.551120399036, 4993.40343021634, 4994.2508623706735, 5019.924701129559], "value": 3793.5707850930003, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.9428154325338, 626.7493002798877, 655.1744801550049, 716.4411758828113, 717.3973861955958, 12459.190152640083, 13020.0, 13020.088593798439, 13026.73946521069, 13034.869651303485, 11975.580854670088, 11983.101267404936, 11983.400829958511, 11988.0, 11992.64022079339, 592.9318128415223, 608.5679167790873, 714.7462650758984, 724.8514054618804, 739.7669734033763, 724.2431658916445, 732.3847967707125, 732.7874916274274, 733.8752412089957, 733.9302766237199, 780.1963977103593, 781.2578050851686, 781.613101514751, 782.941279404047, 782.949108307961, 624.9750009999594, 641.3073880209363, 644.1079105439001, 646.3633321178635, 775.7866586688649, 476.3878416235156, 554.3763266325384, 569.5756661287368, 572.9799457018985, 574.4973148495067, 11723.706907327385, 11749.588764393291, 11762.353070581115, 11767.529298828036, 11768.352740599265, 12296.323702196376, 12425.813612795815, 12628.621141365773, 13207.0, 13376.933115334361, 11466.885331146665, 11585.47865346053, 11788.587399441065, 11824.172307938448, 12440.066994975414, 3605.693516051139, 3637.7271704621994, 3661.450782382664, 3747.2130852521004, 3986.54154772202, 61834.31805150513, 62349.37650623526, 62490.68754656243, 62551.11937686851, 62964.0, 9592.376495527908, 9626.0, 9675.0, 9835.950820245853, 10313.329633573794, 18021.909890450726, 18033.18850651724, 18046.458606241835, 18059.638807223782, 18059.819401806075, 6192.535559833031, 6313.526485513605, 6827.385535301747, 6848.212455567574, 7159.534630249017, 3233.9838300808347, 3647.252313275772, 3691.6676205216254, 3716.9070773230887, 3733.272011957642, 2595.0787470448095, 2718.0350975403844, 2719.5240832854565, 2721.3060669529145, 2722.128918746022, 466.56143225368044, 478.1799214347341, 479.24040395972077, 485.20426500539145, 486.23418116466706, 441.8188542697479, 446.7297285142507, 450.80390030336906, 463.24953575207707, 476.05978193069103, 7283.7450689225625, 7657.119431265364, 7783.610819459004, 7889.8816517752275, 8061.750428683564, 405.94519739835334, 495.35603715170134, 499.2237071354007, 510.4232217594095, 574.3768011707261, 3546.840392182358, 3568.1971556399603, 3639.9090022749115, 3800.6959443244473, 3802.08749900022, 55968.0, 57334.7133264331, 72832.63583682153, 76789.61605191938, 77109.61445192738, 75117.0, 77422.61288693521, 77743.61128194323, 77909.2209077902, 79302.20697792948, 66094.0, 69845.65077174582, 70254.64872675706, 71042.64478677677, 72121.6393918027, 7860.371170306247, 7886.293442082212, 8049.597520124087, 8268.586570671321, 8374.246317831181, 30173.133552312494, 38114.42828357632, 42981.0, 44136.55863441453, 44418.55581444144, 56855.147172791614, 57455.0, 61604.69197653983, 63191.0, 64678.353216467236, 10909.18181136402, 10995.780084398428, 11104.61133860311, 11117.055050320814, 11373.090152787923, 10089.394636321844, 10286.691399258034, 10677.46612669379, 11246.268992515461, 11790.764184716087, 9503.429794212369, 10091.091801737584, 11374.64881507808, 11624.5350185995, 11813.350265735124, 10063.39619622825, 10143.949280253551, 10586.153107751516, 10703.304285221435, 10972.561097556327, 11058.447077646246, 11102.61140860066, 11259.324440533594, 11535.538578456768, 11704.94147529257, 8600.655973760979, 8686.0, 8841.646334146819, 8878.156575125353, 9244.352895297265, 8298.543580103153, 8938.1508756668, 9013.864792027995, 9090.00069946151, 9178.816423671356], "value": 6103.14647888667, "unit": "score"}], "name": "dromaeo_css", "value": 6228.440978831763, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 113.0, 112.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 127.0, 124.0, 165.0, 125.0, 131.0, 125.0, 123.0, 181.0, 125.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 163.0, 162.0, 161.0, 163.0, 161.0, 162.0, 164.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 114.0, 76.0, 78.0, 77.0, 76.0, 110.0, 79.0], "value": 83.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 126.0, 83.0, 85.0, 83.0, 105.0, 84.0, 84.0, 115.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 64.0, 66.0, 66.0, 66.0, 65.0, 66.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 67.0, 66.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 82.0, 82.0, 83.0, 83.0, 91.0, 83.0, 82.0, 82.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 130.0, 131.0, 134.0, 132.0, 134.0, 135.0, 133.0, 222.0, 138.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 174.0, 173.0, 176.0, 176.0, 176.0, 184.0, 174.0, 175.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 71.0, 70.0, 69.0, 69.0, 69.0, 69.0, 69.0, 66.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1552.6999999999998, "alertThreshold": 2.0}]} 22:03:04 INFO - Return code: 0 22:03:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:03:04 INFO - # TBPL SUCCESS # 22:03:04 INFO - Running post-action listener: _resource_record_post_action 22:03:04 INFO - [mozharness: 2016-09-23 05:03:04.633234Z] Finished run-tests step (success) 22:03:04 INFO - Running post-run listener: _resource_record_post_run 22:03:04 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 8192; Write bytes: 391786496; Read time: 28; Write time: 303400 22:03:04 INFO - TinderboxPrint: CPU usage
13.0% 22:03:04 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 22:03:04 INFO - TinderboxPrint: I/O write bytes / time
391,786,496 / 303,400 22:03:04 INFO - TinderboxPrint: CPU idle
8,348.8 (87.0%) 22:03:04 INFO - TinderboxPrint: CPU user
1,201.0 (12.5%) 22:03:04 INFO - TinderboxPrint: Swap in / out
0 / 0 22:03:04 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99876864; Read time: 0; Write time: 99680 22:03:04 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291909632; Read time: 12; Write time: 203720 22:03:05 INFO - Running post-run listener: _upload_blobber_files 22:03:05 INFO - Blob upload gear active. 22:03:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:03:05 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 22:03:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:03:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:03:05 INFO - (blobuploader) - INFO - Open directory for files ... 22:03:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:03:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:03:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:03:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:03:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:03:06 INFO - (blobuploader) - INFO - Done attempting. 22:03:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:03:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:03:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:03:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:03:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:03:10 INFO - (blobuploader) - INFO - Done attempting. 22:03:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:03:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:03:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:03:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:03:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:03:11 INFO - (blobuploader) - INFO - Done attempting. 22:03:11 INFO - (blobuploader) - INFO - Iteration through files over. 22:03:11 INFO - Return code: 0 22:03:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:03:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:03:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e3d7a148b17591c1e96cb26b2e36395c3d70a0b45b2f7b10ddb8e01a767761ead26599c551765debdcd25926a51d564591d6c93d8b0208d264c369842cde80e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f3c7433d9a247cd59a001e515b5b4ed787ffe5b978eba34fa9ed76a25d3299eec3622efcdcc2980dd83bdc8385b8b9aced15253c4eaec233cff8652e5961b857", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/71cc976fa6b1298f6cd7bd8ed7d990197fe0576f8a8d541b3e8026bd1415b17e7d878c5a9ca890b22f86c4e4771774e0f3a2ec3454d6e8f178ef9d48e9aa159a"} 22:03:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:03:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:03:11 INFO - Contents: 22:03:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e3d7a148b17591c1e96cb26b2e36395c3d70a0b45b2f7b10ddb8e01a767761ead26599c551765debdcd25926a51d564591d6c93d8b0208d264c369842cde80e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f3c7433d9a247cd59a001e515b5b4ed787ffe5b978eba34fa9ed76a25d3299eec3622efcdcc2980dd83bdc8385b8b9aced15253c4eaec233cff8652e5961b857", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/71cc976fa6b1298f6cd7bd8ed7d990197fe0576f8a8d541b3e8026bd1415b17e7d878c5a9ca890b22f86c4e4771774e0f3a2ec3454d6e8f178ef9d48e9aa159a"} 22:03:11 INFO - Running post-run listener: copy_logs_to_upload_dir 22:03:11 INFO - Copying logs to upload dir... 22:03:11 INFO - mkdir: /builds/slave/test/build/upload/logs 22:03:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.668526 ========= master_lag: 7.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-09-22 22:03:17.998014) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 22:03:18.001171) ========= 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=836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e3d7a148b17591c1e96cb26b2e36395c3d70a0b45b2f7b10ddb8e01a767761ead26599c551765debdcd25926a51d564591d6c93d8b0208d264c369842cde80e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f3c7433d9a247cd59a001e515b5b4ed787ffe5b978eba34fa9ed76a25d3299eec3622efcdcc2980dd83bdc8385b8b9aced15253c4eaec233cff8652e5961b857", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/71cc976fa6b1298f6cd7bd8ed7d990197fe0576f8a8d541b3e8026bd1415b17e7d878c5a9ca890b22f86c4e4771774e0f3a2ec3454d6e8f178ef9d48e9aa159a"} build_url:https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010352 build_url: 'https://queue.taskcluster.net/v1/task/MGUW0OZJSGuEDeMD2NyMTg/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-Non-PGO/sha512/e3d7a148b17591c1e96cb26b2e36395c3d70a0b45b2f7b10ddb8e01a767761ead26599c551765debdcd25926a51d564591d6c93d8b0208d264c369842cde80e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f3c7433d9a247cd59a001e515b5b4ed787ffe5b978eba34fa9ed76a25d3299eec3622efcdcc2980dd83bdc8385b8b9aced15253c4eaec233cff8652e5961b857", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/71cc976fa6b1298f6cd7bd8ed7d990197fe0576f8a8d541b3e8026bd1415b17e7d878c5a9ca890b22f86c4e4771774e0f3a2ec3454d6e8f178ef9d48e9aa159a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 22:03:18.025854) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 22:03:18.026249) ========= 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=836cfdc75598d54cb747368e000001a9-1474605743.824418-1438916246 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 22:03:18.076935) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 22:03:18.077298) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 22:03:18.077648) ========= ========= Total master_lag: 7.31 =========