builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-066 starttime: 1472670084.41 results: success (0) buildid: 20160831113821 builduid: ff110527863f4b7cbf224f3fb78586b5 revision: 2c986272197fedc498235e2a012c8a3e13993b58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:24.411362) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:24.411845) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:24.412148) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:24.427289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:24.427669) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-31 12:01:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.1M=0s 2016-08-31 12:01:26 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085100 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:24.530493) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:24.530814) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021344 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:24.584164) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:24.584511) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 2c986272197fedc498235e2a012c8a3e13993b58 --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 2c986272197fedc498235e2a012c8a3e13993b58 --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=82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-31 12:01:26,176 truncating revision to first 12 chars 2016-08-31 12:01:26,176 Setting DEBUG logging. 2016-08-31 12:01:26,176 attempt 1/10 2016-08-31 12:01:26,176 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/2c986272197f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 12:01:26,486 unpacking tar archive at: autoland-2c986272197f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.669327 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:25.267936) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:25.268264) ========= script_repo_revision: 2c986272197fedc498235e2a012c8a3e13993b58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:25.268694) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:25.268982) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 12:01:25.279020) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-31 12:01:25.279435) ========= /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=82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:01:26 INFO - MultiFileLogger online at 20160831 12:01:26 in /builds/slave/test 12:01:26 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 12:01:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:01:26 INFO - {'append_to_log': False, 12:01:26 INFO - 'base_work_dir': '/builds/slave/test', 12:01:26 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 12:01:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:01:26 INFO - 'branch': 'Autoland-Non-PGO', 12:01:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:01:26 INFO - 'config_files': ('talos/linux_config.py',), 12:01:26 INFO - 'default_actions': ('clobber', 12:01:26 INFO - 'read-buildbot-config', 12:01:26 INFO - 'download-and-extract', 12:01:26 INFO - 'populate-webroot', 12:01:26 INFO - 'create-virtualenv', 12:01:26 INFO - 'install', 12:01:26 INFO - 'run-tests'), 12:01:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:01:26 INFO - 'download_minidump_stackwalk': True, 12:01:26 INFO - 'download_symbols': 'ondemand', 12:01:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:01:26 INFO - 'tooltool.py': '/tools/tooltool.py', 12:01:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:01:26 INFO - '/tools/misc-python/virtualenv.py')}, 12:01:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:01:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:01:26 INFO - 'installer_path': 'installer.exe', 12:01:26 INFO - 'log_level': 'info', 12:01:26 INFO - 'log_name': 'talos', 12:01:26 INFO - 'log_to_console': True, 12:01:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:01:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:01:26 INFO - 'opt_config_files': (), 12:01:26 INFO - 'pip_index': False, 12:01:26 INFO - 'sps_profile': False, 12:01:26 INFO - 'sps_profile_interval': 0, 12:01:26 INFO - 'suite': 'dromaeojs', 12:01:26 INFO - 'system_bits': '32', 12:01:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:01:26 INFO - 'title': 'talos-linux64-ix-066', 12:01:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:01:26 INFO - 'use_talos_json': True, 12:01:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:01:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:01:26 INFO - 'work_dir': 'build'} 12:01:26 INFO - [mozharness: 2016-08-31 19:01:26.963987Z] Running clobber step. 12:01:26 INFO - Running pre-action listener: _resource_record_pre_action 12:01:26 INFO - Running main action method: clobber 12:01:26 INFO - rmtree: /builds/slave/test/build 12:01:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:01:27 INFO - Running post-action listener: _resource_record_post_action 12:01:27 INFO - [mozharness: 2016-08-31 19:01:27.425989Z] Finished clobber step (success) 12:01:27 INFO - [mozharness: 2016-08-31 19:01:27.426093Z] Running read-buildbot-config step. 12:01:27 INFO - Running pre-action listener: _resource_record_pre_action 12:01:27 INFO - Running main action method: read_buildbot_config 12:01:27 INFO - Using buildbot properties: 12:01:27 INFO - { 12:01:27 INFO - "project": "", 12:01:27 INFO - "product": "firefox", 12:01:27 INFO - "script_repo_revision": "production", 12:01:27 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 12:01:27 INFO - "repository": "", 12:01:27 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 12:01:27 INFO - "buildid": "20160831113821", 12:01:27 INFO - "pgo_build": "False", 12:01:27 INFO - "basedir": "/builds/slave/test", 12:01:27 INFO - "buildnumber": 370, 12:01:27 INFO - "slavename": "talos-linux64-ix-066", 12:01:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:01:27 INFO - "platform": "ubuntu64_hw", 12:01:27 INFO - "scheduler": "tests-autoland-linux64-talos", 12:01:27 INFO - "branch": "autoland", 12:01:27 INFO - "repo_path": "integration/autoland", 12:01:27 INFO - "revision": "2c986272197fedc498235e2a012c8a3e13993b58", 12:01:27 INFO - "stage_platform": "linux64", 12:01:27 INFO - "builduid": "ff110527863f4b7cbf224f3fb78586b5", 12:01:27 INFO - "slavebuilddir": "test" 12:01:27 INFO - } 12:01:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 12:01:27 INFO - Running post-action listener: _resource_record_post_action 12:01:27 INFO - [mozharness: 2016-08-31 19:01:27.428540Z] Finished read-buildbot-config step (success) 12:01:27 INFO - [mozharness: 2016-08-31 19:01:27.428628Z] Running download-and-extract step. 12:01:27 INFO - Running pre-action listener: _resource_record_pre_action 12:01:27 INFO - Running main action method: download_and_extract 12:01:27 INFO - mkdir: /builds/slave/test/build/tests 12:01:27 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')]} 12:01:27 INFO - trying https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:01:27 INFO - Downloading https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:01:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:01:27 INFO - Downloaded 1361 bytes. 12:01:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:01:27 INFO - Using the following test package requirements: 12:01:27 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:01:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:01:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:01:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:01:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:01:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:01:27 INFO - u'jsshell-linux-x86_64.zip'], 12:01:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:01:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:01:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:01:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:01:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:01:27 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:01:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:01:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:01:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:01:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:01:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:01:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:01:27 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')]} 12:01:27 INFO - trying https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:01:27 INFO - Downloading https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:01:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:01:28 INFO - Downloaded 20332708 bytes. 12:01:28 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 12:01:33 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:01:33 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')]} 12:01:33 INFO - trying https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:01:33 INFO - Downloading https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:01:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:01:33 INFO - Downloaded 20332708 bytes. 12:01:33 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 12:01:38 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')]} 12:01:38 INFO - trying https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 12:01:38 INFO - Downloading https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 12:01:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:01:40 INFO - Downloaded 14413998 bytes. 12:01:40 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 12:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:40 INFO - trying https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:01:40 INFO - Downloading https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:01:41 INFO - Downloaded 55867292 bytes. 12:01:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:01:41 INFO - mkdir: /builds/slave/test/properties 12:01:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:01:41 INFO - Writing to file /builds/slave/test/properties/build_url 12:01:41 INFO - Contents: 12:01:41 INFO - build_url:https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:01:42 INFO - Running post-action listener: _resource_record_post_action 12:01:42 INFO - Running post-action listener: set_extra_try_arguments 12:01:42 INFO - [mozharness: 2016-08-31 19:01:42.102759Z] Finished download-and-extract step (success) 12:01:42 INFO - [mozharness: 2016-08-31 19:01:42.102987Z] Running populate-webroot step. 12:01:42 INFO - Running pre-action listener: _resource_record_pre_action 12:01:42 INFO - Running main action method: populate_webroot 12:01:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:01:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:01:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:01:42 INFO - '--tppagecycles', 12:01:42 INFO - '7'], 12:01:42 INFO - 'tests': ['tsvgm']}}, 12:01:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:01:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:01:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:01:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:01:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:42 INFO - 'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:42 INFO - 'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['damp', 'tps']}, 12:01:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:42 INFO - 'tests': ['damp', 'tps']}, 12:01:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['dromaeo_dom']}, 12:01:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:01:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['basic_compositor_video']}, 12:01:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:01:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['a11yr', 12:01:42 INFO - 'ts_paint', 12:01:42 INFO - 'tpaint', 12:01:42 INFO - 'sessionrestore', 12:01:42 INFO - 'sessionrestore_no_auto_restore', 12:01:42 INFO - 'tabpaint']}, 12:01:42 INFO - 'other-e10s': {'tests': ['a11yr', 12:01:42 INFO - 'ts_paint', 12:01:42 INFO - 'tpaint', 12:01:42 INFO - 'sessionrestore', 12:01:42 INFO - 'sessionrestore_no_auto_restore', 12:01:42 INFO - 'tabpaint']}, 12:01:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:01:42 INFO - 'ts_paint', 12:01:42 INFO - 'tpaint', 12:01:42 INFO - 'sessionrestore', 12:01:42 INFO - 'sessionrestore_no_auto_restore', 12:01:42 INFO - 'tabpaint']}, 12:01:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:01:42 INFO - 'ts_paint', 12:01:42 INFO - 'tpaint', 12:01:42 INFO - 'sessionrestore', 12:01:42 INFO - 'sessionrestore_no_auto_restore', 12:01:42 INFO - 'tabpaint']}, 12:01:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['a11yr', 12:01:42 INFO - 'ts_paint', 12:01:42 INFO - 'tpaint', 12:01:42 INFO - 'sessionrestore', 12:01:42 INFO - 'sessionrestore_no_auto_restore', 12:01:42 INFO - 'tabpaint']}, 12:01:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['a11yr', 12:01:42 INFO - 'ts_paint', 12:01:42 INFO - 'tpaint', 12:01:42 INFO - 'sessionrestore', 12:01:42 INFO - 'sessionrestore_no_auto_restore', 12:01:42 INFO - 'tabpaint']}, 12:01:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['tsvgx', 12:01:42 INFO - 'tsvgr_opacity', 12:01:42 INFO - 'tart', 12:01:42 INFO - 'tscrollx', 12:01:42 INFO - 'cart']}, 12:01:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:01:42 INFO - 'tsvgr_opacity', 12:01:42 INFO - 'tart', 12:01:42 INFO - 'tscrollx', 12:01:42 INFO - 'cart']}, 12:01:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:42 INFO - 'talos_options': ['--disable-e10s'], 12:01:42 INFO - 'tests': ['tp5o']}, 12:01:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:42 INFO - 'tests': ['tp5o']}, 12:01:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:42 INFO - 'talos_options': ['--disable-e10s', 12:01:42 INFO - '--xperf_path', 12:01:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:42 INFO - 'tests': ['tp5n']}, 12:01:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:42 INFO - 'talos_options': ['--xperf_path', 12:01:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:42 INFO - 'tests': ['tp5n']}}, 12:01:42 INFO - 'talos.zip': {'path': '', 12:01:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:01:42 INFO - Running post-action listener: _resource_record_post_action 12:01:42 INFO - [mozharness: 2016-08-31 19:01:42.119102Z] Finished populate-webroot step (success) 12:01:42 INFO - [mozharness: 2016-08-31 19:01:42.119192Z] Running create-virtualenv step. 12:01:42 INFO - Running pre-action listener: _resource_record_pre_action 12:01:42 INFO - Running main action method: create_virtualenv 12:01:42 INFO - Creating virtualenv /builds/slave/test/build/venv 12:01:42 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 12:01:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:01:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:01:42 INFO - Using real prefix '/usr' 12:01:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:01:42 INFO - Installing distribute.............................................................................................................................................................................................done. 12:01:44 INFO - Installing pip.................done. 12:01:44 INFO - Return code: 0 12:01:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:01:44 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')]} 12:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:44 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')]} 12:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:44 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 0x7fd27ecddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', '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 12:01:44 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 12:01:44 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 12:01:44 INFO - Using env: {'DISPLAY': ':0', 12:01:44 INFO - 'HOME': '/home/cltbld', 12:01:44 INFO - 'LANG': 'en_US.UTF-8', 12:01:44 INFO - 'LANGUAGE': 'en_US:en', 12:01:44 INFO - 'LOGNAME': 'cltbld', 12:01:44 INFO - 'MAIL': '/var/mail/cltbld', 12:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:44 INFO - 'MOZ_NO_REMOTE': '1', 12:01:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:44 INFO - 'NO_EM_RESTART': '1', 12:01:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:44 INFO - 'PWD': '/builds/slave/test', 12:01:44 INFO - 'SHELL': '/bin/bash', 12:01:44 INFO - 'SHLVL': '1', 12:01:44 INFO - 'TERM': 'linux', 12:01:44 INFO - 'TMOUT': '86400', 12:01:44 INFO - 'USER': 'cltbld', 12:01:44 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:44 INFO - '_': '/tools/buildbot/bin/python'} 12:01:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:44 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 12:01:44 INFO - Cleaning up... 12:01:44 INFO - Return code: 0 12:01:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:01:44 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')]} 12:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:44 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')]} 12:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:44 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 0x7fd27ecddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', '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 12:01:44 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 12:01:44 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 12:01:44 INFO - Using env: {'DISPLAY': ':0', 12:01:44 INFO - 'HOME': '/home/cltbld', 12:01:44 INFO - 'LANG': 'en_US.UTF-8', 12:01:44 INFO - 'LANGUAGE': 'en_US:en', 12:01:44 INFO - 'LOGNAME': 'cltbld', 12:01:44 INFO - 'MAIL': '/var/mail/cltbld', 12:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:44 INFO - 'MOZ_NO_REMOTE': '1', 12:01:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:44 INFO - 'NO_EM_RESTART': '1', 12:01:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:44 INFO - 'PWD': '/builds/slave/test', 12:01:44 INFO - 'SHELL': '/bin/bash', 12:01:44 INFO - 'SHLVL': '1', 12:01:44 INFO - 'TERM': 'linux', 12:01:44 INFO - 'TMOUT': '86400', 12:01:44 INFO - 'USER': 'cltbld', 12:01:44 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:44 INFO - '_': '/tools/buildbot/bin/python'} 12:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:45 INFO - Downloading/unpacking psutil>=3.1.1 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:01:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:47 INFO - Installing collected packages: psutil 12:01:47 INFO - Running setup.py install for psutil 12:01:47 INFO - building 'psutil._psutil_linux' extension 12:01:47 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 12:01:47 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 12:01:47 INFO - building 'psutil._psutil_posix' extension 12:01:47 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 12:01:47 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 12:01:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:47 INFO - Successfully installed psutil 12:01:47 INFO - Cleaning up... 12:01:47 INFO - Return code: 0 12:01:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27ecddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', '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 12:01: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:01: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 mozsystemmonitor==0.3 12:01:47 INFO - Using env: {'DISPLAY': ':0', 12:01:47 INFO - 'HOME': '/home/cltbld', 12:01:47 INFO - 'LANG': 'en_US.UTF-8', 12:01:47 INFO - 'LANGUAGE': 'en_US:en', 12:01:47 INFO - 'LOGNAME': 'cltbld', 12:01:47 INFO - 'MAIL': '/var/mail/cltbld', 12:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:47 INFO - 'MOZ_NO_REMOTE': '1', 12:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:47 INFO - 'NO_EM_RESTART': '1', 12:01:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:47 INFO - 'PWD': '/builds/slave/test', 12:01:47 INFO - 'SHELL': '/bin/bash', 12:01:47 INFO - 'SHLVL': '1', 12:01:47 INFO - 'TERM': 'linux', 12:01:47 INFO - 'TMOUT': '86400', 12:01:47 INFO - 'USER': 'cltbld', 12:01:47 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:47 INFO - '_': '/tools/buildbot/bin/python'} 12:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:01:49 INFO - Installing collected packages: mozsystemmonitor 12:01:49 INFO - Running setup.py install for mozsystemmonitor 12:01:49 INFO - Successfully installed mozsystemmonitor 12:01:49 INFO - Cleaning up... 12:01:50 INFO - Return code: 0 12:01:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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', '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 0x7fd27ecddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', '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 12:01: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:01: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 jsonschema==2.5.1 12:01:50 INFO - Using env: {'DISPLAY': ':0', 12:01:50 INFO - 'HOME': '/home/cltbld', 12:01:50 INFO - 'LANG': 'en_US.UTF-8', 12:01:50 INFO - 'LANGUAGE': 'en_US:en', 12:01:50 INFO - 'LOGNAME': 'cltbld', 12:01:50 INFO - 'MAIL': '/var/mail/cltbld', 12:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:50 INFO - 'MOZ_NO_REMOTE': '1', 12:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:50 INFO - 'NO_EM_RESTART': '1', 12:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:50 INFO - 'PWD': '/builds/slave/test', 12:01:50 INFO - 'SHELL': '/bin/bash', 12:01:50 INFO - 'SHLVL': '1', 12:01:50 INFO - 'TERM': 'linux', 12:01:50 INFO - 'TMOUT': '86400', 12:01:50 INFO - 'USER': 'cltbld', 12:01:50 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:50 INFO - '_': '/tools/buildbot/bin/python'} 12:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:50 INFO - Downloading/unpacking jsonschema==2.5.1 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:01:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01:52 INFO - Downloading functools32-3.2.3-2.tar.gz 12:01:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:01:52 INFO - warning: no files found matching '*.txt' 12:01:52 INFO - no previously-included directories found matching 'build' 12:01:52 INFO - no previously-included directories found matching 'dist' 12:01:52 INFO - no previously-included directories found matching '.git*' 12:01:52 INFO - Installing collected packages: jsonschema, functools32 12:01:52 INFO - Running setup.py install for functools32 12:01:52 INFO - warning: no files found matching '*.txt' 12:01:52 INFO - no previously-included directories found matching 'build' 12:01:52 INFO - no previously-included directories found matching 'dist' 12:01:52 INFO - no previously-included directories found matching '.git*' 12:01:52 INFO - Successfully installed jsonschema functools32 12:01:52 INFO - Cleaning up... 12:01:52 INFO - Return code: 0 12:01:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:52 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 0x7fd27ecddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', '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 12:01:52 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 12:01:52 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 12:01:52 INFO - Using env: {'DISPLAY': ':0', 12:01:52 INFO - 'HOME': '/home/cltbld', 12:01:52 INFO - 'LANG': 'en_US.UTF-8', 12:01:52 INFO - 'LANGUAGE': 'en_US:en', 12:01:52 INFO - 'LOGNAME': 'cltbld', 12:01:52 INFO - 'MAIL': '/var/mail/cltbld', 12:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:52 INFO - 'MOZ_NO_REMOTE': '1', 12:01:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:52 INFO - 'NO_EM_RESTART': '1', 12:01:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:52 INFO - 'PWD': '/builds/slave/test', 12:01:52 INFO - 'SHELL': '/bin/bash', 12:01:52 INFO - 'SHLVL': '1', 12:01:52 INFO - 'TERM': 'linux', 12:01:52 INFO - 'TMOUT': '86400', 12:01:52 INFO - 'USER': 'cltbld', 12:01:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:52 INFO - '_': '/tools/buildbot/bin/python'} 12:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:01:52 INFO - Cleaning up... 12:01:52 INFO - Return code: 0 12:01:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:52 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 0x7fd27ecddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', '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 12:01:52 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 12:01:52 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 12:01:52 INFO - Using env: {'DISPLAY': ':0', 12:01:52 INFO - 'HOME': '/home/cltbld', 12:01:52 INFO - 'LANG': 'en_US.UTF-8', 12:01:52 INFO - 'LANGUAGE': 'en_US:en', 12:01:52 INFO - 'LOGNAME': 'cltbld', 12:01:52 INFO - 'MAIL': '/var/mail/cltbld', 12:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:52 INFO - 'MOZ_NO_REMOTE': '1', 12:01:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:52 INFO - 'NO_EM_RESTART': '1', 12:01:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:52 INFO - 'PWD': '/builds/slave/test', 12:01:52 INFO - 'SHELL': '/bin/bash', 12:01:52 INFO - 'SHLVL': '1', 12:01:52 INFO - 'TERM': 'linux', 12:01:52 INFO - 'TMOUT': '86400', 12:01:52 INFO - 'USER': 'cltbld', 12:01:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:52 INFO - '_': '/tools/buildbot/bin/python'} 12:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:52 INFO - Downloading/unpacking blobuploader==1.2.4 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01:54 INFO - Downloading blobuploader-1.2.4.tar.gz 12:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:01:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:01:54 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 12:01:54 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 12:01:54 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 12:01:54 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 12:01:54 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 12:01:54 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 12:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:01:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:01:55 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 12:01:55 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 12:01:55 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 12:01:55 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 12:01:55 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 12:01:55 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 12:01:55 INFO - Downloading docopt-0.6.1.tar.gz 12:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:01:55 INFO - Installing collected packages: blobuploader, requests, docopt 12:01:55 INFO - Running setup.py install for blobuploader 12:01:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:01:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:01:55 INFO - Running setup.py install for requests 12:01:56 INFO - Running setup.py install for docopt 12:01:56 INFO - Successfully installed blobuploader requests docopt 12:01:56 INFO - Cleaning up... 12:01:56 INFO - Return code: 0 12:01:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:56 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 0x7fd27ecddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', '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 12:01:56 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 12:01:56 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 12:01:56 INFO - Using env: {'DISPLAY': ':0', 12:01:56 INFO - 'HOME': '/home/cltbld', 12:01:56 INFO - 'LANG': 'en_US.UTF-8', 12:01:56 INFO - 'LANGUAGE': 'en_US:en', 12:01:56 INFO - 'LOGNAME': 'cltbld', 12:01:56 INFO - 'MAIL': '/var/mail/cltbld', 12:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:56 INFO - 'MOZ_NO_REMOTE': '1', 12:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:56 INFO - 'NO_EM_RESTART': '1', 12:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:56 INFO - 'PWD': '/builds/slave/test', 12:01:56 INFO - 'SHELL': '/bin/bash', 12:01:56 INFO - 'SHLVL': '1', 12:01:56 INFO - 'TERM': 'linux', 12:01:56 INFO - 'TMOUT': '86400', 12:01:56 INFO - 'USER': 'cltbld', 12:01:56 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:56 INFO - '_': '/tools/buildbot/bin/python'} 12:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:01:56 INFO - Running setup.py (path:/tmp/pip-VryxKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:01:56 INFO - Running setup.py (path:/tmp/pip-BepVm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:01:56 INFO - Running setup.py (path:/tmp/pip-h12RfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:01:56 INFO - Running setup.py (path:/tmp/pip-x_JvQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:01:56 INFO - Running setup.py (path:/tmp/pip-AI0OsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:01:56 INFO - Running setup.py (path:/tmp/pip-lvEk1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:01:56 INFO - Running setup.py (path:/tmp/pip-0fiJ0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:01:56 INFO - Running setup.py (path:/tmp/pip-X38IlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:01:57 INFO - Running setup.py (path:/tmp/pip-LqicSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:01:57 INFO - Running setup.py (path:/tmp/pip-Zrn8nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:01:57 INFO - Running setup.py (path:/tmp/pip-7ihCeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:01:57 INFO - Running setup.py (path:/tmp/pip-zQd839-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:01:57 INFO - Running setup.py (path:/tmp/pip-WVC4M4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:01:57 INFO - Running setup.py (path:/tmp/pip-io7pSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:57 INFO - Running setup.py (path:/tmp/pip-BSCMzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:01:57 INFO - Running setup.py (path:/tmp/pip-1Q9X4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:01:57 INFO - Running setup.py (path:/tmp/pip-oaHAyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:01:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:01:57 INFO - Running setup.py install for manifestparser 12:01:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:01:57 INFO - Running setup.py install for mozcrash 12:01:57 INFO - Running setup.py install for mozdebug 12:01:57 INFO - Running setup.py install for mozdevice 12:01:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:01:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:01:58 INFO - Running setup.py install for mozfile 12:01:58 INFO - Running setup.py install for mozhttpd 12:01:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:01:58 INFO - Running setup.py install for mozinfo 12:01:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:01:58 INFO - Running setup.py install for mozInstall 12:01:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:01:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:01:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:01:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:01:58 INFO - Running setup.py install for mozleak 12:01:58 INFO - Running setup.py install for mozlog 12:01:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:01:58 INFO - Running setup.py install for moznetwork 12:01:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:01:58 INFO - Running setup.py install for mozprocess 12:01:58 INFO - Running setup.py install for mozprofile 12:01:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:01:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:01:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:01:59 INFO - Running setup.py install for mozrunner 12:01:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:01:59 INFO - Running setup.py install for mozscreenshot 12:01:59 INFO - Running setup.py install for moztest 12:01:59 INFO - Running setup.py install for mozversion 12:01:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:01:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:01:59 INFO - Cleaning up... 12:01:59 INFO - Return code: 0 12:01:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:59 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')]} 12:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:59 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')]} 12:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:59 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 0x7fd27ecddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', '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 12:01:59 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 12:01:59 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 12:01:59 INFO - Using env: {'DISPLAY': ':0', 12:01:59 INFO - 'HOME': '/home/cltbld', 12:01:59 INFO - 'LANG': 'en_US.UTF-8', 12:01:59 INFO - 'LANGUAGE': 'en_US:en', 12:01:59 INFO - 'LOGNAME': 'cltbld', 12:01:59 INFO - 'MAIL': '/var/mail/cltbld', 12:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:59 INFO - 'MOZ_NO_REMOTE': '1', 12:01:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:59 INFO - 'NO_EM_RESTART': '1', 12:01:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:59 INFO - 'PWD': '/builds/slave/test', 12:01:59 INFO - 'SHELL': '/bin/bash', 12:01:59 INFO - 'SHLVL': '1', 12:01:59 INFO - 'TERM': 'linux', 12:01:59 INFO - 'TMOUT': '86400', 12:01:59 INFO - 'USER': 'cltbld', 12:01:59 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:59 INFO - '_': '/tools/buildbot/bin/python'} 12:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:01:59 INFO - Running setup.py (path:/tmp/pip-1WhYjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:01:59 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)) 12:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:01:59 INFO - Running setup.py (path:/tmp/pip-Eoeplj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:01:59 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)) 12:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:01:59 INFO - Running setup.py (path:/tmp/pip-rXZsxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:01:59 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)) 12:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:01:59 INFO - Running setup.py (path:/tmp/pip-Idp9pZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:01:59 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)) 12:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:00 INFO - Running setup.py (path:/tmp/pip-FD4VaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:00 INFO - Running setup.py (path:/tmp/pip-HAOZJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:00 INFO - Running setup.py (path:/tmp/pip-LydG1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:00 INFO - Running setup.py (path:/tmp/pip-ayg1J5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:00 INFO - Running setup.py (path:/tmp/pip-fRGRv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:00 INFO - Running setup.py (path:/tmp/pip-D3Fif0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:00 INFO - Running setup.py (path:/tmp/pip-oHGou2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:00 INFO - Running setup.py (path:/tmp/pip-p16wb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:00 INFO - Running setup.py (path:/tmp/pip-_6LDak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:00 INFO - Running setup.py (path:/tmp/pip-v37sJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:00 INFO - Running setup.py (path:/tmp/pip-zGC4vY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:00 INFO - Running setup.py (path:/tmp/pip-QbKI5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:00 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)) 12:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:00 INFO - Running setup.py (path:/tmp/pip-oKhcrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:00 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)) 12:02:00 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)) 12:02:00 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)) 12:02:00 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)) 12:02:00 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)) 12:02:00 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)) 12:02:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:02:00 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 12:02:00 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 12:02:00 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 12:02:00 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 12:02:00 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 12:02:00 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 12:02:02 INFO - Downloading blessings-1.6.tar.gz 12:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:02:02 INFO - Installing collected packages: blessings 12:02:02 INFO - Running setup.py install for blessings 12:02:03 INFO - Successfully installed blessings 12:02:03 INFO - Cleaning up... 12:02:03 INFO - Return code: 0 12:02:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:03 INFO - Reading from file tmpfile_stdout 12:02:03 INFO - Current package versions: 12:02:03 INFO - argparse == 1.2.1 12:02:03 INFO - blessings == 1.6 12:02:03 INFO - blobuploader == 1.2.4 12:02:03 INFO - docopt == 0.6.1 12:02:03 INFO - functools32 == 3.2.3-2 12:02:03 INFO - jsonschema == 2.5.1 12:02:03 INFO - manifestparser == 1.1 12:02:03 INFO - mozInstall == 1.12 12:02:03 INFO - mozcrash == 0.17 12:02:03 INFO - mozdebug == 0.1 12:02:03 INFO - mozdevice == 0.48 12:02:03 INFO - mozfile == 1.2 12:02:03 INFO - mozhttpd == 0.7 12:02:03 INFO - mozinfo == 0.9 12:02:03 INFO - mozleak == 0.1 12:02:03 INFO - mozlog == 3.3 12:02:03 INFO - moznetwork == 0.27 12:02:03 INFO - mozprocess == 0.23 12:02:03 INFO - mozprofile == 0.28 12:02:03 INFO - mozrunner == 6.12 12:02:03 INFO - mozscreenshot == 0.1 12:02:03 INFO - mozsystemmonitor == 0.3 12:02:03 INFO - moztest == 0.7 12:02:03 INFO - mozversion == 1.4 12:02:03 INFO - psutil == 3.1.1 12:02:03 INFO - requests == 1.2.3 12:02:03 INFO - wsgiref == 0.1.2 12:02:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:03 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 0x7fd27ecddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', '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 12:02:03 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 12:02:03 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 12:02:03 INFO - Using env: {'DISPLAY': ':0', 12:02:03 INFO - 'HOME': '/home/cltbld', 12:02:03 INFO - 'LANG': 'en_US.UTF-8', 12:02:03 INFO - 'LANGUAGE': 'en_US:en', 12:02:03 INFO - 'LOGNAME': 'cltbld', 12:02:03 INFO - 'MAIL': '/var/mail/cltbld', 12:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:03 INFO - 'MOZ_NO_REMOTE': '1', 12:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:03 INFO - 'NO_EM_RESTART': '1', 12:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:03 INFO - 'PWD': '/builds/slave/test', 12:02:03 INFO - 'SHELL': '/bin/bash', 12:02:03 INFO - 'SHLVL': '1', 12:02:03 INFO - 'TERM': 'linux', 12:02:03 INFO - 'TMOUT': '86400', 12:02:03 INFO - 'USER': 'cltbld', 12:02:03 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:03 INFO - '_': '/tools/buildbot/bin/python'} 12:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:03 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)) 12:02:03 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)) 12:02:03 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)) 12:02:03 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)) 12:02:03 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)) 12:02:03 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)) 12:02:03 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)) 12:02:03 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)) 12:02:03 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)) 12:02:03 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)) 12:02:03 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)) 12:02:03 INFO - Cleaning up... 12:02:03 INFO - Return code: 0 12:02:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27ecddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, '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': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', '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 12:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:02:03 INFO - Using env: {'DISPLAY': ':0', 12:02:03 INFO - 'HOME': '/home/cltbld', 12:02:03 INFO - 'LANG': 'en_US.UTF-8', 12:02:03 INFO - 'LANGUAGE': 'en_US:en', 12:02:03 INFO - 'LOGNAME': 'cltbld', 12:02:03 INFO - 'MAIL': '/var/mail/cltbld', 12:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:03 INFO - 'MOZ_NO_REMOTE': '1', 12:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:03 INFO - 'NO_EM_RESTART': '1', 12:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:03 INFO - 'PWD': '/builds/slave/test', 12:02:03 INFO - 'SHELL': '/bin/bash', 12:02:03 INFO - 'SHLVL': '1', 12:02:03 INFO - 'TERM': 'linux', 12:02:03 INFO - 'TMOUT': '86400', 12:02:03 INFO - 'USER': 'cltbld', 12:02:03 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:03 INFO - '_': '/tools/buildbot/bin/python'} 12:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:02:03 INFO - Cleaning up... 12:02:03 INFO - Return code: 0 12:02:03 INFO - Running post-action listener: _resource_record_post_action 12:02:03 INFO - Running post-action listener: _start_resource_monitoring 12:02:03 INFO - Starting resource monitoring. 12:02:03 INFO - [mozharness: 2016-08-31 19:02:03.769835Z] Finished create-virtualenv step (success) 12:02:03 INFO - [mozharness: 2016-08-31 19:02:03.770295Z] Running install step. 12:02:03 INFO - Running pre-action listener: _resource_record_pre_action 12:02:03 INFO - Running main action method: install 12:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:03 INFO - Reading from file tmpfile_stdout 12:02:03 INFO - Detecting whether we're running mozinstall >=1.0... 12:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:02:04 INFO - Reading from file tmpfile_stdout 12:02:04 INFO - Output received: 12:02:04 INFO - Usage: mozinstall [options] installer 12:02:04 INFO - Options: 12:02:04 INFO - -h, --help show this help message and exit 12:02:04 INFO - -d DEST, --destination=DEST 12:02:04 INFO - Directory to install application into. [default: 12:02:04 INFO - "/builds/slave/test"] 12:02:04 INFO - --app=APP Application being installed. [default: firefox] 12:02:04 INFO - mkdir: /builds/slave/test/build/application 12:02:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:02:20 INFO - Reading from file tmpfile_stdout 12:02:20 INFO - Output received: 12:02:20 INFO - /builds/slave/test/build/application/firefox/firefox 12:02:20 INFO - Running post-action listener: _resource_record_post_action 12:02:20 INFO - [mozharness: 2016-08-31 19:02:20.192180Z] Finished install step (success) 12:02:20 INFO - [mozharness: 2016-08-31 19:02:20.192460Z] Running run-tests step. 12:02:20 INFO - Running pre-action listener: _resource_record_pre_action 12:02:20 INFO - Running main action method: run_tests 12:02:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:02:20 INFO - Python 2.7.3 12:02:20 INFO - Return code: 0 12:02:20 INFO - grabbing minidump binary from tooltool 12:02:20 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')]} 12:02:20 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 0x1219090>, '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 0x121c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121ce40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:02:20 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 12:02:20 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 12:02:20 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 12:02:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:02:20 INFO - Return code: 0 12:02:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:02:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:02:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:02:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:02:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:02:20 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/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 12:02:20 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/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 12:02:20 INFO - Using env: {'DISPLAY': ':0', 12:02:20 INFO - 'HOME': '/home/cltbld', 12:02:20 INFO - 'LANG': 'en_US.UTF-8', 12:02:20 INFO - 'LANGUAGE': 'en_US:en', 12:02:20 INFO - 'LOGNAME': 'cltbld', 12:02:20 INFO - 'MAIL': '/var/mail/cltbld', 12:02:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:02:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:20 INFO - 'MOZ_NO_REMOTE': '1', 12:02:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:02:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:20 INFO - 'NO_EM_RESTART': '1', 12:02:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:20 INFO - 'PWD': '/builds/slave/test', 12:02:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:02:20 INFO - 'SHELL': '/bin/bash', 12:02:20 INFO - 'SHLVL': '1', 12:02:20 INFO - 'TERM': 'linux', 12:02:20 INFO - 'TMOUT': '86400', 12:02:20 INFO - 'USER': 'cltbld', 12:02:20 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511', 12:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:20 INFO - '_': '/tools/buildbot/bin/python'} 12:02:20 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/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 12:02:20 INFO - mozversion application_buildid: 20160831113821 12:02:20 INFO - mozversion application_changeset: 2c986272197fedc498235e2a012c8a3e13993b58 12:02:20 INFO - mozversion application_display_name: Nightly 12:02:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:02:20 INFO - mozversion application_name: Firefox 12:02:20 INFO - mozversion application_remotingname: firefox 12:02:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 12:02:20 INFO - mozversion application_vendor: Mozilla 12:02:20 INFO - mozversion application_version: 51.0a1 12:02:20 INFO - mozversion platform_buildid: 20160831113821 12:02:20 INFO - mozversion platform_changeset: 2c986272197fedc498235e2a012c8a3e13993b58 12:02:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 12:02:20 INFO - mozversion platform_version: 51.0a1 12:02:20 INFO - using testdate: 1472670140 12:02:20 INFO - actual date: 1472670140 12:02:20 INFO - starting webserver on 'localhost:43198' 12:02:20 INFO - SUITE-START | Running 2 tests 12:02:20 INFO - TEST-START | dromaeo_css 12:02:20 INFO - operating with platform_type : linux_ 12:02:20 INFO - Initialising browser for dromaeo_css test... 12:02:20 INFO - TEST-INFO | started process 24963 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0ZlfiD/profile http://localhost:43198/getInfo.html) 12:02:25 INFO - PROCESS | 24963 | __metrics Screen width/height:1600/1200 12:02:25 INFO - PROCESS | 24963 | colorDepth:24 12:02:25 INFO - PROCESS | 24963 | Browser inner width/height: 1024/697 12:02:25 INFO - PROCESS | 24963 | __metrics 12:02:25 INFO - PROCESS | 24963 | 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" 12:02:25 INFO - PROCESS | 24963 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:02:26 INFO - PROCESS | 24963 | console.error: PushService: 12:02:26 INFO - PROCESS | 24963 | stateChangeProcessEnqueue: Error transitioning state 12:02:26 INFO - PROCESS | 24963 | UnknownError 12:02:27 INFO - TEST-INFO | 24963: exit 0 12:02:27 INFO - Browser initialized. 12:02:27 INFO - Running cycle 1/1 for dromaeo_css test... 12:02:27 INFO - TEST-INFO | started process 25046 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0ZlfiD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:02:39 INFO - PROCESS | 25046 | RSS: Main: 181866496 12:02:39 INFO - PROCESS | 25046 | 12:03:30 INFO - PROCESS | 25046 | 1472670210859 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] 12:05:43 INFO - PROCESS | 25046 | Cycle 1(1): loaded http://localhost:43198/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43198/tests/dromaeo/cssquery-ext.html) 12:05:44 INFO - PROCESS | 25046 | RSS: Main: 277245952 12:05:44 INFO - PROCESS | 25046 | 12:08:47 INFO - PROCESS | 25046 | Cycle 1(1): loaded http://localhost:43198/tests/dromaeo/cssquery-ext.html (next: http://localhost:43198/tests/dromaeo/cssquery-jquery.html) 12:08:48 INFO - PROCESS | 25046 | RSS: Main: 281583616 12:08:48 INFO - PROCESS | 25046 | 12:11:50 INFO - PROCESS | 25046 | Cycle 1(1): loaded http://localhost:43198/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43198/tests/dromaeo/cssquery-mootools.html) 12:11:50 INFO - PROCESS | 25046 | RSS: Main: 254652416 12:11:50 INFO - PROCESS | 25046 | 12:14:53 INFO - PROCESS | 25046 | Cycle 1(1): loaded http://localhost:43198/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43198/tests/dromaeo/cssquery-prototype.html) 12:14:53 INFO - PROCESS | 25046 | RSS: Main: 219594752 12:14:53 INFO - PROCESS | 25046 | 12:17:56 INFO - PROCESS | 25046 | Cycle 1(1): loaded http://localhost:43198/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43198/tests/dromaeo/cssquery-yui.html) 12:17:57 INFO - PROCESS | 25046 | RSS: Main: 220278784 12:17:57 INFO - PROCESS | 25046 | 12:21:00 INFO - PROCESS | 25046 | Cycle 1(1): loaded http://localhost:43198/tests/dromaeo/cssquery-yui.html (next: http://localhost:43198/tests/dromaeo/cssquery-dojo.html) 12:21:00 INFO - PROCESS | 25046 | RSS: Main: 311455744 12:21:00 INFO - PROCESS | 25046 | 12:21:00 INFO - PROCESS | 25046 | __start_tp_report 12:21:00 INFO - PROCESS | 25046 | _x_x_mozilla_page_load 12:21:00 INFO - PROCESS | 25046 | _x_x_mozilla_page_load_details 12:21:00 INFO - PROCESS | 25046 | |i|pagename|runs| 12:21:00 INFO - PROCESS | 25046 | |0;dojo.html;2536.518061568302;2537.340291524203;2563.8589877556738;2565.8203925725184;2599.454114635925;4450.732956022641;4524.751138687371;4581.289900065487;4622.422197225347;4625.4911959684405;4499.6625253105985;4529.773511324437;4535.115652447774;4539.614132798716;4617.584417402433;4163.333866581347;4177.707560470768;4204.852830150945;4206.053637931472;4333.025069359401;4275.40144379787;4285.742855428669;4286.142771445708;4311.956880431187;4322.13557288542;4056.391541268804;4096.201240758053;4219.535851056381;4221.725587836796;4260.38224457455;4152.2525945330135;4166.64583510402;4174.499060112776;4176.958230417687;4176.979115104405;4146.295129827907;4149.585041495826;4150.045489537423;4150.107726838744;4151.771652559108;4474.664400170001;4519.502854685982;4523.32150177476;4524.2082635538645;4533.52397998212;4769.880752981203;4803.543663352011;4891.804327826882;4898.779554920037;4901.313816065754;4925.975370123162;4926.989967056745;4931.432885218174;4932.457429682732;4994.101061808873;4440.35614835847;4566.086782643484;4600.930986035212;4611.1023060464195;4660;4932.038252540792;5015.4733752956145;5016.0971025215085;5034.572061374824;5057.570106540912;4841.685290456109;4850.611951043908;4863.270509423614;4866.829660961849;4907.190313598217;4801.063792560487;4835.6131509479155;4941.530554597308;4952.133376659071;5006.824761133343;4741.739204343725;4768.713877167381;4778.904421911542;4833.661643684979;4838.879028024327;4755.857324280277;4758.381410416624;4758.738269395148;4770.236762118045;4826.734529600905;4003.7597744134864;4053.493313335833;4063.329550624173;4073.06319546506;4111.362738775495;3599.3341231872187;3620.0225938996323;3631.019624701316;3644.526211592476;3712.2575484903127;4238.766867822299;4240.957590424118;4243.384709217146;4268.850590229327;4298.312270036779;4697.647676424282;4901.705897646153;4914.238293064581;4921.729304888266;4982.277569752366;4186.099988502426;4222.092250166229;4225.64082053029;4227.027783609787;4268.509121451073;4388.100439409914;4388.517263101056;4395.956040439619;4421.911561768747;4441.200583894865;4456.131054444482;4607.516210797818;4608.9308660370125;4613.054323863667;4665.346851440733;4819.783109759999;4835.734034628129;4847.854564363075;4886;4945.060438516671;4549.112922979987;4571.885702857455;4579.74811385377;4580.450345958507;4638.930416043694;4666.836660716858;4791.496892826342;4801.615870730263;4813.470518243061;4861.124997500484;4763.071201115749;4777.95222047775;4811.158047341772;4828.2998965150555;4836.56470917605;4696.530346965276;4749.857504274877;4751.026039661931;4756.857294281177;4818.349522814376;4727.669063165546;4739.834105806281;4743.169945259635;4746.62027037843;4768.737719425465;4556.544345565417;4639.8144074238;4648.86053418398;4669.252919532859;4670.439547254351;4780.474147843804;4781.737004464649;4785.330053792542;4829.492903245249;4842.346283251716;4540.432445944257;4660.487346391962;4661.650376221729;4663.27719203524;4722.456917454537 12:21:00 INFO - PROCESS | 25046 | |1;ext.html;574.804566447408;576;578.5198285423097;588.496835205899;593.8841925824463;29869.850650746743;38194.23611527768;39427.21145577091;39591.80204098976;43250.78374608122;40226.7988660057;40692;40746.185076298454;40774.796126019406;40779.184416311655;7737.535747855132;7873.133955264916;7886.723964661238;7891.763247102596;7891.960540197291;7722.961385193066;7808.570528620923;7809.60951952403;7815.843683126334;7818.530888146729;7817.452778305521;7822.022247219098;7859.449838511307;7863.646135923897;7869.449138560303;7732.188120247378;7763.805904852367;7772.494787838829;7773.805654858617;7776.922230777733;7688.077430708295;7751.379889608874;7756.767296981043;7759.030121234846;7763.573003484863;38491.23017539633;39081;39142;39169.804150979064;39196.60803391958;2866.784991125674;2877.295621658582;2924.7513961313316;2952.8818847246084;2989.8206107633396;27364.179074627795;28203;28382;28563.57154642682;29834.850825745732;3437.503119104894;3515.103648569624;3564.9108772280642;3569.5002699621814;3596.226811235597;252803;253652;253822;254036;254355;50890;51201.74399127981;51206;51332.48667513352;51340.22989655158;32216.67783322184;33194.8340258302;33333.3333333332;35082.473762893576;53914.19128713072;13258.40337184829;13388.397522111529;13412.664683382798;13414.262215578237;13434.731305374033;10415.114715249138;10501.474926253657;10509.054185123376;10513.421761803022;10514.631987880388;17553.64892702139;17555.73666395005;17600.823991759917;17612.383566575107;17790.55523611894;6671.566348187353;6784.710904928048;6991.720331186797;7004.789856304319;7027.156741190987;790.3519114326313;844.2106630300689;924.6255266617031;928.9178107504681;968.6851773173747;26940.730592694214;27515.324561228266;27919.860400697868;27946.89700572518;28276;878.2403221213605;885.685581618529;901.8466860633708;914.1863741270142;918.0498184379136;13992.30038498071;14673.266336683124;14678.706425871424;14722.263886805831;14836.856958954215;403339;409008;409415;410190;410528;48688.75655621699;49169.26246106311;49339.10574308165;49379;49381.75309123431;1049.7638031442864;1083.8482612434266;1117.6367680503706;1134.6652737442473;1146.6273461125168;25840.353991150372;28571.428571428038;29445.558316625695;29647.51762411914;29666.550366346182;41423.37864932089;41904.79047604743;41986.37020444635;41992;42006.36990445085;304786;306989;307023;307196;307199;21945.15959707013;24144.87927560351;24922.750772492767;25126.874365628053;25230.369240769127;40541.18917621572;41119.177616446905;41248.587514124476;41692.791536042125;42282.36576451294;35035.299294013465;35292.823535882155;35613.64386356207;35720;36386.09034774152;34960.650393496755;35106.82446587751;35264.471032934016;35441.29117417689;35890.820545897106;34470;34705.65294347024;35200;35834;35925.28149437049;29826.701732982394;31816.840915795274;32011;32151.839240803645;32221.194470138435;24546.263612091665;24835.379115522257;24921.875390622932;25065.87467062653;26717 12:21:00 INFO - PROCESS | 25046 | |2;jquery.html;368.6019099372678;373.87101449999756;378.25483796920065;386.30272358393125;386.9864554740583;15504.302306396212;16901;17569.209385577662;17703.55741106473;17713.82286177138;13528.797068043987;13967.301634918238;14096.929515352409;14285.571432857005;14470;4585.701929374588;4851.175300198975;4851.63612729045;4852.102361063197;4854.514548545152;3482.112061424346;4760.785764640599;4827.951720482785;5790.247267855173;6107.144999700045;6131.816045518642;6147.170132032185;6149.661768602725;6152.169457123298;6155.138280640714;6003.519718422537;6115.633062016249;6141.311139436646;6158.630482171039;6165.907511387333;5240.842774716764;5459.153831156177;5461.863453413656;5466.426025267369;5466.835994920159;12289.69275768104;12336.938315308365;12346.691332716753;12393.62819115428;12406.75186496265;1836.8438682711985;1899.5725961658516;2154.526004279056;2311.248844125649;2377.0848223434105;8714.389992700513;9181.21959633432;9549.904500954972;9697.612095516171;9907.950460247725;1669.5659128626523;1678.5803549112723;1695.5761059735066;1730.6711724772379;1770.3538208553914;70402;73363.63318183375;73451.63274183594;74760;74803.62598187028;10020.34867733595;10024.147947424404;10220.591176352862;10222.386656800616;10383.792324153475;13956;14064.929675351557;14152.504662336769;14166.575002749934;14170.929145354414;6680.164979377578;6866.519343645997;6947.270536593686;7158.570485770871;7192.964035179895;6364.395382438661;6394.680265986682;6586.670666466657;6594.208694956636;6603.339666033455;4312.525622181557;4382.408374869352;4421.115776844644;4444.088961762831;4525.027119169395;1619.3117924881878;1716.1162001569203;1760.4542591796585;1814.600787826676;1826.4794533557872;804.7907544038475;807.9798005049921;850.6427300533791;862.985991460032;925.8472352061834;15928.442504512286;16641.50075497737;16739.414120505968;16769.413070542472;17565;823.8352329534117;827.6317038917745;844.4764246167415;891.148952750122;902.4314681750456;9211.539423028957;9647.517624118766;9687.370320929118;9712.271579631555;9858.950705246429;358621;359004;362357;365244;377979;79890.60054699806;82600;83276;84814.57592711996;85976;808.3856269235306;883.4566741453959;896.3008853094597;937.3672770879765;938.2306508662831;14028;14473.493427729978;14884.702305954037;16000;16178.67642647164;38026;38122.618773811904;38765;39120.80439597784;39889.401658975716;47966.76016620034;48640;48686.75656621836;49861.750691246314;51334;7474.92525074764;7538.623068846646;7539.32146106853;7702.383809295355;7964.084130325086;8613.397062205586;9817.950910245689;11451.656450306504;11986.880131198925;12695.301758403008;10577.841332380174;10658.73353166177;10756.300840445347;10801.837972430578;10897.945510272399;10406.635767748092;10614.363138211733;10676.199285053497;10710.571577136827;10736.785264294827;10553.8944610556;10739.248252622241;10754.40850753216;11092.611758588411;11331.546738130382;7719.922800772145;7893.644785984759;7993.760187194393;8107.22981316774;8115.1479094694205;8154.796130096795;10669.679909602724;10708.73228169302;10715.410652414192;10964.451777410937 12:21:00 INFO - PROCESS | 25046 | |3;mootools.html;1347.4542810161886;1377.5591810620601;1449.6665766873618;1637.4923773630182;1674.9330026798928;6848;7276.16324122726;7644.885326720104;8112.472689275193;8120.837583248332;7548.660310286036;7960.442769006172;7967.681292748283;7968.561729104897;7987.20127987201;2209.8453108282465;2233;2235.4970131720315;2235.932922012334;2239.5185035217423;1867.0664667666167;2130.20117455954;2175.82593392529;2179.3244094330807;2205.40454077399;2172.663237198229;2183.3449965010514;2189.5839790439845;2194.7585765565773;2198.4503874031493;2090.351990882831;2096.6540520814046;2134.1356750516065;2136.156218293781;2137.0810551462864;2088.885111318877;2089.3105275259127;2095.057224249079;2108.694239335288;2143.3141394753684;7846.921530784676;7853.253940875607;7892.092409372881;7909.327707144899;7961.323287520568;4465.732056076646;4606.493285738566;4606.516315786827;4609.124266389375;4609.631229501631;2844.573314002916;2971.5691224772504;3011.262240751008;3019.230096325423;3022.637283525969;947.8672985781996;982.1013772398247;986.7039888033561;1003.6236411345745;1005.8843233028149;10659.093977011964;10701.83947240792;10768.892311076866;10803.757567879795;10814.945925270402;4133.318002529549;4237.8304867805555;4319.33050377192;4374.21264172452;4386.8025938832825;9219.769505762275;9231;9231.584578693975;9246.289436454277;9248.028956959557;4587.908241835212;4622.422197225347;4625.537446255347;4631.374764406829;4635.536446355338;4079.184163167319;4165.208610364081;4194.937075943863;4204.327307630764;4224.38746381779;3632.56409230894;3681.7054635629083;3731.197792474631;3775.7923314217983;3781.8865434037025;1073.3613499967507;1121.5513794482272;1156.3813359852438;1184.3663639952583;1212.0727643352843;784.9803754906173;791.4499422901029;792.5363662257644;802.2058162419163;824.2128767027474;5073.3911930568565;5102.948970510248;5186.066508028591;5242.554382877498;5251.054810134213;805.6052534258289;814.6130587970666;828.4739190613883;847.3009766942174;880.8458519759145;3521.1197200699826;3601.549806274216;3640.344737947195;3653.287608916236;3761.4733937248807;9340.439573625605;9843.360181588174;9844.852327215098;9854.655087071918;9861.260405469618;9753.317267791328;9786.804263914824;9789.314747967715;9797.31418800677;9798.804023919623;7036.296370363025;7112.110986126734;7119.92880071203;7151.141862976476;7204.063471748639;5020.849374518771;5086.872828179326;5145.408278048072;5162.303089083076;5195.220716892496;8586.313094952264;8850.114988501098;8912.509811960404;8926.196642301964;8951.910480895107;8967.417117887577;9063.864042039242;9103.271738261055;9139.131782480654;9157.130072643089;6510.73957041732;6579.749847528954;6579.835504112244;6580.8354791130605;6583;6927.480438966997;6983.895241571279;7097.645117744195;7238.963805180941;7265.709371625076;5213.40045894727;6630.602163870183;6651.667416629246;6736.157980252468;6970.302969702989;6790.388865002174;6850.554713943578;6866.588004719732;6888.586684798928;6896.379325860697;6047.0627052806885;6146;6146.754129834757;6148.385161483816;6151.446369826737;4462.129884672458;4497.145542346943;4581.839635612738;4670.673052886265;4703.976480117714;4370.300751879684;4396.428464299621;4503.819847206206;4580.152671755736;4589.082183563234 12:21:00 INFO - PROCESS | 25046 | |4;prototype.html;285.48897473522396;298.8416139446094;302.4404851025603;303.76154718545934;305.1060393048367;4683.250679891218;4692.8122875085;4708.62331013519;4717.245240761479;4744.28835674649;4625.5836974672275;4627.8380256691025;4653.976730116345;4693.882652933678;4695.530446955303;3728.6830619397247;3734.5705243897028;3736.234072015244;3736.719746019046;3737.103095257146;3504.369213541574;3734.533183352081;3737.6075512071247;3741.86903458379;3770.6983441324764;3692.7045836333164;3721.3487639663085;3722;3723.441483777428;3735.337774690264;3655.780653160819;3674.540682414698;3712.6658600725927;3716.8699095531797;3726.962730372689;3064.494358430856;3094.932248374307;3216.5818443602416;3241.060092573151;3242.1084201844446;4520.2315606347;4544.568266014723;4553.089382123555;4581.152486789954;4590.357349971006;4086.223617512663;4121.690873184523;4126.66986641068;4136.255474014676;4151.446860180997;4651.534846515356;4708.364370809965;4865.343178670905;4879.121758083544;4938.62960277977;3507.473878918183;3528.8235588220487;3536.6816986471085;3538.274653695986;3549.361114999299;5119.59043276537;5119.718415487147;5135.537801597837;5157.381114266274;5162.406323272834;3571.6784789628005;3610.494530765695;3627.383344831385;3643.9271214576092;3664.5785734640326;3659.579148397915;3670.284294562588;3673.246984368198;3692.2430901665093;3716.7026637868908;4168.645665118499;4183.581641835792;4187.078842654611;4200.957990420118;4201.957980420218;4906.705597664151;4908.435529914034;4920.606351491872;4929.309896614477;4929.605631549467;1236.437420973455;1256.5916077274921;1262.61490245476;1273.6624794429483;1275.4388069249685;784.2902173532962;809.7327881798992;816.1348970091603;816.9665373302777;818.6643476174739;3867.45855580219;3912.5109361329833;3920.2355540669864;3950.466686997276;3960.6039396060164;4463.085067561142;4479.3057076153245;4483.327500874895;4524.6832721709825;4528.88677783058;3870.3807390817333;3880.476135721698;3916.74541154824;3935.193285376491;3938.428927805452;2331.661909023216;2334.1298464856977;2378.881055947196;2393.4495066134537;2421.2615152378694;4272.893177670583;4560.201964656172;4654.790534425959;4774.832880849153;4807.807687692523;5218.530332270038;5232.502912223333;5233.764480598382;5249.028929648028;5255.47445255479;4789.976050119727;4807.399075115611;4812.326274321597;4812.8796780080775;4818.301346304836;4969.82605608802;5039.974800125976;5080.898382032265;5111.900941297639;5112.693238405707;5000.57495112905;5001.899582091934;5031.6729412588065;5038.949610503848;5041.143005689115;4830.516948305141;4838.274258861199;4849.830255941025;4851.830185943475;4866.95133048665;3531.876384326536;3618.9457158142122;3652.9634703652623;3655.0862284428895;3658.5975542690817;5103.285540024473;5105.361829771278;5110.923336149886;5119.820806271762;5124.308218390619;4965.900681986268;4984.900301994012;4988.1270777613045;4991.826920673711;4992.650514463952;4669.276262179367;4969.3291405659775;4969.676970996874;4982.57996471004;4983.800647974041;4823.5658790709;4851;4865.878353041202;4916.164252077084;4924.138275801649;4959.925601116059;4989.12690279207;4997.625178111583;5011.937942677849;5037.395512538372;4766.571008609242;4853.98559597763;4856.271559266138;4858.4898585648;4877.829275975324 12:21:00 INFO - PROCESS | 25046 | |5;yui.html;578.5834199376449;661.6625520984296;709.8899670551067;712.7256006437522;730.813642521157;10933.726656833584;12501.249925004495;13060.673483162926;13080.280584567845;13840;14113.92943035286;14825.55523334299;14832.406703731838;14866.108033517969;14919.32863021164;747.7906186267841;765.6401491299108;770.3259647808168;774.1175060431113;774.5391492062242;603.5805115444767;734.9706011759523;758.8216769059267;760.7907825348018;764.782037119421;803.0122948773013;804.9879251811227;805.118395357083;805.279275048831;805.279275048831;800.7877912353231;801.3148757812074;801.4870482890955;801.7193982106245;802.3701394405364;585.1982783586471;585.3122580967346;585.5081731345648;585.5666806563113;585.7481283048287;13326.533571325053;13392.464301427932;13415;13428.664283392896;13504.392302346418;12871.549495767696;12880.291583962879;13009.739805203842;13113.147645403018;13828.792568111487;9979.800403991952;9990.400575965392;10153.136983356424;10351.792964140675;10718.785624287548;3162.130414136108;3193.3613277344625;3214.164317277501;3273.5744353233927;3279.2949515854207;59467.40532594705;60522.999999999556;60850.69574652143;61164;61579.7684046321;10046.447445390573;10560.630377936888;10682.679519614425;10695.144388448905;10703.518341674644;19873.701894471593;19980.90009549943;20012.29956951529;20018.69971950422;20083.799162008192;6838.418734407532;7178.461615378763;7283.417326613858;7323.560586364835;7449.255074492507;3794.844645113596;3828.8659896903473;3850.3261929162286;3883.572806991229;3977.3835055565855;2803.90186343477;2916.1543152485965;2917.722816332445;2918.4454953558757;2924.1227631710403;485.2309090092243;494.40918102866726;498.18297991294304;500.314569040417;505.4263411028509;510.59662866335924;574.6954114319417;581.0934941491287;587.7795826565039;594.6402426531972;7847.215278472221;8027.317677997319;8032.357411407073;8093.595320233965;8241.011078670597;566.8299510147021;567.29088639201;576.7347020370593;579.5855962986485;590.2680675961776;3701.574318953301;3720.6837418819164;3819.904502387407;3828.1769419574925;4006.3790112532606;102642;105040.45052743133;105249.47375263227;105587.4720626392;106452;90828;94195;95368;95783;96892;76458;76725.6163719189;76782.61608692033;77248.61375693085;77608.61195693986;7146.64266786669;7302.707891684486;7356.190819010012;7468.701251949861;7554.848903021799;46112.702387915175;46641.13818374498;48879.266810998575;49223.507764921895;49749.75125124351;64807.35192648013;71381.64309178421;71642;71892.28107718856;71927.28072719416;9695.9030409695;10423.374597524173;10522.368657880552;10980.945095274472;11330.886691132982;12753.553625623059;12823.358832058173;13264.46942122343;13694.589162325146;14000.36998335057;11863.110266729856;11995.520179192734;12333;12859.742805144031;12885;11725;11874.525018999144;12774.552890648783;12782.488700451877;12783.105182637126;10557.208209384173;10889.401082940514;10947.890521095005;11885;12165.45255463524;8977;9415.435073895589;9563;9571.090746379083;9700.394022749406;8777.341699372444;8916.420432671855;9386.718398448058;9706.514674266116;9715.417074975525 12:21:00 INFO - PROCESS | 25046 | __end_tp_report 12:21:00 INFO - PROCESS | 25046 | __start_cc_report 12:21:00 INFO - PROCESS | 25046 | _x_x_mozilla_cycle_collect,893 12:21:00 INFO - PROCESS | 25046 | __end_cc_report 12:21:00 INFO - PROCESS | 25046 | __startTimestamp1472671260984__endTimestamp 12:21:00 INFO - PROCESS | 25046 | 12:21:00 INFO - PROCESS | 25046 | ------- Summary: start ------- 12:21:00 INFO - PROCESS | 25046 | Number of tests: 6 12:21:00 INFO - PROCESS | 25046 | 12:21:00 INFO - PROCESS | 25046 | [#0] dojo.html Cycles:165 Average:4503.17 Median:4622.42 stddev:466.87 (10.1%) stddev-sans-first:442.08 12:21:00 INFO - PROCESS | 25046 | Values: 2536.5 2537.3 2563.9 2565.8 2599.5 4450.7 4524.8 4581.3 4622.4 4625.5 4499.7 4529.8 4535.1 4539.6 4617.6 4163.3 4177.7 4204.9 4206.1 4333.0 4275.4 4285.7 4286.1 4312.0 4322.1 4056.4 4096.2 4219.5 4221.7 4260.4 4152.3 4166.6 4174.5 4177.0 4177.0 4146.3 4149.6 4150.0 4150.1 4151.8 4474.7 4519.5 4523.3 4524.2 4533.5 4769.9 4803.5 4891.8 4898.8 4901.3 4926.0 4927.0 4931.4 4932.5 4994.1 4440.4 4566.1 4600.9 4611.1 4660.0 4932.0 5015.5 5016.1 5034.6 5057.6 4841.7 4850.6 4863.3 4866.8 4907.2 4801.1 4835.6 4941.5 4952.1 5006.8 4741.7 4768.7 4778.9 4833.7 4838.9 4755.9 4758.4 4758.7 4770.2 4826.7 4003.8 4053.5 4063.3 4073.1 4111.4 3599.3 3620.0 3631.0 3644.5 3712.3 4238.8 4241.0 4243.4 4268.9 4298.3 4697.6 4901.7 4914.2 4921.7 4982.3 4186.1 4222.1 4225.6 4227.0 4268.5 4388.1 4388.5 4396.0 4421.9 4441.2 4456.1 4607.5 4608.9 4613.1 4665.3 4819.8 4835.7 4847.9 4886.0 4945.1 4549.1 4571.9 4579.7 4580.5 4638.9 4666.8 4791.5 4801.6 4813.5 4861.1 4763.1 4778.0 4811.2 4828.3 4836.6 4696.5 4749.9 4751.0 4756.9 4818.3 4727.7 4739.8 4743.2 4746.6 4768.7 4556.5 4639.8 4648.9 4669.3 4670.4 4780.5 4781.7 4785.3 4829.5 4842.3 4540.4 4660.5 4661.7 4663.3 4722.5 12:21:00 INFO - PROCESS | 25046 | 12:21:01 INFO - PROCESS | 25046 | [#1] ext.html Cycles:180 Average:46993.72 Median:35862.41 stddev:86841.52 (242.2%) stddev-sans-first:87015.20 12:21:01 INFO - PROCESS | 25046 | Values: 574.8 576.0 578.5 588.5 593.9 29869.9 38194.2 39427.2 39591.8 43250.8 40226.8 40692.0 40746.2 40774.8 40779.2 7737.5 7873.1 7886.7 7891.8 7892.0 7723.0 7808.6 7809.6 7815.8 7818.5 7817.5 7822.0 7859.4 7863.6 7869.4 7732.2 7763.8 7772.5 7773.8 7776.9 7688.1 7751.4 7756.8 7759.0 7763.6 38491.2 39081.0 39142.0 39169.8 39196.6 2866.8 2877.3 2924.8 2952.9 2989.8 27364.2 28203.0 28382.0 28563.6 29834.9 3437.5 3515.1 3564.9 3569.5 3596.2 252803.0 253652.0 253822.0 254036.0 254355.0 50890.0 51201.7 51206.0 51332.5 51340.2 32216.7 33194.8 33333.3 35082.5 53914.2 13258.4 13388.4 13412.7 13414.3 13434.7 10415.1 10501.5 10509.1 10513.4 10514.6 17553.6 17555.7 17600.8 17612.4 17790.6 6671.6 6784.7 6991.7 7004.8 7027.2 790.4 844.2 924.6 928.9 968.7 26940.7 27515.3 27919.9 27946.9 28276.0 878.2 885.7 901.8 914.2 918.0 13992.3 14673.3 14678.7 14722.3 14836.9 403339.0 409008.0 409415.0 410190.0 410528.0 48688.8 49169.3 49339.1 49379.0 49381.8 1049.8 1083.8 1117.6 1134.7 1146.6 25840.4 28571.4 29445.6 29647.5 29666.6 41423.4 41904.8 41986.4 41992.0 42006.4 304786.0 306989.0 307023.0 307196.0 307199.0 21945.2 24144.9 24922.8 25126.9 25230.4 40541.2 41119.2 41248.6 41692.8 42282.4 35035.3 35292.8 35613.6 35720.0 36386.1 34960.7 35106.8 35264.5 35441.3 35890.8 34470.0 34705.7 35200.0 35834.0 35925.3 29826.7 31816.8 32011.0 32151.8 32221.2 24546.3 24835.4 24921.9 25065.9 26717.0 12:21:01 INFO - PROCESS | 25046 | 12:21:01 INFO - PROCESS | 25046 | [#2] jquery.html Cycles:180 Average:23638.78 Median:4432.60 stddev:60669.30 (1368.7%) stddev-sans-first:60814.33 12:21:01 INFO - PROCESS | 25046 | Values: 368.6 373.9 378.3 386.3 387.0 15504.3 16901.0 17569.2 17703.6 17713.8 13528.8 13967.3 14096.9 14285.6 14470.0 4585.7 4851.2 4851.6 4852.1 4854.5 3482.1 4760.8 4828.0 5790.2 6107.1 6131.8 6147.2 6149.7 6152.2 6155.1 6003.5 6115.6 6141.3 6158.6 6165.9 5240.8 5459.2 5461.9 5466.4 5466.8 12289.7 12336.9 12346.7 12393.6 12406.8 1836.8 1899.6 2154.5 2311.2 2377.1 8714.4 9181.2 9549.9 9697.6 9908.0 1669.6 1678.6 1695.6 1730.7 1770.4 70402.0 73363.6 73451.6 74760.0 74803.6 10020.3 10024.1 10220.6 10222.4 10383.8 13956.0 14064.9 14152.5 14166.6 14170.9 6680.2 6866.5 6947.3 7158.6 7193.0 6364.4 6394.7 6586.7 6594.2 6603.3 4312.5 4382.4 4421.1 4444.1 4525.0 1619.3 1716.1 1760.5 1814.6 1826.5 804.8 808.0 850.6 863.0 925.8 15928.4 16641.5 16739.4 16769.4 17565.0 823.8 827.6 844.5 891.1 902.4 9211.5 9647.5 9687.4 9712.3 9859.0 358621.0 359004.0 362357.0 365244.0 377979.0 79890.6 82600.0 83276.0 84814.6 85976.0 808.4 883.5 896.3 937.4 938.2 14028.0 14473.5 14884.7 16000.0 16178.7 38026.0 38122.6 38765.0 39120.8 39889.4 47966.8 48640.0 48686.8 49861.8 51334.0 7474.9 7538.6 7539.3 7702.4 7964.1 8613.4 9818.0 11451.7 11986.9 12695.3 10577.8 10658.7 10756.3 10801.8 10897.9 10406.6 10614.4 10676.2 10710.6 10736.8 10553.9 10739.2 10754.4 11092.6 11331.5 7719.9 7893.6 7993.8 8107.2 8115.1 8154.8 10669.7 10708.7 10715.4 10964.5 12:21:01 INFO - PROCESS | 25046 | 12:21:01 INFO - PROCESS | 25046 | [#3] mootools.html Cycles:180 Average:5123.07 Median:5124.18 stddev:2886.52 (56.3%) stddev-sans-first:2880.67 12:21:01 INFO - PROCESS | 25046 | Values: 1347.5 1377.6 1449.7 1637.5 1674.9 6848.0 7276.2 7644.9 8112.5 8120.8 7548.7 7960.4 7967.7 7968.6 7987.2 2209.8 2233.0 2235.5 2235.9 2239.5 1867.1 2130.2 2175.8 2179.3 2205.4 2172.7 2183.3 2189.6 2194.8 2198.5 2090.4 2096.7 2134.1 2136.2 2137.1 2088.9 2089.3 2095.1 2108.7 2143.3 7846.9 7853.3 7892.1 7909.3 7961.3 4465.7 4606.5 4606.5 4609.1 4609.6 2844.6 2971.6 3011.3 3019.2 3022.6 947.9 982.1 986.7 1003.6 1005.9 10659.1 10701.8 10768.9 10803.8 10814.9 4133.3 4237.8 4319.3 4374.2 4386.8 9219.8 9231.0 9231.6 9246.3 9248.0 4587.9 4622.4 4625.5 4631.4 4635.5 4079.2 4165.2 4194.9 4204.3 4224.4 3632.6 3681.7 3731.2 3775.8 3781.9 1073.4 1121.6 1156.4 1184.4 1212.1 785.0 791.4 792.5 802.2 824.2 5073.4 5102.9 5186.1 5242.6 5251.1 805.6 814.6 828.5 847.3 880.8 3521.1 3601.5 3640.3 3653.3 3761.5 9340.4 9843.4 9844.9 9854.7 9861.3 9753.3 9786.8 9789.3 9797.3 9798.8 7036.3 7112.1 7119.9 7151.1 7204.1 5020.8 5086.9 5145.4 5162.3 5195.2 8586.3 8850.1 8912.5 8926.2 8951.9 8967.4 9063.9 9103.3 9139.1 9157.1 6510.7 6579.7 6579.8 6580.8 6583.0 6927.5 6983.9 7097.6 7239.0 7265.7 5213.4 6630.6 6651.7 6736.2 6970.3 6790.4 6850.6 6866.6 6888.6 6896.4 6047.1 6146.0 6146.8 6148.4 6151.4 4462.1 4497.1 4581.8 4670.7 4704.0 4370.3 4396.4 4503.8 4580.2 4589.1 12:21:01 INFO - PROCESS | 25046 | 12:21:01 INFO - PROCESS | 25046 | [#4] prototype.html Cycles:180 Average:4054.99 Median:4585.75 stddev:1193.28 (26.0%) stddev-sans-first:1162.60 12:21:01 INFO - PROCESS | 25046 | Values: 285.5 298.8 302.4 303.8 305.1 4683.3 4692.8 4708.6 4717.2 4744.3 4625.6 4627.8 4654.0 4693.9 4695.5 3728.7 3734.6 3736.2 3736.7 3737.1 3504.4 3734.5 3737.6 3741.9 3770.7 3692.7 3721.3 3722.0 3723.4 3735.3 3655.8 3674.5 3712.7 3716.9 3727.0 3064.5 3094.9 3216.6 3241.1 3242.1 4520.2 4544.6 4553.1 4581.2 4590.4 4086.2 4121.7 4126.7 4136.3 4151.4 4651.5 4708.4 4865.3 4879.1 4938.6 3507.5 3528.8 3536.7 3538.3 3549.4 5119.6 5119.7 5135.5 5157.4 5162.4 3571.7 3610.5 3627.4 3643.9 3664.6 3659.6 3670.3 3673.2 3692.2 3716.7 4168.6 4183.6 4187.1 4201.0 4202.0 4906.7 4908.4 4920.6 4929.3 4929.6 1236.4 1256.6 1262.6 1273.7 1275.4 784.3 809.7 816.1 817.0 818.7 3867.5 3912.5 3920.2 3950.5 3960.6 4463.1 4479.3 4483.3 4524.7 4528.9 3870.4 3880.5 3916.7 3935.2 3938.4 2331.7 2334.1 2378.9 2393.4 2421.3 4272.9 4560.2 4654.8 4774.8 4807.8 5218.5 5232.5 5233.8 5249.0 5255.5 4790.0 4807.4 4812.3 4812.9 4818.3 4969.8 5040.0 5080.9 5111.9 5112.7 5000.6 5001.9 5031.7 5038.9 5041.1 4830.5 4838.3 4849.8 4851.8 4867.0 3531.9 3618.9 3653.0 3655.1 3658.6 5103.3 5105.4 5110.9 5119.8 5124.3 4965.9 4984.9 4988.1 4991.8 4992.7 4669.3 4969.3 4969.7 4982.6 4983.8 4823.6 4851.0 4865.9 4916.2 4924.1 4959.9 4989.1 4997.6 5011.9 5037.4 4766.6 4854.0 4856.3 4858.5 4877.8 12:21:01 INFO - PROCESS | 25046 | 12:21:01 INFO - PROCESS | 25046 | [#5] yui.html Cycles:180 Average:18655.65 Median:502.87 stddev:27363.71 (5441.5%) stddev-sans-first:27406.81 12:21:01 INFO - PROCESS | 25046 | Values: 578.6 661.7 709.9 712.7 730.8 10933.7 12501.2 13060.7 13080.3 13840.0 14113.9 14825.6 14832.4 14866.1 14919.3 747.8 765.6 770.3 774.1 774.5 603.6 735.0 758.8 760.8 764.8 803.0 805.0 805.1 805.3 805.3 800.8 801.3 801.5 801.7 802.4 585.2 585.3 585.5 585.6 585.7 13326.5 13392.5 13415.0 13428.7 13504.4 12871.5 12880.3 13009.7 13113.1 13828.8 9979.8 9990.4 10153.1 10351.8 10718.8 3162.1 3193.4 3214.2 3273.6 3279.3 59467.4 60523.0 60850.7 61164.0 61579.8 10046.4 10560.6 10682.7 10695.1 10703.5 19873.7 19980.9 20012.3 20018.7 20083.8 6838.4 7178.5 7283.4 7323.6 7449.3 3794.8 3828.9 3850.3 3883.6 3977.4 2803.9 2916.2 2917.7 2918.4 2924.1 485.2 494.4 498.2 500.3 505.4 510.6 574.7 581.1 587.8 594.6 7847.2 8027.3 8032.4 8093.6 8241.0 566.8 567.3 576.7 579.6 590.3 3701.6 3720.7 3819.9 3828.2 4006.4 102642.0 105040.5 105249.5 105587.5 106452.0 90828.0 94195.0 95368.0 95783.0 96892.0 76458.0 76725.6 76782.6 77248.6 77608.6 7146.6 7302.7 7356.2 7468.7 7554.8 46112.7 46641.1 48879.3 49223.5 49749.8 64807.4 71381.6 71642.0 71892.3 71927.3 9695.9 10423.4 10522.4 10980.9 11330.9 12753.6 12823.4 13264.5 13694.6 14000.4 11863.1 11995.5 12333.0 12859.7 12885.0 11725.0 11874.5 12774.6 12782.5 12783.1 10557.2 10889.4 10947.9 11885.0 12165.5 8977.0 9415.4 9563.0 9571.1 9700.4 8777.3 8916.4 9386.7 9706.5 9715.4 12:21:01 INFO - PROCESS | 25046 | -------- Summary: end -------- 12:21:01 INFO - PROCESS | 25046 | 12:21:01 INFO - TEST-INFO | 25046: exit 0 12:21:01 INFO - TEST-OK | dromaeo_css | took 1121414ms 12:21:01 INFO - TEST-START | kraken 12:21:01 INFO - operating with platform_type : linux_ 12:21:01 INFO - Initialising browser for kraken test... 12:21:01 INFO - TEST-INFO | started process 25356 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkrgmPa/profile http://localhost:43198/getInfo.html) 12:21:06 INFO - PROCESS | 25356 | __metrics Screen width/height:1600/1200 12:21:06 INFO - PROCESS | 25356 | colorDepth:24 12:21:06 INFO - PROCESS | 25356 | Browser inner width/height: 1024/697 12:21:06 INFO - PROCESS | 25356 | __metrics 12:21:07 INFO - PROCESS | 25356 | 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" 12:21:07 INFO - PROCESS | 25356 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:21:07 INFO - PROCESS | 25356 | console.error: PushService: 12:21:07 INFO - PROCESS | 25356 | stateChangeProcessEnqueue: Error transitioning state 12:21:07 INFO - PROCESS | 25356 | UnknownError 12:21:08 INFO - TEST-INFO | 25356: exit 0 12:21:08 INFO - Browser initialized. 12:21:08 INFO - Running cycle 1/1 for kraken test... 12:21:08 INFO - TEST-INFO | started process 25436 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkrgmPa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:21:20 INFO - PROCESS | 25436 | RSS: Main: 181735424 12:21:20 INFO - PROCESS | 25436 | 12:21:23 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43198/tests/kraken/driver.html?testName=audio-beat-detection) 12:21:24 INFO - PROCESS | 25436 | RSS: Main: 244068352 12:21:24 INFO - PROCESS | 25436 | 12:21:27 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43198/tests/kraken/driver.html?testName=audio-dft) 12:21:27 INFO - PROCESS | 25436 | RSS: Main: 226521088 12:21:27 INFO - PROCESS | 25436 | 12:21:30 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43198/tests/kraken/driver.html?testName=audio-fft) 12:21:31 INFO - PROCESS | 25436 | RSS: Main: 304627712 12:21:31 INFO - PROCESS | 25436 | 12:21:33 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43198/tests/kraken/driver.html?testName=audio-oscillator) 12:21:34 INFO - PROCESS | 25436 | RSS: Main: 214360064 12:21:34 INFO - PROCESS | 25436 | 12:21:36 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43198/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:21:37 INFO - PROCESS | 25436 | RSS: Main: 215535616 12:21:37 INFO - PROCESS | 25436 | 12:21:40 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43198/tests/kraken/driver.html?testName=imaging-darkroom) 12:21:40 INFO - PROCESS | 25436 | RSS: Main: 253931520 12:21:40 INFO - PROCESS | 25436 | 12:21:44 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43198/tests/kraken/driver.html?testName=imaging-desaturate) 12:21:45 INFO - PROCESS | 25436 | RSS: Main: 247988224 12:21:45 INFO - PROCESS | 25436 | 12:21:47 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43198/tests/kraken/driver.html?testName=json-parse-financial) 12:21:48 INFO - PROCESS | 25436 | RSS: Main: 250703872 12:21:48 INFO - PROCESS | 25436 | 12:21:50 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43198/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:21:50 INFO - PROCESS | 25436 | RSS: Main: 214622208 12:21:50 INFO - PROCESS | 25436 | 12:21:52 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43198/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:21:53 INFO - PROCESS | 25436 | RSS: Main: 222801920 12:21:53 INFO - PROCESS | 25436 | 12:21:55 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43198/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:21:56 INFO - PROCESS | 25436 | RSS: Main: 222625792 12:21:56 INFO - PROCESS | 25436 | 12:21:59 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43198/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:21:59 INFO - PROCESS | 25436 | RSS: Main: 224215040 12:21:59 INFO - PROCESS | 25436 | 12:22:02 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43198/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:22:03 INFO - PROCESS | 25436 | RSS: Main: 222576640 12:22:03 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | Cycle 1(1): loaded http://localhost:43198/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43198/tests/kraken/driver.html?testName=ai-astar) 12:22:05 INFO - PROCESS | 25436 | RSS: Main: 272687104 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | __start_tp_report 12:22:05 INFO - PROCESS | 25436 | _x_x_mozilla_page_load 12:22:05 INFO - PROCESS | 25436 | _x_x_mozilla_page_load_details 12:22:05 INFO - PROCESS | 25436 | |i|pagename|runs| 12:22:05 INFO - PROCESS | 25436 | |0;ai-astar;115;114;135;114;113;114;113;114;113;114 12:22:05 INFO - PROCESS | 25436 | |1;audio-beat-detection;154;122;147;182;128;155;156;123;203;127 12:22:05 INFO - PROCESS | 25436 | |2;audio-dft;161;162;163;160;162;162;163;162;161;163 12:22:05 INFO - PROCESS | 25436 | |3;audio-fft;77;76;78;181;80;75;75;76;159;76 12:22:05 INFO - PROCESS | 25436 | |4;audio-oscillator;83;85;137;84;84;84;146;83;84;133 12:22:05 INFO - PROCESS | 25436 | |5;imaging-gaussian-blur;118;118;117;118;118;118;117;118;118;117 12:22:05 INFO - PROCESS | 25436 | |6;imaging-darkroom;147;148;146;147;147;147;147;147;146;147 12:22:05 INFO - PROCESS | 25436 | |7;imaging-desaturate;95;95;95;95;96;94;97;96;96;95 12:22:05 INFO - PROCESS | 25436 | |8;json-parse-financial;64;64;73;65;67;66;67;77;64;64 12:22:05 INFO - PROCESS | 25436 | |9;json-stringify-tinderbox;66;67;66;67;68;66;66;66;67;67 12:22:05 INFO - PROCESS | 25436 | |10;stanford-crypto-aes;83;83;83;83;83;83;83;83;91;82 12:22:05 INFO - PROCESS | 25436 | |11;stanford-crypto-ccm;134;133;148;159;142;133;132;132;175;146 12:22:05 INFO - PROCESS | 25436 | |12;stanford-crypto-pbkdf2;174;172;172;172;171;179;172;170;177;171 12:22:05 INFO - PROCESS | 25436 | |13;stanford-crypto-sha256-iterative;69;68;70;69;71;69;69;72;69;68 12:22:05 INFO - PROCESS | 25436 | __end_tp_report 12:22:05 INFO - PROCESS | 25436 | __start_cc_report 12:22:05 INFO - PROCESS | 25436 | _x_x_mozilla_cycle_collect,1986 12:22:05 INFO - PROCESS | 25436 | __end_cc_report 12:22:05 INFO - PROCESS | 25436 | __startTimestamp1472671325415__endTimestamp 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | ------- Summary: start ------- 12:22:05 INFO - PROCESS | 25436 | Number of tests: 14 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#0] ai-astar Cycles:10 Average:115.90 Median:114.00 stddev:6.74 (5.9%) stddev-sans-first:7.14 12:22:05 INFO - PROCESS | 25436 | Values: 115.0 114.0 135.0 114.0 113.0 114.0 113.0 114.0 113.0 114.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#1] audio-beat-detection Cycles:10 Average:149.70 Median:154.50 stddev:26.76 (17.3%) stddev-sans-first:28.34 12:22:05 INFO - PROCESS | 25436 | Values: 154.0 122.0 147.0 182.0 128.0 155.0 156.0 123.0 203.0 127.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 12:22:05 INFO - PROCESS | 25436 | Values: 161.0 162.0 163.0 160.0 162.0 162.0 163.0 162.0 161.0 163.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#3] audio-fft Cycles:10 Average:95.30 Median:76.00 stddev:39.74 (52.3%) stddev-sans-first:41.59 12:22:05 INFO - PROCESS | 25436 | Values: 77.0 76.0 78.0 181.0 80.0 75.0 75.0 76.0 159.0 76.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#4] audio-oscillator Cycles:10 Average:100.30 Median:84.00 stddev:26.67 (31.7%) stddev-sans-first:27.54 12:22:05 INFO - PROCESS | 25436 | Values: 83.0 85.0 137.0 84.0 84.0 84.0 146.0 83.0 84.0 133.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 12:22:05 INFO - PROCESS | 25436 | Values: 118.0 118.0 117.0 118.0 118.0 118.0 117.0 118.0 118.0 117.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 12:22:05 INFO - PROCESS | 25436 | Values: 147.0 148.0 146.0 147.0 147.0 147.0 147.0 147.0 146.0 147.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.84 (0.9%) stddev-sans-first:0.88 12:22:05 INFO - PROCESS | 25436 | Values: 95.0 95.0 95.0 95.0 96.0 94.0 97.0 96.0 96.0 95.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:66.50 stddev:4.43 (6.7%) stddev-sans-first:4.56 12:22:05 INFO - PROCESS | 25436 | Values: 64.0 64.0 73.0 65.0 67.0 66.0 67.0 77.0 64.0 64.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 12:22:05 INFO - PROCESS | 25436 | Values: 66.0 67.0 66.0 67.0 68.0 66.0 66.0 66.0 67.0 67.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.00 stddev:2.58 (3.1%) stddev-sans-first:2.73 12:22:05 INFO - PROCESS | 25436 | Values: 83.0 83.0 83.0 83.0 83.0 83.0 83.0 83.0 91.0 82.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:144.00 stddev:14.28 (9.9%) stddev-sans-first:14.74 12:22:05 INFO - PROCESS | 25436 | Values: 134.0 133.0 148.0 159.0 142.0 133.0 132.0 132.0 175.0 146.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:172.00 stddev:2.87 (1.7%) stddev-sans-first:3.02 12:22:05 INFO - PROCESS | 25436 | Values: 174.0 172.0 172.0 172.0 171.0 179.0 172.0 170.0 177.0 171.0 12:22:05 INFO - PROCESS | 25436 | 12:22:05 INFO - PROCESS | 25436 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.00 stddev:1.26 (1.8%) stddev-sans-first:1.33 12:22:05 INFO - PROCESS | 25436 | Values: 69.0 68.0 70.0 69.0 71.0 69.0 69.0 72.0 69.0 68.0 12:22:05 INFO - PROCESS | 25436 | -------- Summary: end -------- 12:22:05 INFO - PROCESS | 25436 | 12:22:06 INFO - TEST-INFO | 25436: exit 0 12:22:06 INFO - TEST-OK | kraken | took 64942ms 12:22:06 INFO - SUITE-END | took 1186s 12:22:07 INFO - Completed test suite (00:19:46) 12:22:07 INFO - javascript benchmark 12:22:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2536.518061568302, 2537.340291524203, 2563.8589877556738, 2565.8203925725184, 2599.454114635925, 4450.732956022641, 4524.751138687371, 4581.289900065487, 4622.422197225347, 4625.4911959684405, 4499.6625253105985, 4529.773511324437, 4535.115652447774, 4539.614132798716, 4617.584417402433, 4163.333866581347, 4177.707560470768, 4204.852830150945, 4206.053637931472, 4333.025069359401, 4275.40144379787, 4285.742855428669, 4286.142771445708, 4311.956880431187, 4322.13557288542, 4056.391541268804, 4096.201240758053, 4219.535851056381, 4221.725587836796, 4260.38224457455, 4152.2525945330135, 4166.64583510402, 4174.499060112776, 4176.958230417687, 4176.979115104405, 4146.295129827907, 4149.585041495826, 4150.045489537423, 4150.107726838744, 4151.771652559108, 4474.664400170001, 4519.502854685982, 4523.32150177476, 4524.2082635538645, 4533.52397998212, 4769.880752981203, 4803.543663352011, 4891.804327826882, 4898.779554920037, 4901.313816065754, 4925.975370123162, 4926.989967056745, 4931.432885218174, 4932.457429682732, 4994.101061808873, 4440.35614835847, 4566.086782643484, 4600.930986035212, 4611.1023060464195, 4660.0, 4932.038252540792, 5015.4733752956145, 5016.0971025215085, 5034.572061374824, 5057.570106540912, 4841.685290456109, 4850.611951043908, 4863.270509423614, 4866.829660961849, 4907.190313598217, 4801.063792560487, 4835.6131509479155, 4941.530554597308, 4952.133376659071, 5006.824761133343, 4741.739204343725, 4768.713877167381, 4778.904421911542, 4833.661643684979, 4838.879028024327, 4755.857324280277, 4758.381410416624, 4758.738269395148, 4770.236762118045, 4826.734529600905, 4003.7597744134864, 4053.493313335833, 4063.329550624173, 4073.06319546506, 4111.362738775495, 3599.3341231872187, 3620.0225938996323, 3631.019624701316, 3644.526211592476, 3712.2575484903127, 4238.766867822299, 4240.957590424118, 4243.384709217146, 4268.850590229327, 4298.312270036779, 4697.647676424282, 4901.705897646153, 4914.238293064581, 4921.729304888266, 4982.277569752366, 4186.099988502426, 4222.092250166229, 4225.64082053029, 4227.027783609787, 4268.509121451073, 4388.100439409914, 4388.517263101056, 4395.956040439619, 4421.911561768747, 4441.200583894865, 4456.131054444482, 4607.516210797818, 4608.9308660370125, 4613.054323863667, 4665.346851440733, 4819.783109759999, 4835.734034628129, 4847.854564363075, 4886.0, 4945.060438516671, 4549.112922979987, 4571.885702857455, 4579.74811385377, 4580.450345958507, 4638.930416043694, 4666.836660716858, 4791.496892826342, 4801.615870730263, 4813.470518243061, 4861.124997500484, 4763.071201115749, 4777.95222047775, 4811.158047341772, 4828.2998965150555, 4836.56470917605, 4696.530346965276, 4749.857504274877, 4751.026039661931, 4756.857294281177, 4818.349522814376, 4727.669063165546, 4739.834105806281, 4743.169945259635, 4746.62027037843, 4768.737719425465, 4556.544345565417, 4639.8144074238, 4648.86053418398, 4669.252919532859, 4670.439547254351, 4780.474147843804, 4781.737004464649, 4785.330053792542, 4829.492903245249, 4842.346283251716, 4540.432445944257, 4660.487346391962, 4661.650376221729, 4663.27719203524, 4722.456917454537], "value": 4473.762930648104, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.804566447408, 576.0, 578.5198285423097, 588.496835205899, 593.8841925824463, 29869.850650746743, 38194.23611527768, 39427.21145577091, 39591.80204098976, 43250.78374608122, 40226.7988660057, 40692.0, 40746.185076298454, 40774.796126019406, 40779.184416311655, 7737.535747855132, 7873.133955264916, 7886.723964661238, 7891.763247102596, 7891.960540197291, 7722.961385193066, 7808.570528620923, 7809.60951952403, 7815.843683126334, 7818.530888146729, 7817.452778305521, 7822.022247219098, 7859.449838511307, 7863.646135923897, 7869.449138560303, 7732.188120247378, 7763.805904852367, 7772.494787838829, 7773.805654858617, 7776.922230777733, 7688.077430708295, 7751.379889608874, 7756.767296981043, 7759.030121234846, 7763.573003484863, 38491.23017539633, 39081.0, 39142.0, 39169.804150979064, 39196.60803391958, 2866.784991125674, 2877.295621658582, 2924.7513961313316, 2952.8818847246084, 2989.8206107633396, 27364.179074627795, 28203.0, 28382.0, 28563.57154642682, 29834.850825745732, 3437.503119104894, 3515.103648569624, 3564.9108772280642, 3569.5002699621814, 3596.226811235597, 252803.0, 253652.0, 253822.0, 254036.0, 254355.0, 50890.0, 51201.74399127981, 51206.0, 51332.48667513352, 51340.22989655158, 32216.67783322184, 33194.8340258302, 33333.3333333332, 35082.473762893576, 53914.19128713072, 13258.40337184829, 13388.397522111529, 13412.664683382798, 13414.262215578237, 13434.731305374033, 10415.114715249138, 10501.474926253657, 10509.054185123376, 10513.421761803022, 10514.631987880388, 17553.64892702139, 17555.73666395005, 17600.823991759917, 17612.383566575107, 17790.55523611894, 6671.566348187353, 6784.710904928048, 6991.720331186797, 7004.789856304319, 7027.156741190987, 790.3519114326313, 844.2106630300689, 924.6255266617031, 928.9178107504681, 968.6851773173747, 26940.730592694214, 27515.324561228266, 27919.860400697868, 27946.89700572518, 28276.0, 878.2403221213605, 885.685581618529, 901.8466860633708, 914.1863741270142, 918.0498184379136, 13992.30038498071, 14673.266336683124, 14678.706425871424, 14722.263886805831, 14836.856958954215, 403339.0, 409008.0, 409415.0, 410190.0, 410528.0, 48688.75655621699, 49169.26246106311, 49339.10574308165, 49379.0, 49381.75309123431, 1049.7638031442864, 1083.8482612434266, 1117.6367680503706, 1134.6652737442473, 1146.6273461125168, 25840.353991150372, 28571.428571428038, 29445.558316625695, 29647.51762411914, 29666.550366346182, 41423.37864932089, 41904.79047604743, 41986.37020444635, 41992.0, 42006.36990445085, 304786.0, 306989.0, 307023.0, 307196.0, 307199.0, 21945.15959707013, 24144.87927560351, 24922.750772492767, 25126.874365628053, 25230.369240769127, 40541.18917621572, 41119.177616446905, 41248.587514124476, 41692.791536042125, 42282.36576451294, 35035.299294013465, 35292.823535882155, 35613.64386356207, 35720.0, 36386.09034774152, 34960.650393496755, 35106.82446587751, 35264.471032934016, 35441.29117417689, 35890.820545897106, 34470.0, 34705.65294347024, 35200.0, 35834.0, 35925.28149437049, 29826.701732982394, 31816.840915795274, 32011.0, 32151.839240803645, 32221.194470138435, 24546.263612091665, 24835.379115522257, 24921.875390622932, 25065.87467062653, 26717.0], "value": 17081.28730103359, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.6019099372678, 373.87101449999756, 378.25483796920065, 386.30272358393125, 386.9864554740583, 15504.302306396212, 16901.0, 17569.209385577662, 17703.55741106473, 17713.82286177138, 13528.797068043987, 13967.301634918238, 14096.929515352409, 14285.571432857005, 14470.0, 4585.701929374588, 4851.175300198975, 4851.63612729045, 4852.102361063197, 4854.514548545152, 3482.112061424346, 4760.785764640599, 4827.951720482785, 5790.247267855173, 6107.144999700045, 6131.816045518642, 6147.170132032185, 6149.661768602725, 6152.169457123298, 6155.138280640714, 6003.519718422537, 6115.633062016249, 6141.311139436646, 6158.630482171039, 6165.907511387333, 5240.842774716764, 5459.153831156177, 5461.863453413656, 5466.426025267369, 5466.835994920159, 12289.69275768104, 12336.938315308365, 12346.691332716753, 12393.62819115428, 12406.75186496265, 1836.8438682711985, 1899.5725961658516, 2154.526004279056, 2311.248844125649, 2377.0848223434105, 8714.389992700513, 9181.21959633432, 9549.904500954972, 9697.612095516171, 9907.950460247725, 1669.5659128626523, 1678.5803549112723, 1695.5761059735066, 1730.6711724772379, 1770.3538208553914, 70402.0, 73363.63318183375, 73451.63274183594, 74760.0, 74803.62598187028, 10020.34867733595, 10024.147947424404, 10220.591176352862, 10222.386656800616, 10383.792324153475, 13956.0, 14064.929675351557, 14152.504662336769, 14166.575002749934, 14170.929145354414, 6680.164979377578, 6866.519343645997, 6947.270536593686, 7158.570485770871, 7192.964035179895, 6364.395382438661, 6394.680265986682, 6586.670666466657, 6594.208694956636, 6603.339666033455, 4312.525622181557, 4382.408374869352, 4421.115776844644, 4444.088961762831, 4525.027119169395, 1619.3117924881878, 1716.1162001569203, 1760.4542591796585, 1814.600787826676, 1826.4794533557872, 804.7907544038475, 807.9798005049921, 850.6427300533791, 862.985991460032, 925.8472352061834, 15928.442504512286, 16641.50075497737, 16739.414120505968, 16769.413070542472, 17565.0, 823.8352329534117, 827.6317038917745, 844.4764246167415, 891.148952750122, 902.4314681750456, 9211.539423028957, 9647.517624118766, 9687.370320929118, 9712.271579631555, 9858.950705246429, 358621.0, 359004.0, 362357.0, 365244.0, 377979.0, 79890.60054699806, 82600.0, 83276.0, 84814.57592711996, 85976.0, 808.3856269235306, 883.4566741453959, 896.3008853094597, 937.3672770879765, 938.2306508662831, 14028.0, 14473.493427729978, 14884.702305954037, 16000.0, 16178.67642647164, 38026.0, 38122.618773811904, 38765.0, 39120.80439597784, 39889.401658975716, 47966.76016620034, 48640.0, 48686.75656621836, 49861.750691246314, 51334.0, 7474.92525074764, 7538.623068846646, 7539.32146106853, 7702.383809295355, 7964.084130325086, 8613.397062205586, 9817.950910245689, 11451.656450306504, 11986.880131198925, 12695.301758403008, 10577.841332380174, 10658.73353166177, 10756.300840445347, 10801.837972430578, 10897.945510272399, 10406.635767748092, 10614.363138211733, 10676.199285053497, 10710.571577136827, 10736.785264294827, 10553.8944610556, 10739.248252622241, 10754.40850753216, 11092.611758588411, 11331.546738130382, 7719.922800772145, 7893.644785984759, 7993.760187194393, 8107.22981316774, 8115.1479094694205, 8154.796130096795, 10669.679909602724, 10708.73228169302, 10715.410652414192, 10964.451777410937], "value": 8113.862225469444, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.4542810161886, 1377.5591810620601, 1449.6665766873618, 1637.4923773630182, 1674.9330026798928, 6848.0, 7276.16324122726, 7644.885326720104, 8112.472689275193, 8120.837583248332, 7548.660310286036, 7960.442769006172, 7967.681292748283, 7968.561729104897, 7987.20127987201, 2209.8453108282465, 2233.0, 2235.4970131720315, 2235.932922012334, 2239.5185035217423, 1867.0664667666167, 2130.20117455954, 2175.82593392529, 2179.3244094330807, 2205.40454077399, 2172.663237198229, 2183.3449965010514, 2189.5839790439845, 2194.7585765565773, 2198.4503874031493, 2090.351990882831, 2096.6540520814046, 2134.1356750516065, 2136.156218293781, 2137.0810551462864, 2088.885111318877, 2089.3105275259127, 2095.057224249079, 2108.694239335288, 2143.3141394753684, 7846.921530784676, 7853.253940875607, 7892.092409372881, 7909.327707144899, 7961.323287520568, 4465.732056076646, 4606.493285738566, 4606.516315786827, 4609.124266389375, 4609.631229501631, 2844.573314002916, 2971.5691224772504, 3011.262240751008, 3019.230096325423, 3022.637283525969, 947.8672985781996, 982.1013772398247, 986.7039888033561, 1003.6236411345745, 1005.8843233028149, 10659.093977011964, 10701.83947240792, 10768.892311076866, 10803.757567879795, 10814.945925270402, 4133.318002529549, 4237.8304867805555, 4319.33050377192, 4374.21264172452, 4386.8025938832825, 9219.769505762275, 9231.0, 9231.584578693975, 9246.289436454277, 9248.028956959557, 4587.908241835212, 4622.422197225347, 4625.537446255347, 4631.374764406829, 4635.536446355338, 4079.184163167319, 4165.208610364081, 4194.937075943863, 4204.327307630764, 4224.38746381779, 3632.56409230894, 3681.7054635629083, 3731.197792474631, 3775.7923314217983, 3781.8865434037025, 1073.3613499967507, 1121.5513794482272, 1156.3813359852438, 1184.3663639952583, 1212.0727643352843, 784.9803754906173, 791.4499422901029, 792.5363662257644, 802.2058162419163, 824.2128767027474, 5073.3911930568565, 5102.948970510248, 5186.066508028591, 5242.554382877498, 5251.054810134213, 805.6052534258289, 814.6130587970666, 828.4739190613883, 847.3009766942174, 880.8458519759145, 3521.1197200699826, 3601.549806274216, 3640.344737947195, 3653.287608916236, 3761.4733937248807, 9340.439573625605, 9843.360181588174, 9844.852327215098, 9854.655087071918, 9861.260405469618, 9753.317267791328, 9786.804263914824, 9789.314747967715, 9797.31418800677, 9798.804023919623, 7036.296370363025, 7112.110986126734, 7119.92880071203, 7151.141862976476, 7204.063471748639, 5020.849374518771, 5086.872828179326, 5145.408278048072, 5162.303089083076, 5195.220716892496, 8586.313094952264, 8850.114988501098, 8912.509811960404, 8926.196642301964, 8951.910480895107, 8967.417117887577, 9063.864042039242, 9103.271738261055, 9139.131782480654, 9157.130072643089, 6510.73957041732, 6579.749847528954, 6579.835504112244, 6580.8354791130605, 6583.0, 6927.480438966997, 6983.895241571279, 7097.645117744195, 7238.963805180941, 7265.709371625076, 5213.40045894727, 6630.602163870183, 6651.667416629246, 6736.157980252468, 6970.302969702989, 6790.388865002174, 6850.554713943578, 6866.588004719732, 6888.586684798928, 6896.379325860697, 6047.0627052806885, 6146.0, 6146.754129834757, 6148.385161483816, 6151.446369826737, 4462.129884672458, 4497.145542346943, 4581.839635612738, 4670.673052886265, 4703.976480117714, 4370.300751879684, 4396.428464299621, 4503.819847206206, 4580.152671755736, 4589.082183563234], "value": 4122.913599140719, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.48897473522396, 298.8416139446094, 302.4404851025603, 303.76154718545934, 305.1060393048367, 4683.250679891218, 4692.8122875085, 4708.62331013519, 4717.245240761479, 4744.28835674649, 4625.5836974672275, 4627.8380256691025, 4653.976730116345, 4693.882652933678, 4695.530446955303, 3728.6830619397247, 3734.5705243897028, 3736.234072015244, 3736.719746019046, 3737.103095257146, 3504.369213541574, 3734.533183352081, 3737.6075512071247, 3741.86903458379, 3770.6983441324764, 3692.7045836333164, 3721.3487639663085, 3722.0, 3723.441483777428, 3735.337774690264, 3655.780653160819, 3674.540682414698, 3712.6658600725927, 3716.8699095531797, 3726.962730372689, 3064.494358430856, 3094.932248374307, 3216.5818443602416, 3241.060092573151, 3242.1084201844446, 4520.2315606347, 4544.568266014723, 4553.089382123555, 4581.152486789954, 4590.357349971006, 4086.223617512663, 4121.690873184523, 4126.66986641068, 4136.255474014676, 4151.446860180997, 4651.534846515356, 4708.364370809965, 4865.343178670905, 4879.121758083544, 4938.62960277977, 3507.473878918183, 3528.8235588220487, 3536.6816986471085, 3538.274653695986, 3549.361114999299, 5119.59043276537, 5119.718415487147, 5135.537801597837, 5157.381114266274, 5162.406323272834, 3571.6784789628005, 3610.494530765695, 3627.383344831385, 3643.9271214576092, 3664.5785734640326, 3659.579148397915, 3670.284294562588, 3673.246984368198, 3692.2430901665093, 3716.7026637868908, 4168.645665118499, 4183.581641835792, 4187.078842654611, 4200.957990420118, 4201.957980420218, 4906.705597664151, 4908.435529914034, 4920.606351491872, 4929.309896614477, 4929.605631549467, 1236.437420973455, 1256.5916077274921, 1262.61490245476, 1273.6624794429483, 1275.4388069249685, 784.2902173532962, 809.7327881798992, 816.1348970091603, 816.9665373302777, 818.6643476174739, 3867.45855580219, 3912.5109361329833, 3920.2355540669864, 3950.466686997276, 3960.6039396060164, 4463.085067561142, 4479.3057076153245, 4483.327500874895, 4524.6832721709825, 4528.88677783058, 3870.3807390817333, 3880.476135721698, 3916.74541154824, 3935.193285376491, 3938.428927805452, 2331.661909023216, 2334.1298464856977, 2378.881055947196, 2393.4495066134537, 2421.2615152378694, 4272.893177670583, 4560.201964656172, 4654.790534425959, 4774.832880849153, 4807.807687692523, 5218.530332270038, 5232.502912223333, 5233.764480598382, 5249.028929648028, 5255.47445255479, 4789.976050119727, 4807.399075115611, 4812.326274321597, 4812.8796780080775, 4818.301346304836, 4969.82605608802, 5039.974800125976, 5080.898382032265, 5111.900941297639, 5112.693238405707, 5000.57495112905, 5001.899582091934, 5031.6729412588065, 5038.949610503848, 5041.143005689115, 4830.516948305141, 4838.274258861199, 4849.830255941025, 4851.830185943475, 4866.95133048665, 3531.876384326536, 3618.9457158142122, 3652.9634703652623, 3655.0862284428895, 3658.5975542690817, 5103.285540024473, 5105.361829771278, 5110.923336149886, 5119.820806271762, 5124.308218390619, 4965.900681986268, 4984.900301994012, 4988.1270777613045, 4991.826920673711, 4992.650514463952, 4669.276262179367, 4969.3291405659775, 4969.676970996874, 4982.57996471004, 4983.800647974041, 4823.5658790709, 4851.0, 4865.878353041202, 4916.164252077084, 4924.138275801649, 4959.925601116059, 4989.12690279207, 4997.625178111583, 5011.937942677849, 5037.395512538372, 4766.571008609242, 4853.98559597763, 4856.271559266138, 4858.4898585648, 4877.829275975324], "value": 3677.50861298798, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.5834199376449, 661.6625520984296, 709.8899670551067, 712.7256006437522, 730.813642521157, 10933.726656833584, 12501.249925004495, 13060.673483162926, 13080.280584567845, 13840.0, 14113.92943035286, 14825.55523334299, 14832.406703731838, 14866.108033517969, 14919.32863021164, 747.7906186267841, 765.6401491299108, 770.3259647808168, 774.1175060431113, 774.5391492062242, 603.5805115444767, 734.9706011759523, 758.8216769059267, 760.7907825348018, 764.782037119421, 803.0122948773013, 804.9879251811227, 805.118395357083, 805.279275048831, 805.279275048831, 800.7877912353231, 801.3148757812074, 801.4870482890955, 801.7193982106245, 802.3701394405364, 585.1982783586471, 585.3122580967346, 585.5081731345648, 585.5666806563113, 585.7481283048287, 13326.533571325053, 13392.464301427932, 13415.0, 13428.664283392896, 13504.392302346418, 12871.549495767696, 12880.291583962879, 13009.739805203842, 13113.147645403018, 13828.792568111487, 9979.800403991952, 9990.400575965392, 10153.136983356424, 10351.792964140675, 10718.785624287548, 3162.130414136108, 3193.3613277344625, 3214.164317277501, 3273.5744353233927, 3279.2949515854207, 59467.40532594705, 60522.999999999556, 60850.69574652143, 61164.0, 61579.7684046321, 10046.447445390573, 10560.630377936888, 10682.679519614425, 10695.144388448905, 10703.518341674644, 19873.701894471593, 19980.90009549943, 20012.29956951529, 20018.69971950422, 20083.799162008192, 6838.418734407532, 7178.461615378763, 7283.417326613858, 7323.560586364835, 7449.255074492507, 3794.844645113596, 3828.8659896903473, 3850.3261929162286, 3883.572806991229, 3977.3835055565855, 2803.90186343477, 2916.1543152485965, 2917.722816332445, 2918.4454953558757, 2924.1227631710403, 485.2309090092243, 494.40918102866726, 498.18297991294304, 500.314569040417, 505.4263411028509, 510.59662866335924, 574.6954114319417, 581.0934941491287, 587.7795826565039, 594.6402426531972, 7847.215278472221, 8027.317677997319, 8032.357411407073, 8093.595320233965, 8241.011078670597, 566.8299510147021, 567.29088639201, 576.7347020370593, 579.5855962986485, 590.2680675961776, 3701.574318953301, 3720.6837418819164, 3819.904502387407, 3828.1769419574925, 4006.3790112532606, 102642.0, 105040.45052743133, 105249.47375263227, 105587.4720626392, 106452.0, 90828.0, 94195.0, 95368.0, 95783.0, 96892.0, 76458.0, 76725.6163719189, 76782.61608692033, 77248.61375693085, 77608.61195693986, 7146.64266786669, 7302.707891684486, 7356.190819010012, 7468.701251949861, 7554.848903021799, 46112.702387915175, 46641.13818374498, 48879.266810998575, 49223.507764921895, 49749.75125124351, 64807.35192648013, 71381.64309178421, 71642.0, 71892.28107718856, 71927.28072719416, 9695.9030409695, 10423.374597524173, 10522.368657880552, 10980.945095274472, 11330.886691132982, 12753.553625623059, 12823.358832058173, 13264.46942122343, 13694.589162325146, 14000.36998335057, 11863.110266729856, 11995.520179192734, 12333.0, 12859.742805144031, 12885.0, 11725.0, 11874.525018999144, 12774.552890648783, 12782.488700451877, 12783.105182637126, 10557.208209384173, 10889.401082940514, 10947.890521095005, 11885.0, 12165.45255463524, 8977.0, 9415.435073895589, 9563.0, 9571.090746379083, 9700.394022749406, 8777.341699372444, 8916.420432671855, 9386.718398448058, 9706.514674266116, 9715.417074975525], "value": 6606.679066783791, "unit": "score"}], "name": "dromaeo_css", "value": 6293.161953146442, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 135.0, 114.0, 113.0, 114.0, 113.0, 114.0, 113.0, 114.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 122.0, 147.0, 182.0, 128.0, 155.0, 156.0, 123.0, 203.0, 127.0], "value": 149.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 160.0, 162.0, 162.0, 163.0, 162.0, 161.0, 163.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 78.0, 181.0, 80.0, 75.0, 75.0, 76.0, 159.0, 76.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 137.0, 84.0, 84.0, 84.0, 146.0, 83.0, 84.0, 133.0], "value": 100.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 117.0, 118.0, 118.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 97.0, 96.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 73.0, 65.0, 67.0, 66.0, 67.0, 77.0, 64.0, 64.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 68.0, 66.0, 66.0, 66.0, 67.0, 67.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 83.0, 83.0, 83.0, 83.0, 83.0, 91.0, 82.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 133.0, 148.0, 159.0, 142.0, 133.0, 132.0, 132.0, 175.0, 146.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 172.0, 172.0, 172.0, 171.0, 179.0, 172.0, 170.0, 177.0, 171.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 70.0, 69.0, 71.0, 69.0, 69.0, 72.0, 69.0, 68.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1586.3, "alertThreshold": 2.0}]} 12:22:07 INFO - Return code: 0 12:22:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:22:07 INFO - # TBPL SUCCESS # 12:22:07 INFO - Running post-action listener: _resource_record_post_action 12:22:07 INFO - [mozharness: 2016-08-31 19:22:07.245198Z] Finished run-tests step (success) 12:22:07 INFO - Running post-run listener: _resource_record_post_run 12:22:07 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 8192; Write bytes: 418594816; Read time: 184; Write time: 313540 12:22:07 INFO - TinderboxPrint: CPU usage
13.1% 12:22:07 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 184 12:22:07 INFO - TinderboxPrint: I/O write bytes / time
418,594,816 / 313,540 12:22:07 INFO - TinderboxPrint: CPU idle
8,344.4 (86.9%) 12:22:07 INFO - TinderboxPrint: CPU user
1,203.6 (12.5%) 12:22:07 INFO - TinderboxPrint: Swap in / out
0 / 0 12:22:07 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 124993536; Read time: 0; Write time: 85432 12:22:07 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293601280; Read time: 168; Write time: 228108 12:22:07 INFO - Running post-run listener: _upload_blobber_files 12:22:07 INFO - Blob upload gear active. 12:22:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:22:07 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 12:22:07 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'] 12:22:07 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 12:22:08 INFO - (blobuploader) - INFO - Open directory for files ... 12:22:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:22:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:22:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:11 INFO - (blobuploader) - INFO - Done attempting. 12:22:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:22:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:22:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:13 INFO - (blobuploader) - INFO - Done attempting. 12:22:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:22:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:22:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:14 INFO - (blobuploader) - INFO - Done attempting. 12:22:14 INFO - (blobuploader) - INFO - Iteration through files over. 12:22:14 INFO - Return code: 0 12:22:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:22:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:22:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4c894ee1f88d9274a6e7ccb0b00357e6bbe7f676a4f50ce1c73bce958eeebb62162bda8dbcf791c7d59998cb3a458f3154245f7e61a416485c6ba9646fbeb720", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e00226009cad390edcd454d674b34fcc1023a9321225352e4f0b89e0d4d85f7bdaecafaeaf95d39d24945991332f9808c95d85ed59c5671891918a0415dcb441", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/86cba8834c8e745179e176bacb490c628163f09d525144cc2c0f374959e7d3e5532b732058e728c41395a1539dff26372d57f072756362154164013442196b87"} 12:22:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:22:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:22:14 INFO - Contents: 12:22:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4c894ee1f88d9274a6e7ccb0b00357e6bbe7f676a4f50ce1c73bce958eeebb62162bda8dbcf791c7d59998cb3a458f3154245f7e61a416485c6ba9646fbeb720", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e00226009cad390edcd454d674b34fcc1023a9321225352e4f0b89e0d4d85f7bdaecafaeaf95d39d24945991332f9808c95d85ed59c5671891918a0415dcb441", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/86cba8834c8e745179e176bacb490c628163f09d525144cc2c0f374959e7d3e5532b732058e728c41395a1539dff26372d57f072756362154164013442196b87"} 12:22:14 INFO - Running post-run listener: copy_logs_to_upload_dir 12:22:14 INFO - Copying logs to upload dir... 12:22:14 INFO - mkdir: /builds/slave/test/build/upload/logs 12:22:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.596996 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-31 12:22:12.872303) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 12:22:12.875523) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511 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/4c894ee1f88d9274a6e7ccb0b00357e6bbe7f676a4f50ce1c73bce958eeebb62162bda8dbcf791c7d59998cb3a458f3154245f7e61a416485c6ba9646fbeb720", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e00226009cad390edcd454d674b34fcc1023a9321225352e4f0b89e0d4d85f7bdaecafaeaf95d39d24945991332f9808c95d85ed59c5671891918a0415dcb441", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/86cba8834c8e745179e176bacb490c628163f09d525144cc2c0f374959e7d3e5532b732058e728c41395a1539dff26372d57f072756362154164013442196b87"} build_url:https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011328 build_url: 'https://queue.taskcluster.net/v1/task/TyP4ZqsKQzmTY6YunBag6w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4c894ee1f88d9274a6e7ccb0b00357e6bbe7f676a4f50ce1c73bce958eeebb62162bda8dbcf791c7d59998cb3a458f3154245f7e61a416485c6ba9646fbeb720", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e00226009cad390edcd454d674b34fcc1023a9321225352e4f0b89e0d4d85f7bdaecafaeaf95d39d24945991332f9808c95d85ed59c5671891918a0415dcb441", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/86cba8834c8e745179e176bacb490c628163f09d525144cc2c0f374959e7d3e5532b732058e728c41395a1539dff26372d57f072756362154164013442196b87"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 12:22:12.901929) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 12:22:12.902318) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1472668468.32306-418718511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004424 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 12:22:12.985290) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 12:22:12.985659) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 12:22:12.985987) ========= ========= Total master_lag: 0.15 =========